-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Sat 2025-10-25 20:12:13 IST. -- Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioSeek Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::seek Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxMw","realUri":"upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913","type":"song","albumart":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxMw.jpg?albumArt=true","artist":"Ghibran","album":"Maara","name":"Maara & Paaru","title":"Maara & Paaru","duration":290} Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: [1761403263275] ControllerUPNPBrowser::seek Oct 25 20:11:03 volumioamber volumio[1136]: info: ControllerMpd::seek Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: error: null Oct 25 20:11:03 volumioamber volumio[1136]: info: Oct 25 20:11:03 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:03 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:03 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:03 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::getQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: sendMpdCommand status took 60 milliseconds Oct 25 20:11:03 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:03 volumioamber sudo[3525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:03 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:03 volumioamber sudo[3525]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:03 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:11:03 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:03 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":79457,"duration":291,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxMw","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxMw","trackType":""} Oct 25 20:11:03 volumioamber volumio[1136]: verbose: CURRENT POSITION 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:03 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:03 volumioamber volumio[1136]: info: ------------------------------ 112ms Oct 25 20:11:03 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:03 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:03 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:03 volumioamber sudo[3525]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:03 volumioamber sudo[3529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::getQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getQueue Oct 25 20:11:03 volumioamber sudo[3529]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: CoreStateMachine::getQueue Oct 25 20:11:03 volumioamber volumio[1136]: info: CorePlayQueue::getQueue Oct 25 20:11:03 volumioamber sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:03 volumioamber sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:03 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:03 volumioamber sudo[3529]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:03 volumioamber sudo[3532]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:03 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:03 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:04 volumioamber volumio[3527]: pygame 1.9.4.post1 Oct 25 20:11:04 volumioamber volumio[3527]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:04 volumioamber volumio[3527]: Traceback (most recent call last): Oct 25 20:11:04 volumioamber volumio[3527]: File "spectrum.py", line 715, in Oct 25 20:11:04 volumioamber volumio[3527]: pm = Spectrum(None, True) Oct 25 20:11:04 volumioamber volumio[3527]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:04 volumioamber volumio[3527]: self.init_display() Oct 25 20:11:04 volumioamber volumio[3527]: File "spectrum.py", line 104, in init_display Oct 25 20:11:04 volumioamber volumio[3527]: pygame.display.init() Oct 25 20:11:04 volumioamber volumio[3527]: pygame.error: Unable to open a console terminal Oct 25 20:11:04 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:04 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:11:06 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::ClearQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::serviceStop Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 1 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::serviceStop Oct 25 20:11:06 volumioamber volumio[1136]: info: [1761403266883] ControllerUPNPBrowser::stop Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::clearPlayQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::addQueueItems Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::addQueueItems Oct 25 20:11:06 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31912 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31912 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31914 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31914 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPlay Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::play index 2 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::addQueueItems Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::addQueueItems Oct 25 20:11:06 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31916 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31916 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31917 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31917 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31918 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31918 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31920 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31920 Oct 25 20:11:06 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31921 Oct 25 20:11:06 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31921 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::play index undefined Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 2 Oct 25 20:11:06 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:06 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 2 Oct 25 20:11:06 volumioamber volumio[1136]: info: [1761403266935] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:06 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:06 volumioamber volumio[1136]: info: Oct 25 20:11:06 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:06 volumioamber volumio[1136]: info: sendMpdCommand stop took 97 milliseconds Oct 25 20:11:06 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:06 volumioamber volumio[1136]: info: sendMpdCommand stop took 45 milliseconds Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:11:06 volumioamber sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:06 volumioamber volumio[1136]: info: Oct 25 20:11:06 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:06 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:06 volumioamber volumio[1136]: info: sendMpdCommand status took 5 milliseconds Oct 25 20:11:06 volumioamber sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:06 volumioamber volumio[1136]: info: sendMpdCommand clear took 5 milliseconds Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNA" Oct 25 20:11:06 volumioamber volumio[1136]: error: updateQueue error: null Oct 25 20:11:06 volumioamber volumio[1136]: info: ------------------------------ 9ms Oct 25 20:11:06 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 20:11:06 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:06 volumioamber volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 20:11:06 volumioamber volumio[1136]: info: ------------------------------ 18ms Oct 25 20:11:07 volumioamber sudo[3537]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:07 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:07 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNA" Oct 25 20:11:07 volumioamber volumio[1136]: info: Oct 25 20:11:07 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:07 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:07 volumioamber volumio[1136]: info: sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNA" took 1 milliseconds Oct 25 20:11:07 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 20:11:07 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:11:07 volumioamber volumio[1136]: info: ------------------------------ 4ms Oct 25 20:11:07 volumioamber volumio[1136]: info: sendMpdCommand play took 2 milliseconds Oct 25 20:11:07 volumioamber volumio[1136]: info: Oct 25 20:11:07 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:07 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:07 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:07 volumioamber volumio[1136]: info: sendMpdCommand status took 1 milliseconds Oct 25 20:11:07 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:07 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:07 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:11:07 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:07 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:07 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:07 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 2 Oct 25 20:11:07 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":277,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxNA","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNA","trackType":""} Oct 25 20:11:07 volumioamber volumio[1136]: verbose: CURRENT POSITION 2 Oct 25 20:11:07 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:07 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:07 volumioamber volumio[1136]: info: ------------------------------ 8ms Oct 25 20:11:10 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::ClearQueue Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 2 Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 2 Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::serviceStop Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 2 Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::serviceStop Oct 25 20:11:10 volumioamber volumio[1136]: info: [1761403270928] ControllerUPNPBrowser::stop Oct 25 20:11:10 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::clearPlayQueue Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::addQueueItems Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::addQueueItems Oct 25 20:11:10 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31912 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31912 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31914 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31914 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915 Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPlay Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::play index 3 Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::addQueueItems Oct 25 20:11:10 volumioamber volumio[1136]: info: CorePlayQueue::addQueueItems Oct 25 20:11:10 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31916 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31916 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31917 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31917 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31918 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31918 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31920 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31920 Oct 25 20:11:10 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31921 Oct 25 20:11:10 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31921 Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:10 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:11 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::play index undefined Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:11 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:11 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:11 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:11 volumioamber volumio[1136]: info: [1761403271012] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:11 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:11 volumioamber volumio[1136]: info: Oct 25 20:11:11 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand stop took 125 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand stop took 42 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:11:11 volumioamber volumio[1136]: info: Oct 25 20:11:11 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:11 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand status took 4 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand clear took 3 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ" Oct 25 20:11:11 volumioamber sudo[3558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:11 volumioamber sudo[3558]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:11 volumioamber volumio[1136]: error: updateQueue error: null Oct 25 20:11:11 volumioamber volumio[1136]: info: ------------------------------ 7ms Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:11 volumioamber volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 20:11:11 volumioamber volumio[1136]: info: ------------------------------ 14ms Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ" Oct 25 20:11:11 volumioamber volumio[1136]: info: Oct 25 20:11:11 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:11 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ" took 2 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:11:11 volumioamber volumio[1136]: info: ------------------------------ 4ms Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand play took 3 milliseconds Oct 25 20:11:11 volumioamber sudo[3558]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:11 volumioamber volumio[1136]: info: Oct 25 20:11:11 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:11 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:11 volumioamber volumio[1136]: info: Oct 25 20:11:11 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:11 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand status took 4 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:11 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand status took 5 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:11 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:11 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxNQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ","trackType":""} Oct 25 20:11:11 volumioamber volumio[1136]: verbose: CURRENT POSITION 3 Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:11 volumioamber volumio[1136]: info: ------------------------------ 12ms Oct 25 20:11:11 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 25 20:11:11 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:11 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:11 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxNQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ","trackType":""} Oct 25 20:11:11 volumioamber volumio[1136]: verbose: CURRENT POSITION 3 Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:11 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:11 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:11 volumioamber volumio[1136]: info: ------------------------------ 108ms Oct 25 20:11:11 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:11 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:11 volumioamber sudo[3564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:11 volumioamber sudo[3564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:11 volumioamber sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:11 volumioamber sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:11 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:11 volumioamber sudo[3564]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:11 volumioamber sudo[3566]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:11 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:11 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:12 volumioamber volumio[3569]: pygame 1.9.4.post1 Oct 25 20:11:12 volumioamber volumio[3569]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:12 volumioamber volumio[3569]: Traceback (most recent call last): Oct 25 20:11:12 volumioamber volumio[3569]: File "spectrum.py", line 715, in Oct 25 20:11:12 volumioamber volumio[3569]: pm = Spectrum(None, True) Oct 25 20:11:12 volumioamber volumio[3569]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:12 volumioamber volumio[3569]: self.init_display() Oct 25 20:11:12 volumioamber volumio[3569]: File "spectrum.py", line 104, in init_display Oct 25 20:11:12 volumioamber volumio[3569]: pygame.display.init() Oct 25 20:11:12 volumioamber volumio[3569]: pygame.error: Unable to open a console terminal Oct 25 20:11:12 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:12 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:11:15 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:15 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:15 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:15 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:15 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioSeek Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::seek Oct 25 20:11:19 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:19 volumioamber volumio[1136]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ","realUri":"upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915","type":"song","albumart":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ.jpg?albumArt=true","artist":"Ghibran, Benny Dayal","album":"Maara","name":"Oh Azhage","title":"Oh Azhage","duration":281} Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:19 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:19 volumioamber volumio[1136]: info: [1761403279036] ControllerUPNPBrowser::seek Oct 25 20:11:19 volumioamber volumio[1136]: info: ControllerMpd::seek Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:19 volumioamber volumio[1136]: error: null Oct 25 20:11:19 volumioamber volumio[1136]: info: Oct 25 20:11:19 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:19 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:19 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:19 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:19 volumioamber volumio[1136]: info: sendMpdCommand status took 45 milliseconds Oct 25 20:11:19 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:19 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:19 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 20:11:19 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:19 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:19 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80823,"duration":282,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxNQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxNQ","trackType":""} Oct 25 20:11:19 volumioamber volumio[1136]: verbose: CURRENT POSITION 3 Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:19 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:19 volumioamber sudo[3616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:19 volumioamber sudo[3616]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:19 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:19 volumioamber volumio[1136]: info: ------------------------------ 92ms Oct 25 20:11:19 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:19 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:19 volumioamber sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:19 volumioamber sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:19 volumioamber sudo[3623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:19 volumioamber sudo[3623]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:19 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:19 volumioamber sudo[3616]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:19 volumioamber sudo[3623]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:19 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:19 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:19 volumioamber sudo[3620]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:19 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:20 volumioamber volumio[3618]: pygame 1.9.4.post1 Oct 25 20:11:20 volumioamber volumio[3618]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:20 volumioamber volumio[3618]: Traceback (most recent call last): Oct 25 20:11:20 volumioamber volumio[3618]: File "spectrum.py", line 715, in Oct 25 20:11:20 volumioamber volumio[3618]: pm = Spectrum(None, True) Oct 25 20:11:20 volumioamber volumio[3618]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:20 volumioamber volumio[3618]: self.init_display() Oct 25 20:11:20 volumioamber volumio[3618]: File "spectrum.py", line 104, in init_display Oct 25 20:11:20 volumioamber volumio[3618]: pygame.display.init() Oct 25 20:11:20 volumioamber volumio[3618]: pygame.error: Unable to open a console terminal Oct 25 20:11:20 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:20 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:11:29 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::ClearQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::serviceStop Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 3 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::serviceStop Oct 25 20:11:29 volumioamber volumio[1136]: info: [1761403289049] ControllerUPNPBrowser::stop Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::clearPlayQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::addQueueItems Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::addQueueItems Oct 25 20:11:29 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31912 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31912 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31913 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31914 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31914 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31915 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31916 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31916 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31917 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31917 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31918 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31918 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPlay Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::play index 7 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::addQueueItems Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::addQueueItems Oct 25 20:11:29 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31920 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31920 Oct 25 20:11:29 volumioamber volumio[1136]: info: Adding Item to queue: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31921 Oct 25 20:11:29 volumioamber volumio[1136]: info: Using cached record of: upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31921 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::saveQueue Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::play index undefined Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:29 volumioamber volumio[1136]: info: [1761403289110] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:29 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:29 volumioamber volumio[1136]: info: Oct 25 20:11:29 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand stop took 108 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand stop took 49 milliseconds Oct 25 20:11:29 volumioamber sudo[3641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:11:29 volumioamber sudo[3641]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:29 volumioamber volumio[1136]: info: Oct 25 20:11:29 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:29 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand status took 7 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand clear took 6 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ" Oct 25 20:11:29 volumioamber volumio[1136]: error: updateQueue error: null Oct 25 20:11:29 volumioamber volumio[1136]: info: ------------------------------ 5ms Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:29 volumioamber volumio[1136]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 25 20:11:29 volumioamber volumio[1136]: info: ------------------------------ 15ms Oct 25 20:11:29 volumioamber sudo[3641]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ" Oct 25 20:11:29 volumioamber volumio[1136]: info: Oct 25 20:11:29 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:29 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ" took 2 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:11:29 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:29 volumioamber volumio[1136]: info: ------------------------------ 4ms Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand play took 3 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: info: Oct 25 20:11:29 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:29 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand status took 2 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:29 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:11:29 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:29 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:29 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:29 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:29 volumioamber volumio[1136]: verbose: CURRENT POSITION 7 Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:29 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:29 volumioamber volumio[1136]: info: ------------------------------ 12ms Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreCommandRouter::volumioSeek Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreStateMachine::seek Oct 25 20:11:31 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:31 volumioamber volumio[1136]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","realUri":"upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919","type":"song","albumart":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ.jpg?albumArt=true","artist":"Ghibran, Padmalatha","album":"Maara","name":"Theeranadhi","title":"Theeranadhi","duration":272} Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:31 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:31 volumioamber volumio[1136]: info: [1761403291887] ControllerUPNPBrowser::seek Oct 25 20:11:31 volumioamber volumio[1136]: info: ControllerMpd::seek Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:31 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:31 volumioamber volumio[1136]: error: null Oct 25 20:11:31 volumioamber volumio[1136]: info: Oct 25 20:11:31 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:31 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:31 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:31 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:32 volumioamber sudo[3662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:32 volumioamber volumio[1136]: info: sendMpdCommand status took 49 milliseconds Oct 25 20:11:32 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:32 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:32 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 25 20:11:32 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:32 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:32 volumioamber sudo[3662]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:32 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:32 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13983,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:32 volumioamber volumio[1136]: verbose: CURRENT POSITION 7 Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:32 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:32 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:32 volumioamber volumio[1136]: info: ------------------------------ 120ms Oct 25 20:11:32 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:32 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:32 volumioamber sudo[3662]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:32 volumioamber sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:32 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:32 volumioamber sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:32 volumioamber sudo[3667]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:32 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:32 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:32 volumioamber sudo[3670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:32 volumioamber sudo[3670]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:32 volumioamber sudo[3670]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:32 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:33 volumioamber volumio[3664]: pygame 1.9.4.post1 Oct 25 20:11:33 volumioamber volumio[3664]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:33 volumioamber volumio[3664]: Traceback (most recent call last): Oct 25 20:11:33 volumioamber volumio[3664]: File "spectrum.py", line 715, in Oct 25 20:11:33 volumioamber volumio[3664]: pm = Spectrum(None, True) Oct 25 20:11:33 volumioamber volumio[3664]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:33 volumioamber volumio[3664]: self.init_display() Oct 25 20:11:33 volumioamber volumio[3664]: File "spectrum.py", line 104, in init_display Oct 25 20:11:33 volumioamber volumio[3664]: pygame.display.init() Oct 25 20:11:33 volumioamber volumio[3664]: pygame.error: Unable to open a console terminal Oct 25 20:11:33 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:33 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:11:33 volumioamber sudo[3673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 20:11:33 volumioamber sudo[3673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:33 volumioamber sudo[3673]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:33 volumioamber sudo[3676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 20:11:33 volumioamber sudo[3676]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:33 volumioamber sudo[3676]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:33 volumioamber volumio[1136]: verbose: New Socket.io Connection to 192.168.29.2 from 192.168.29.90 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 20:11:33 volumioamber volumio[1136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 20:11:33 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 20:11:34 volumioamber sudo[3680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 20:11:34 volumioamber sudo[3680]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:34 volumioamber sudo[3680]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:34 volumioamber sudo[3682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 20:11:34 volumioamber sudo[3682]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:34 volumioamber sudo[3682]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:34 volumioamber volumio[1136]: verbose: New Socket.io Connection to 192.168.29.2 from 192.168.29.90 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 20:11:34 volumioamber volumio[1136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 20:11:34 volumioamber volumio[1136]: info: Listing playlists Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 20:11:34 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 20:11:35 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetQueue Oct 25 20:11:35 volumioamber volumio[1136]: info: CoreStateMachine::getQueue Oct 25 20:11:35 volumioamber volumio[1136]: info: CorePlayQueue::getQueue Oct 25 20:11:36 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:36 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:36 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:36 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:36 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:36 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:36 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:36 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPlay Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::play index 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::serviceStop Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::serviceStop Oct 25 20:11:37 volumioamber volumio[1136]: info: [1761403297708] ControllerUPNPBrowser::stop Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:37 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:37 volumioamber volumio[1136]: info: Oct 25 20:11:37 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand stop took 54 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::play index undefined Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber sudo[3686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:37 volumioamber sudo[3686]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: [1761403297778] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand status took 11 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand stop took 2 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:11:37 volumioamber volumio[1136]: info: Oct 25 20:11:37 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:37 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand clear took 8 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ" Oct 25 20:11:37 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:37 volumioamber sudo[3686]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: 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":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:37 volumioamber volumio[1136]: verbose: CURRENT POSITION 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService stop Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: No code Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: ------------------------------ 87ms Oct 25 20:11:37 volumioamber volumio[1136]: error: updateQueue error: null Oct 25 20:11:37 volumioamber volumio[1136]: info: ------------------------------ 65ms Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ" Oct 25 20:11:37 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:37 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:37 volumioamber sudo[3690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:37 volumioamber sudo[3690]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:37 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:37 volumioamber sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:37 volumioamber sudo[3693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:37 volumioamber sudo[3690]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:37 volumioamber sudo[3695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:37 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:37 volumioamber sudo[3695]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:37 volumioamber volumio[1136]: info: Oct 25 20:11:37 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:37 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ" took 103 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:11:37 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:37 volumioamber volumio[1136]: info: ------------------------------ 6ms Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand play took 5 milliseconds Oct 25 20:11:37 volumioamber sudo[3693]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:37 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:37 volumioamber volumio[1136]: info: Oct 25 20:11:37 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:37 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand status took 8 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:37 volumioamber volumio[1136]: info: Oct 25 20:11:37 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:37 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:37 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:37 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:37 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:37 volumioamber volumio[1136]: verbose: CURRENT POSITION 7 Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:37 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:37 volumioamber volumio[1136]: info: ------------------------------ 18ms Oct 25 20:11:37 volumioamber volumio[1136]: info: sendMpdCommand status took 5 milliseconds Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:37 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:38 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:11:38 volumioamber sudo[3695]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:38 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:38 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:38 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:38 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:38 volumioamber volumio[1136]: verbose: CURRENT POSITION 7 Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:38 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:38 volumioamber volumio[1136]: info: ------------------------------ 57ms Oct 25 20:11:38 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:38 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:38 volumioamber sudo[3701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:38 volumioamber sudo[3701]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:38 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:38 volumioamber sudo[3703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:38 volumioamber sudo[3703]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:38 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:38 volumioamber sudo[3701]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:38 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:38 volumioamber sudo[3703]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:38 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:38 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:38 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:38 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:39 volumioamber volumio[3706]: pygame 1.9.4.post1 Oct 25 20:11:39 volumioamber volumio[3706]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:39 volumioamber volumio[3706]: Traceback (most recent call last): Oct 25 20:11:39 volumioamber volumio[3706]: File "spectrum.py", line 715, in Oct 25 20:11:39 volumioamber volumio[3706]: pm = Spectrum(None, True) Oct 25 20:11:39 volumioamber volumio[3706]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:39 volumioamber volumio[3706]: self.init_display() Oct 25 20:11:39 volumioamber volumio[3706]: File "spectrum.py", line 104, in init_display Oct 25 20:11:39 volumioamber volumio[3706]: pygame.display.init() Oct 25 20:11:39 volumioamber volumio[3706]: pygame.error: Unable to open a console terminal Oct 25 20:11:39 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:39 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:41 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:41 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:41 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:41 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:41 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:41 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioSeek Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::seek Oct 25 20:11:41 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:41 volumioamber volumio[1136]: info: TRACKBLOCK {"service":"upnp_browser","uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","realUri":"upnp/http://192.168.29.98:2869/upnphost/udhisapi.dll?control=uuid:9831a244-f71f-48a0-a7cf-5ca2640c6c73+urn:upnp-org:serviceId:ContentDirectory@7_89ddb07b-31919","type":"song","albumart":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ.jpg?albumArt=true","artist":"Ghibran, Padmalatha","album":"Maara","name":"Theeranadhi","title":"Theeranadhi","duration":272} Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:41 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:41 volumioamber volumio[1136]: info: [1761403301847] ControllerUPNPBrowser::seek Oct 25 20:11:41 volumioamber volumio[1136]: info: ControllerMpd::seek Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:41 volumioamber volumio[1136]: error: null Oct 25 20:11:41 volumioamber volumio[1136]: info: Oct 25 20:11:41 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:41 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:41 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:41 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:41 volumioamber volumio[1136]: info: sendMpdCommand status took 43 milliseconds Oct 25 20:11:41 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:41 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:41 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:11:41 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:41 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:41 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":69846,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:41 volumioamber volumio[1136]: verbose: CURRENT POSITION 7 Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:41 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:41 volumioamber sudo[3726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:41 volumioamber sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:41 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:41 volumioamber volumio[1136]: info: ------------------------------ 106ms Oct 25 20:11:42 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:42 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:42 volumioamber sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:42 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:42 volumioamber sudo[3732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:42 volumioamber sudo[3732]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:42 volumioamber sudo[3726]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:42 volumioamber sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:42 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:42 volumioamber sudo[3732]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:42 volumioamber sudo[3730]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:42 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:42 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:43 volumioamber volumio[3728]: pygame 1.9.4.post1 Oct 25 20:11:43 volumioamber volumio[3728]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:43 volumioamber volumio[3728]: Traceback (most recent call last): Oct 25 20:11:43 volumioamber volumio[3728]: File "spectrum.py", line 715, in Oct 25 20:11:43 volumioamber volumio[3728]: pm = Spectrum(None, True) Oct 25 20:11:43 volumioamber volumio[3728]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:43 volumioamber volumio[3728]: self.init_display() Oct 25 20:11:43 volumioamber volumio[3728]: File "spectrum.py", line 104, in init_display Oct 25 20:11:43 volumioamber volumio[3728]: pygame.display.init() Oct 25 20:11:43 volumioamber volumio[3728]: pygame.error: Unable to open a console terminal Oct 25 20:11:43 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:43 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:11:45 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:45 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:45 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:45 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:45 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:54 volumioamber sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 20:11:54 volumioamber sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:54 volumioamber sudo[3752]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:54 volumioamber sudo[3755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 20:11:54 volumioamber sudo[3755]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:54 volumioamber sudo[3755]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:54 volumioamber volumio[1136]: verbose: New Socket.io Connection to 192.168.29.2 from 192.168.29.90 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 20:11:54 volumioamber volumio[1136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 20:11:54 volumioamber sudo[3758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 25 20:11:54 volumioamber sudo[3758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:54 volumioamber sudo[3758]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:54 volumioamber sudo[3761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 25 20:11:54 volumioamber sudo[3761]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:54 volumioamber sudo[3761]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:54 volumioamber volumio[1136]: verbose: New Socket.io Connection to 192.168.29.2 from 192.168.29.90 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 25 20:11:54 volumioamber volumio[1136]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 20:11:54 volumioamber volumio[1136]: info: Listing playlists Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 25 20:11:54 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 25 20:11:55 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetQueue Oct 25 20:11:55 volumioamber volumio[1136]: info: CoreStateMachine::getQueue Oct 25 20:11:55 volumioamber volumio[1136]: info: CorePlayQueue::getQueue Oct 25 20:11:56 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:56 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:56 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:56 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:56 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:56 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:56 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPlay Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::play index 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::serviceStop Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 7 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::serviceStop Oct 25 20:11:57 volumioamber volumio[1136]: info: [1761403317110] ControllerUPNPBrowser::stop Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:57 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:57 volumioamber volumio[1136]: info: Oct 25 20:11:57 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand stop took 49 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::play index undefined Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: [1761403317162] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand status took 4 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand stop took 3 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:11:57 volumioamber sudo[3764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:57 volumioamber sudo[3764]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:57 volumioamber volumio[1136]: info: Oct 25 20:11:57 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:57 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand clear took 6 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMA" Oct 25 20:11:57 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: 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":"0_N184OWRkYjA3Yi0zMTkxOQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkxOQ","trackType":""} Oct 25 20:11:57 volumioamber volumio[1136]: verbose: CURRENT POSITION 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService stop Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: No code Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: ------------------------------ 101ms Oct 25 20:11:57 volumioamber systemd[1]: Starting Cleanup of Temporary Directories... Oct 25 20:11:57 volumioamber volumio[1136]: error: updateQueue error: null Oct 25 20:11:57 volumioamber volumio[1136]: info: ------------------------------ 101ms Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMA" Oct 25 20:11:57 volumioamber sudo[3764]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:57 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:57 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:57 volumioamber systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Oct 25 20:11:57 volumioamber systemd[1]: Started Cleanup of Temporary Directories. Oct 25 20:11:57 volumioamber sudo[3769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:57 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:11:57 volumioamber sudo[3769]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:57 volumioamber sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:57 volumioamber sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:57 volumioamber sudo[3769]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:57 volumioamber sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:11:57 volumioamber sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:57 volumioamber volumio[1136]: info: Oct 25 20:11:57 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:11:57 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMA" took 119 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:11:57 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:57 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:57 volumioamber volumio[1136]: info: ------------------------------ 8ms Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand play took 5 milliseconds Oct 25 20:11:57 volumioamber sudo[3771]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:57 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:57 volumioamber volumio[1136]: info: Oct 25 20:11:57 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:57 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:57 volumioamber volumio[1136]: info: Oct 25 20:11:57 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand status took 9 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand status took 3 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:57 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:11:57 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":112,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkyMA","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMA","trackType":""} Oct 25 20:11:57 volumioamber volumio[1136]: verbose: CURRENT POSITION 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:11:57 volumioamber sudo[3774]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:57 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:11:57 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":112,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkyMA","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMA","trackType":""} Oct 25 20:11:57 volumioamber volumio[1136]: verbose: CURRENT POSITION 8 Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:11:57 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:11:57 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:57 volumioamber volumio[1136]: info: ------------------------------ 44ms Oct 25 20:11:57 volumioamber volumio[1136]: info: ------------------------------ 80ms Oct 25 20:11:57 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:57 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:11:57 volumioamber sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:57 volumioamber sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:57 volumioamber sudo[3782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:11:57 volumioamber sudo[3782]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:11:57 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:11:57 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:11:57 volumioamber sudo[3780]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:57 volumioamber sudo[3782]: pam_unix(sudo:session): session closed for user root Oct 25 20:11:57 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:57 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:11:58 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 25 20:11:58 volumioamber volumio[1136]: info: Received Get System Info Oct 25 20:11:58 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 25 20:11:58 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 25 20:11:58 volumioamber volumio[1136]: info: Discovery: Getting this device information Oct 25 20:11:58 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:11:58 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 25 20:11:59 volumioamber volumio[3785]: pygame 1.9.4.post1 Oct 25 20:11:59 volumioamber volumio[3785]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:11:59 volumioamber volumio[3785]: Traceback (most recent call last): Oct 25 20:11:59 volumioamber volumio[3785]: File "spectrum.py", line 715, in Oct 25 20:11:59 volumioamber volumio[3785]: pm = Spectrum(None, True) Oct 25 20:11:59 volumioamber volumio[3785]: File "spectrum.py", line 63, in __init__ Oct 25 20:11:59 volumioamber volumio[3785]: self.init_display() Oct 25 20:11:59 volumioamber volumio[3785]: File "spectrum.py", line 104, in init_display Oct 25 20:11:59 volumioamber volumio[3785]: pygame.display.init() Oct 25 20:11:59 volumioamber volumio[3785]: pygame.error: Unable to open a console terminal Oct 25 20:11:59 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:11:59 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPlay Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::play index 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::stop Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::updateTrackBlock Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrackBlock Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::serviceStop Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 8 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::serviceStop Oct 25 20:12:00 volumioamber volumio[1136]: info: [1761403320123] ControllerUPNPBrowser::stop Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:12:00 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:12:00 volumioamber volumio[1136]: info: Oct 25 20:12:00 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand stop took 45 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::play index undefined Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetVisibleSources Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 25 20:12:00 volumioamber volumio[1136]: info: [1761403320171] ControllerUPNPBrowser::clearAddPlayTrack Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand status took 5 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand stop took 2 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:12:00 volumioamber volumio[1136]: info: Oct 25 20:12:00 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:12:00 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 25 20:12:00 volumioamber sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand clear took 3 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMQ" Oct 25 20:12:00 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:12:00 volumioamber sudo[3791]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: 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":"0_N184OWRkYjA3Yi0zMTkyMA","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMA","trackType":""} Oct 25 20:12:00 volumioamber volumio[1136]: verbose: CURRENT POSITION 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService stop Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: No code Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:12:00 volumioamber sudo[3791]: pam_unix(sudo:session): session closed for user root Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: ------------------------------ 75ms Oct 25 20:12:00 volumioamber volumio[1136]: error: updateQueue error: null Oct 25 20:12:00 volumioamber volumio[1136]: info: ------------------------------ 67ms Oct 25 20:12:00 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:12:00 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:12:00 volumioamber sudo[3795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:12:00 volumioamber sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:12:00 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status stop Oct 25 20:12:00 volumioamber sudo[3797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:12:00 volumioamber sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:12:00 volumioamber sudo[3795]: pam_unix(sudo:session): session closed for user root Oct 25 20:12:00 volumioamber sudo[3801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Oct 25 20:12:00 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMQ" Oct 25 20:12:00 volumioamber sudo[3801]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:12:00 volumioamber sudo[3797]: pam_unix(sudo:session): session closed for user root Oct 25 20:12:00 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:12:00 volumioamber volumio[1136]: info: Oct 25 20:12:00 volumioamber volumio[1136]: ---------------------------- MPD announces system playlist update Oct 25 20:12:00 volumioamber volumio[1136]: info: Ignoring MPD Status Update Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand add "http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMQ" took 7 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:12:00 volumioamber volumio[1136]: info: ------------------------------ 7ms Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand play took 6 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:12:00 volumioamber volumio[1136]: info: Oct 25 20:12:00 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:12:00 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:12:00 volumioamber volumio[1136]: info: Oct 25 20:12:00 volumioamber volumio[1136]: ---------------------------- MPD announces state update: player Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand status took 9 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: info: ControllerMpd::getState Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand status took 3 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 25 20:12:00 volumioamber sudo[3801]: pam_unix(sudo:session): session closed for user root Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::parseState Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:12:00 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkyMQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMQ","trackType":""} Oct 25 20:12:00 volumioamber volumio[1136]: verbose: CURRENT POSITION 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:12:00 volumioamber volumio[1136]: info: ------------------------------ 19ms Oct 25 20:12:00 volumioamber volumio[1136]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 25 20:12:00 volumioamber volumio[1136]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:12:00 volumioamber volumio[1136]: info: ControllerMpd::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::servicePushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CorePlayQueue::getTrack 9 Oct 25 20:12:00 volumioamber volumio[1136]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_N184OWRkYjA3Yi0zMTkyMQ","artist":null,"album":null,"uri":"http://192.168.29.98:10243/WMPNSSv4/3387337646/0_N184OWRkYjA3Yi0zMTkyMQ","trackType":""} Oct 25 20:12:00 volumioamber volumio[1136]: verbose: CURRENT POSITION 9 Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::syncState stateService play Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:12:00 volumioamber volumio[1136]: info: Received an update from plugin. extracting info from payload Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreStateMachine::pushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioPushState Oct 25 20:12:00 volumioamber volumio[1136]: info: CoreCommandRouter::volumioGetState Oct 25 20:12:00 volumioamber volumio[1136]: info: ------------------------------ 75ms Oct 25 20:12:00 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:12:00 volumioamber volumio[1136]: info: PeppySpectrum ---peppyspectrum status play Oct 25 20:12:00 volumioamber sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:12:00 volumioamber sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:12:00 volumioamber volumio[1136]: info: peppyspectrum Daemon Stop Oct 25 20:12:00 volumioamber sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Oct 25 20:12:00 volumioamber sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:12:00 volumioamber systemd[1]: Started peppyspectrum Daemon. Oct 25 20:12:00 volumioamber sudo[3806]: pam_unix(sudo:session): session closed for user root Oct 25 20:12:00 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:12:00 volumioamber sudo[3809]: pam_unix(sudo:session): session closed for user root Oct 25 20:12:00 volumioamber volumio[1136]: info: peppyspectrum Daemon Started Oct 25 20:12:02 volumioamber volumio[3811]: pygame 1.9.4.post1 Oct 25 20:12:02 volumioamber volumio[3811]: Hello from the pygame community. https://www.pygame.org/contribute.html Oct 25 20:12:02 volumioamber volumio[3811]: Traceback (most recent call last): Oct 25 20:12:02 volumioamber volumio[3811]: File "spectrum.py", line 715, in Oct 25 20:12:02 volumioamber volumio[3811]: pm = Spectrum(None, True) Oct 25 20:12:02 volumioamber volumio[3811]: File "spectrum.py", line 63, in __init__ Oct 25 20:12:02 volumioamber volumio[3811]: self.init_display() Oct 25 20:12:02 volumioamber volumio[3811]: File "spectrum.py", line 104, in init_display Oct 25 20:12:02 volumioamber volumio[3811]: pygame.display.init() Oct 25 20:12:02 volumioamber volumio[3811]: pygame.error: Unable to open a console terminal Oct 25 20:12:02 volumioamber systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Oct 25 20:12:02 volumioamber systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Oct 25 20:12:05 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 20:12:05 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:12:08 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 20:12:08 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:12:09 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 20:12:09 volumioamber volumio[1136]: info: Preload queue cleared Oct 25 20:12:13 volumioamber volumio[1136]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 25 20:12:13 volumioamber volumio[1136]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 20:12:13 volumioamber volumio[1136]: TypeError: Cannot read property 'length' of undefined Oct 25 20:12:13 volumioamber volumio[1136]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 25 20:12:13 volumioamber volumio[1136]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 25 20:12:13 volumioamber volumio[1136]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 25 20:12:13 volumioamber volumio[1136]: at Parser.emit (events.js:315:20) Oct 25 20:12:13 volumioamber volumio[1136]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 25 20:12:13 volumioamber volumio[1136]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 25 20:12:13 volumioamber volumio[1136]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 25 20:12:13 volumioamber volumio[1136]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 25 20:12:13 volumioamber volumio[1136]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 25 20:12:13 volumioamber volumio[1136]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 25 20:12:13 volumioamber volumio[1136]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 25 20:12:13 volumioamber volumio[1136]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 25 20:12:13 volumioamber volumio[1136]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 25 20:12:13 volumioamber volumio[1136]: at IncomingMessage.emit (events.js:327:22) Oct 25 20:12:13 volumioamber volumio[1136]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 25 20:12:13 volumioamber volumio[1136]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 25 20:12:13 volumioamber volumio[1136]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 20:12:13 volumioamber sudo[3852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-25 20:11 Oct 25 20:12:13 volumioamber sudo[3852]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"