-- Logs begin at Fri 2025-10-24 20:46:05 MSK, end at Fri 2025-10-24 21:52:36 MSK. -- Oct 24 21:51:23 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 24 21:51:24 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:51:24 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:51:24 volumio volumio[1272]: info: Discovery: Getting this device information Oct 24 21:51:24 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:51:24 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:24 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:51:34 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:51:34 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:34 volumio sudo[9078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:51:34 volumio sudo[9078]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:34 volumio sudo[9081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:51:34 volumio sudo[9081]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:34 volumio sudo[9078]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:34 volumio sudo[9081]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:34 volumio volumio[1272]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 10; VOG-L29 Build/HUAWEIVOG-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:51:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 21:51:35 volumio sudo[9130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 21:51:35 volumio sudo[9130]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:35 volumio sudo[9130]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:35 volumio sudo[9133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 21:51:35 volumio sudo[9133]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:35 volumio sudo[9133]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:35 volumio volumio[1272]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.6 UA: Mozilla/5.0 (Linux; Android 10; VOG-L29 Build/HUAWEIVOG-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/141.0.7390.97 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:51:35 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 21:51:35 volumio volumio[1272]: info: Listing playlists Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 24 21:51:35 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::volumioPause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::servicePause Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::servicePause Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::pause Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand pause Oct 24 21:51:37 volumio volumio[1272]: info: Oct 24 21:51:37 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand pause took 47 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:37 volumio volumio[1272]: info: Oct 24 21:51:37 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:37 volumio volumio[1272]: info: Oct 24 21:51:37 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:37 volumio volumio[1272]: info: Oct 24 21:51:37 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:37 volumio volumio[1272]: info: Oct 24 21:51:37 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand status took 6 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand status took 6 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand status took 5 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand status took 5 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand status took 5 milliseconds Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 21:51:37 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:37 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4930,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:37 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState stateService pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4930,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:37 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState stateService pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4930,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:37 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState stateService pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4930,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:37 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState stateService pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:37 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4930,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:37 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState stateService pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus pause Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:37 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:37 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:37 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:37 volumio volumio[1272]: info: ------------------------------ 42ms Oct 24 21:51:37 volumio volumio[1272]: info: ------------------------------ 41ms Oct 24 21:51:37 volumio volumio[1272]: info: ------------------------------ 40ms Oct 24 21:51:37 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status pause Oct 24 21:51:37 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status pause Oct 24 21:51:37 volumio sudo[9142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:37 volumio sudo[9142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:37 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status pause Oct 24 21:51:37 volumio sudo[9145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:37 volumio sudo[9145]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:37 volumio sudo[9142]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:37 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status pause Oct 24 21:51:37 volumio sudo[9145]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:37 volumio sudo[9148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:37 volumio sudo[9148]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:37 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status pause Oct 24 21:51:37 volumio sudo[9148]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:37 volumio sudo[9151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:37 volumio sudo[9151]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:37 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:37 volumio sudo[9151]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:37 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:37 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:37 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:37 volumio sudo[9154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:37 volumio sudo[9154]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:37 volumio sudo[9154]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:37 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:38 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Oct 24 21:51:38 volumio volumio[1272]: info: CoreStateMachine::play index undefined Oct 24 21:51:38 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:51:38 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:38 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Oct 24 21:51:38 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:38 volumio volumio[1272]: info: ControllerMpd::resume Oct 24 21:51:38 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Oct 24 21:51:39 volumio volumio[1272]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 24 21:51:39 volumio volumio[1272]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 24 21:51:39 volumio volumio[1272]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 24 21:51:39 volumio volumio[1272]: error: FusionDsp - WebSocket error: [object Object] Oct 24 21:51:40 volumio volumio[1272]: info: Oct 24 21:51:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand play took 1285 milliseconds Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:40 volumio volumio[1272]: info: Oct 24 21:51:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:40 volumio volumio[1272]: info: Oct 24 21:51:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:40 volumio volumio[1272]: info: Oct 24 21:51:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:40 volumio volumio[1272]: info: Oct 24 21:51:40 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand status took 35 milliseconds Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand status took 22 milliseconds Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand status took 21 milliseconds Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand status took 19 milliseconds Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand status took 3 milliseconds Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 21:51:40 volumio volumio[1272]: info: Received Get System Info Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:51:40 volumio volumio[1272]: info: Discovery: Getting this device information Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:51:40 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 21:51:40 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5227,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:40 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus pause Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5227,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:40 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5227,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:40 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5227,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:40 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5227,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:40 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:40 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:40 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:40 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:40 volumio volumio[1272]: info: ------------------------------ 120ms Oct 24 21:51:40 volumio volumio[1272]: info: ------------------------------ 106ms Oct 24 21:51:40 volumio volumio[1272]: info: ------------------------------ 104ms Oct 24 21:51:40 volumio volumio[1272]: info: ------------------------------ 103ms Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:40 volumio sudo[9161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9161]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9167]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9171]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9175]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9163]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:40 volumio sudo[9177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio sudo[9165]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:40 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:51:40 volumio sudo[9167]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio sudo[9161]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio sudo[9173]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio sudo[9163]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio sudo[9171]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio sudo[9169]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio sudo[9177]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio sudo[9165]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio sudo[9175]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:40 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:41 volumio volumio[9189]: Traceback (most recent call last): Oct 24 21:51:41 volumio volumio[9189]: File "spectrum.py", line 18, in Oct 24 21:51:41 volumio volumio[9189]: import pygame Oct 24 21:51:41 volumio volumio[9189]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:51:41 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:51:41 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:51:45 volumio volumio[1272]: info: CoreCommandRouter::volumioGetQueue Oct 24 21:51:45 volumio volumio[1272]: info: CoreStateMachine::getQueue Oct 24 21:51:45 volumio volumio[1272]: info: CorePlayQueue::getQueue Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::play index 1 Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::stop Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::serviceStop Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::serviceStop Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::stop Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 21:51:49 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand stop took 29 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::play index undefined Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::clearAddPlayTracks USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand status took 4 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand stop took 3 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand status took 2 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand status took 1 milliseconds Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand clear took 6 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand add "USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf" Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:49 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:49 volumio sudo[9213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:49 volumio sudo[9213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:49 volumio volumio[1272]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:51:49 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::syncState stateService stop Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:49 volumio volumio[1272]: info: No code Oct 24 21:51:49 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:49 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:49 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:49 volumio volumio[1272]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 21:51:49 volumio volumio[1272]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 21:51:49 volumio volumio[1272]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 21:51:49 volumio volumio[1272]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 21:51:49 volumio sudo[9213]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 45ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 43ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 42ms Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: error: updateQueue error: null Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 38ms Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand add "USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf" took 37 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 3ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 2ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 1ms Oct 24 21:51:49 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: Oct 24 21:51:49 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:51:49 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:51:49 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:51:49 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:51:49 volumio sudo[9218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:49 volumio sudo[9218]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:49 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:51:49 volumio sudo[9218]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:49 volumio sudo[9221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:49 volumio sudo[9221]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:49 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 81ms Oct 24 21:51:49 volumio volumio[1272]: info: sendMpdCommand play took 80 milliseconds Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 80ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 79ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 79ms Oct 24 21:51:49 volumio volumio[1272]: info: ------------------------------ 79ms Oct 24 21:51:49 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:49 volumio sudo[9221]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:49 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:49 volumio sudo[9224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:51:49 volumio sudo[9224]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:49 volumio sudo[9224]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:49 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 24 21:51:50 volumio volumio[1272]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 24 21:51:50 volumio volumio[1272]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 24 21:51:50 volumio volumio[1272]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 24 21:51:50 volumio volumio[1272]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 24 21:51:50 volumio volumio[1272]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 75 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 74 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 66 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 66 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 65 milliseconds Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: Oct 24 21:51:50 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 12 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 11 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 10 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 10 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand status took 2 milliseconds Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 117ms Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 127ms Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 127ms Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 120ms Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 119ms Oct 24 21:51:50 volumio volumio[1272]: error: FusionDsp - WebSocket error: [object Object] Oct 24 21:51:50 volumio volumio[1272]: error: FusionDsp - WebSocket error: [object Object] Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 69ms Oct 24 21:51:50 volumio volumio[1272]: info: [LastFM] Current track has sufficient metadata: title (L'Horloge) and artist (Mylene Farmer) passed on explicitly Oct 24 21:51:50 volumio volumio[1272]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9228]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9230]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9232]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9237]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:51:50 volumio sudo[9228]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9230]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio sudo[9237]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio sudo[9232]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9242]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9244]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio volumio[9238]: Traceback (most recent call last): Oct 24 21:51:50 volumio volumio[9238]: File "spectrum.py", line 18, in Oct 24 21:51:50 volumio volumio[9238]: import pygame Oct 24 21:51:50 volumio volumio[9238]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:51:50 volumio sudo[9242]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:51:50 volumio sudo[9244]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:51:50 volumio sudo[9248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9248]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:51:50 volumio sudo[9251]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9248]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio sudo[9251]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9257]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9257]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[9252]: Traceback (most recent call last): Oct 24 21:51:50 volumio volumio[9252]: File "spectrum.py", line 18, in Oct 24 21:51:50 volumio volumio[9252]: import pygame Oct 24 21:51:50 volumio volumio[9252]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:51:50 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:51:50 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:51:50 volumio sudo[9260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9260]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 509 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 509 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 509 milliseconds Oct 24 21:51:50 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 508 milliseconds Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":276,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:51:50 volumio volumio[1272]: verbose: CURRENT POSITION 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:51:50 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:51:50 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:50 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 548ms Oct 24 21:51:50 volumio volumio[1272]: info: ------------------------------ 541ms Oct 24 21:51:50 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:50 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:50 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:51:50 volumio sudo[9260]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9265]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9265]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio sudo[9268]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio volumio[9262]: Traceback (most recent call last): Oct 24 21:51:50 volumio volumio[9262]: File "spectrum.py", line 18, in Oct 24 21:51:50 volumio volumio[9262]: import pygame Oct 24 21:51:50 volumio volumio[9262]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:51:50 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:51:50 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:51:50 volumio sudo[9271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9271]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio sudo[9274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9274]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:51:50 volumio sudo[9274]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio sudo[9278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:50 volumio sudo[9271]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio sudo[9278]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:50 volumio sudo[9278]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:50 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:51:51 volumio volumio[9275]: Traceback (most recent call last): Oct 24 21:51:51 volumio volumio[9275]: File "spectrum.py", line 18, in Oct 24 21:51:51 volumio volumio[9275]: import pygame Oct 24 21:51:51 volumio volumio[9275]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:51:51 volumio sudo[9284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:51 volumio sudo[9284]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:51 volumio sudo[9282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:51 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:51:51 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:51:51 volumio sudo[9282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio sudo[9288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:51:51 volumio sudo[9288]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:51:51 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:51:51 volumio sudo[9284]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio sudo[9282]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:51 volumio sudo[9288]: pam_unix(sudo:session): session closed for user root Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:51:51 volumio volumio[9289]: Traceback (most recent call last): Oct 24 21:51:51 volumio volumio[9289]: File "spectrum.py", line 18, in Oct 24 21:51:51 volumio volumio[9289]: import pygame Oct 24 21:51:51 volumio volumio[9289]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:51:51 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:51:51 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:51:54 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:51:54 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:51:54 volumio volumio[1272]: info: Discovery: Getting this device information Oct 24 21:51:54 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:51:54 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:51:54 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPlay Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::play index 2 Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::stop Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::stPlaybackTimer Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::updateTrackBlock Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrackBlock Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::serviceStop Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 1 Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::serviceStop Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::stop Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 21:52:09 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand stop took 33 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::play index undefined Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::clearAddPlayTracks USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand status took 6 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand stop took 5 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand status took 4 milliseconds Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:09 volumio sudo[9335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:52:09 volumio sudo[9335]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand clear took 5 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand add "USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf" Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:09 volumio volumio[1272]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"L'Horloge","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/02 - L'Horloge.dsf","trackType":"dsf"} Oct 24 21:52:09 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::syncState stateService stop Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:09 volumio volumio[1272]: info: No code Oct 24 21:52:09 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:09 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:09 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:09 volumio volumio[1272]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 35ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 30ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 29ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 28ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 28ms Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: error: updateQueue error: null Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 22ms Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand add "USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf" took 26 milliseconds Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand play Oct 24 21:52:09 volumio sudo[9335]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:09 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:52:09 volumio sudo[9342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:52:09 volumio sudo[9342]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:09 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:52:09 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status stop Oct 24 21:52:09 volumio sudo[9345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:52:09 volumio sudo[9345]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:09 volumio sudo[9342]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:09 volumio sudo[9345]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 200ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 199ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 199ms Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 198ms Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand play took 192 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces system playlist update Oct 24 21:52:09 volumio volumio[1272]: info: Ignoring MPD Status Update Oct 24 21:52:09 volumio volumio[1272]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 24 21:52:09 volumio volumio[1272]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 24 21:52:09 volumio volumio[1272]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 24 21:52:09 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:52:09 volumio volumio[1272]: info: Oct 24 21:52:09 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:09 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:09 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:09 volumio volumio[1272]: info: sendMpdCommand status took 7 milliseconds Oct 24 21:52:09 volumio volumio[1272]: info: ------------------------------ 6ms Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 6ms Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:10 volumio volumio[1272]: info: Oct 24 21:52:10 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:10 volumio volumio[1272]: info: Oct 24 21:52:10 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:10 volumio volumio[1272]: info: Oct 24 21:52:10 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:10 volumio volumio[1272]: info: Oct 24 21:52:10 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand status took 5 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand status took 8 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand status took 7 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand status took 7 milliseconds Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:10 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus stop Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 19ms Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand status took 9 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:10 volumio sudo[9348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:10 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:10 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio sudo[9348]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:10 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:10 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:10 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:10 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:10 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:10 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio sudo[9348]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 49ms Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 48ms Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 47ms Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 48ms Oct 24 21:52:10 volumio volumio[1272]: error: FusionDsp - WebSocket error: [object Object] Oct 24 21:52:10 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 24 21:52:10 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:10 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:10 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:10 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:10 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:10 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:10 volumio volumio[1272]: info: ------------------------------ 58ms Oct 24 21:52:10 volumio volumio[1272]: info: [LastFM] Current track has sufficient metadata: title (Plus Grandir) and artist (Mylene Farmer) passed on explicitly Oct 24 21:52:10 volumio volumio[1272]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9351]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9355]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9351]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9355]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9359]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9359]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9362]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9365]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio sudo[9362]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9365]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9368]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio volumio[9354]: Traceback (most recent call last): Oct 24 21:52:10 volumio volumio[9354]: File "spectrum.py", line 18, in Oct 24 21:52:10 volumio volumio[9354]: import pygame Oct 24 21:52:10 volumio volumio[9354]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:52:10 volumio sudo[9371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9371]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio sudo[9368]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:52:10 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:10 volumio sudo[9374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9374]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:52:10 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:10 volumio sudo[9378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9378]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio sudo[9371]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9374]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9378]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio sudo[9381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:10 volumio sudo[9381]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Stop Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio sudo[9381]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:10 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:10 volumio volumio[9375]: Traceback (most recent call last): Oct 24 21:52:10 volumio volumio[9375]: File "spectrum.py", line 18, in Oct 24 21:52:10 volumio volumio[9375]: import pygame Oct 24 21:52:10 volumio volumio[9375]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:52:10 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:52:10 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioSeek Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::seek Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: TRACKBLOCK {"uri":"mnt/USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","service":"mpd","name":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=135&web=Mylene%20Farmer/En%20Concert/extralarge&path=%2FUSB%2FwdB%2FMylene%20Farmer%2F198%20-%20En%20Concert&metadata=false","duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","trackType":"dsf","channels":2} Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::startPlaybackTimer Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::seek Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio volumio[1272]: error: null Oct 24 21:52:15 volumio volumio[1272]: info: Oct 24 21:52:15 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:15 volumio volumio[1272]: info: Oct 24 21:52:15 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:15 volumio volumio[1272]: info: Oct 24 21:52:15 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:15 volumio volumio[1272]: info: Oct 24 21:52:15 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:15 volumio volumio[1272]: info: Oct 24 21:52:15 volumio volumio[1272]: ---------------------------- MPD announces state update: player Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::getState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand status Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand status took 6 milliseconds Oct 24 21:52:15 volumio sudo[9398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9398]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand status took 5 milliseconds Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand status took 4 milliseconds Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand status took 3 milliseconds Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand status took 3 milliseconds Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseState Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91000,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:15 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:15 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91000,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:15 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:15 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91000,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:15 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:15 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:52:15 volumio sudo[9398]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[1272]: info: ------------------------------ 48ms Oct 24 21:52:15 volumio volumio[1272]: info: ------------------------------ 46ms Oct 24 21:52:15 volumio volumio[1272]: info: ------------------------------ 46ms Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 24 21:52:15 volumio volumio[1272]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:15 volumio volumio[1272]: verbose: ControllerMpd::parseTrackInfo Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91000,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:15 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:15 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: ControllerMpd::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::servicePushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91000,"duration":287,"samplerate":"11.28 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"22579 Kbps","isStreaming":false,"title":"Plus Grandir","artist":"Mylene Farmer","album":"En Concert","uri":"USB/wdB/Mylene Farmer/198 - En Concert/03 - Plus Grandir.dsf","trackType":"dsf"} Oct 24 21:52:15 volumio volumio[1272]: verbose: CURRENT POSITION 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState stateService play Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::syncState currentStatus play Oct 24 21:52:15 volumio volumio[1272]: info: Received an update from plugin. extracting info from payload Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: CoreStateMachine::pushState Oct 24 21:52:15 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:15 volumio volumio[1272]: info: CoreCommandRouter::volumioPushState Oct 24 21:52:15 volumio volumio[1272]: info: ------------------------------ 72ms Oct 24 21:52:15 volumio volumio[1272]: info: ------------------------------ 72ms Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio volumio[9400]: Traceback (most recent call last): Oct 24 21:52:15 volumio volumio[9400]: File "spectrum.py", line 18, in Oct 24 21:52:15 volumio volumio[9400]: import pygame Oct 24 21:52:15 volumio volumio[9400]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio sudo[9405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9405]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio sudo[9408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9408]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:52:15 volumio sudo[9408]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio sudo[9405]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio sudo[9413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9413]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio sudo[9413]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[9409]: Traceback (most recent call last): Oct 24 21:52:15 volumio volumio[9409]: File "spectrum.py", line 18, in Oct 24 21:52:15 volumio volumio[9409]: import pygame Oct 24 21:52:15 volumio volumio[9409]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio sudo[9416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio sudo[9419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio systemd[1]: Started peppyspectrum Daemon. Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio sudo[9416]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio sudo[9419]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio sudo[9423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio sudo[9423]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[9420]: Traceback (most recent call last): Oct 24 21:52:15 volumio volumio[9420]: File "spectrum.py", line 18, in Oct 24 21:52:15 volumio volumio[9420]: import pygame Oct 24 21:52:15 volumio volumio[9420]: ModuleNotFoundError: No module named 'pygame' Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio sudo[9427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9427]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio systemd[1]: Failed to start peppyspectrum Daemon. Oct 24 21:52:15 volumio sudo[9427]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio sudo[9430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9430]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio systemd[1]: Failed to start peppyspectrum Daemon. Oct 24 21:52:15 volumio sudo[9430]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum status play Oct 24 21:52:15 volumio sudo[9433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9433]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio systemd[1]: Failed to start peppyspectrum Daemon. Oct 24 21:52:15 volumio sudo[9433]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:15 volumio sudo[9436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio sudo[9436]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio volumio[1272]: Job for peppyspectrum.service failed because the control process exited with error code. Oct 24 21:52:15 volumio volumio[1272]: See "systemctl status peppyspectrum.service" and "journalctl -xe" for details. Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio volumio[1272]: Job for peppyspectrum.service failed because the control process exited with error code. Oct 24 21:52:15 volumio volumio[1272]: See "systemctl status peppyspectrum.service" and "journalctl -xe" for details. Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio volumio[1272]: Job for peppyspectrum.service failed because the control process exited with error code. Oct 24 21:52:15 volumio volumio[1272]: See "systemctl status peppyspectrum.service" and "journalctl -xe" for details. Oct 24 21:52:15 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:15 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:15 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:15 volumio volumio[1272]: info: peppyspectrum Daemon Started Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Start request repeated too quickly. Oct 24 21:52:15 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 24 21:52:15 volumio systemd[1]: Failed to start peppyspectrum Daemon. Oct 24 21:52:15 volumio sudo[9436]: pam_unix(sudo:session): session closed for user root Oct 24 21:52:15 volumio volumio[1272]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Oct 24 21:52:15 volumio volumio[1272]: Job for peppyspectrum.service failed because the control process exited with error code. Oct 24 21:52:15 volumio volumio[1272]: See "systemctl status peppyspectrum.service" and "journalctl -xe" for details. Oct 24 21:52:20 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 21:52:20 volumio volumio[1272]: info: Received Get System Info Oct 24 21:52:20 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:52:20 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:52:20 volumio volumio[1272]: info: Discovery: Getting this device information Oct 24 21:52:20 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:52:20 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:20 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:52:23 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 21:52:23 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 24 21:52:24 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 21:52:24 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 21:52:24 volumio volumio[1272]: info: Discovery: Getting this device information Oct 24 21:52:24 volumio volumio[1272]: info: CoreCommandRouter::volumioGetState Oct 24 21:52:24 volumio volumio[1272]: info: CorePlayQueue::getTrack 2 Oct 24 21:52:24 volumio volumio[1272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 21:52:35 volumio volumio[1272]: info: Starting Uninstall of plugin audio_interface - fusiondsp Oct 24 21:52:35 volumio volumio[1272]: info: Uninstalling plugin fusiondsp Oct 24 21:52:35 volumio volumio[1272]: info: FusionDsp - Stopping FusionDsp service Oct 24 21:52:35 volumio volumio[1272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 21:52:35 volumio volumio[1272]: TypeError: Cannot read property 'stop' of null Oct 24 21:52:35 volumio volumio[1272]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Oct 24 21:52:35 volumio volumio[1272]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 24 21:52:35 volumio volumio[1272]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Oct 24 21:52:35 volumio volumio[1272]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1465:22) Oct 24 21:52:35 volumio volumio[1272]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1246:45) Oct 24 21:52:35 volumio volumio[1272]: at Socket.emit (events.js:400:28) Oct 24 21:52:35 volumio volumio[1272]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 24 21:52:35 volumio volumio[1272]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 24 21:52:35 volumio volumio[1272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 21:52:36 volumio sudo[9530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 21:51 Oct 24 21:52:36 volumio sudo[9530]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"