-- Logs begin at Fri 2025-05-16 22:12:56 CST, end at Fri 2025-05-16 22:16:43 CST. -- May 16 22:15:00 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 16 22:15:00 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: Stopping peppymeterbasic service May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::stop May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::stPlaybackTimer May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::serviceStop May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::serviceStop May 16 22:15:03 volumio volumio[1012]: info: [1747404903698] ControllerVolusonic::stop May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::stop May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand stop May 16 22:15:03 volumio volumio[1012]: info: Disabling plugin peppymeterbasic May 16 22:15:03 volumio volumio[1012]: info: Preparing to generate the ALSA configuration file May 16 22:15:03 volumio volumio[1012]: info: PeppyMeterBasic ---peppymeterbasic status stop May 16 22:15:03 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:03 volumio sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 16 22:15:03 volumio sudo[2398]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:03 volumio volumio[1012]: info: [1747404903774] ControllerVolusonic: MPD player state update May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio volumio[1012]: info: May 16 22:15:03 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand stop took 76 milliseconds May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio volumio[1012]: info: May 16 22:15:03 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio sudo[2398]: pam_unix(sudo:session): session closed for user root May 16 22:15:03 volumio volumio[1012]: info: May 16 22:15:03 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio volumio[1012]: info: May 16 22:15:03 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio volumio[1012]: info: May 16 22:15:03 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:03 volumio volumio[1012]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 16 22:15:03 volumio sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:03 volumio volumio[1012]: info: Reading ALSA contributions from plugins. May 16 22:15:03 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand status took 15 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand status took 13 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand status took 11 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand status took 9 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand status took 7 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand status took 6 milliseconds May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:03 volumio volumio[1012]: info: peppymeterbasic Daemon Stop May 16 22:15:03 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 11 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 12 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 6 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 6 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 6 milliseconds May 16 22:15:03 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: [1747404903807] ControllerVolusonic::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: 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":"stream.view?id=a3dbf5f751e6b4639d049d4b214db00f&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.100.43:4533/rest/stream.view?id=a3dbf5f751e6b4639d049d4b214db00f&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:03 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: No code May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:03 volumio volumio[1012]: info: [1747404903822] ControllerVolusonic::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: 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":"stream.view?id=a3dbf5f751e6b4639d049d4b214db00f&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.100.43:4533/rest/stream.view?id=a3dbf5f751e6b4639d049d4b214db00f&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:03 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: No code May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:03 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:03 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:03 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:03 volumio volumio[1012]: info: ------------------------------ 134ms May 16 22:15:03 volumio volumio[1012]: info: ------------------------------ 153ms May 16 22:15:03 volumio volumio[1012]: info: ------------------------------ 152ms May 16 22:15:03 volumio volumio[1012]: info: ------------------------------ 150ms May 16 22:15:03 volumio volumio[1012]: info: ------------------------------ 149ms May 16 22:15:03 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:03 volumio sudo[2407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 16 22:15:03 volumio sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:03 volumio sudo[2407]: pam_unix(sudo:session): session closed for user root May 16 22:15:03 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:03 volumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:03 volumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:03 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2421]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2424]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2427]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2427]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2430]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2430]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2433]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:04 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2439]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: Stopping peppyspectrum service May 16 22:15:04 volumio volumio[1012]: info: CoreStateMachine::stop May 16 22:15:04 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:04 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio volumio[1012]: info: Disabling plugin peppyspectrum May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:04 volumio sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:04 volumio volumio[1012]: info: Asound.conf file written May 16 22:15:04 volumio sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2445]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 16 22:15:04 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio volumio[1012]: info: Output device has changed, restarting MPD May 16 22:15:04 volumio sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 22:15:04 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio volumio[1012]: info: Output device has changed, restarting Shairport Sync May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:04 volumio sudo[2457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 22:15:04 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio systemd[1]: Stopping Music Player Daemon... May 16 22:15:04 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 22:15:04 volumio volumio[1012]: info: Preparing to generate the ALSA configuration file May 16 22:15:04 volumio systemd[1]: mpd.service: Succeeded. May 16 22:15:04 volumio systemd[1]: Stopped Music Player Daemon. May 16 22:15:04 volumio volumio[1012]: info: Done. May 16 22:15:04 volumio volumio[1012]: info: MPD Permissions set May 16 22:15:04 volumio systemd[1]: Starting Music Player Daemon... May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: Starting Shairport Sync May 16 22:15:04 volumio volumio[1012]: info: Asound.conf file written May 16 22:15:04 volumio sudo[2462]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 22:15:04 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 22:15:04 volumio sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio sudo[2474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 16 22:15:04 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 22:15:04 volumio squeezeboxserver[940]: [22:15:04.605] mDNSsearchCallback:682 [0x628670]: removing renderer (volumio) May 16 22:15:04 volumio squeezeboxserver[940]: [22:15:04.605] slimproto_close:906 [0x5cdfa8] slimproto stop for volumio May 16 22:15:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 16 22:15:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 22:15:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 22:15:04 volumio sudo[2470]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio volumio[1012]: info: Output device has changed, restarting MPD May 16 22:15:04 volumio sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 22:15:04 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio volumio[1012]: info: Output device has changed, restarting Shairport Sync May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:04 volumio sudo[2486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 22:15:04 volumio sudo[2486]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 16 22:15:04 volumio systemd[1]: mpd.service: Succeeded. May 16 22:15:04 volumio systemd[1]: Stopped Music Player Daemon. May 16 22:15:04 volumio squeezeboxserver[940]: [22:15:04.733] DelRaopDevice:1011 [0x628670]: Raop device stopped May 16 22:15:04 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 22:15:04 volumio volumio[1012]: info: Done. May 16 22:15:04 volumio systemd[1]: Starting Music Player Daemon... May 16 22:15:04 volumio volumio[1012]: info: MPD Permissions set May 16 22:15:04 volumio volumio[1012]: info: Shairport-Sync Started May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 22:15:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:04 volumio volumio[1012]: info: Starting Shairport Sync May 16 22:15:04 volumio sudo[2493]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 22:15:04 volumio sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 22:15:04 volumio sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:04 volumio sudo[2493]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 22:15:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 16 22:15:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 22:15:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 22:15:04 volumio sudo[2501]: pam_unix(sudo:session): session closed for user root May 16 22:15:04 volumio volumio[1012]: info: Shairport-Sync Started May 16 22:15:05 volumio volumio[1012]: error: Upnp client error: Error: This socket has been ended by the other party May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.725] AddRaopDevice:865 [0x628670]: AirPort Express May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.725] AddRaopDevice:929 [0x628670]: adding renderer (volumio@192.168.100.49) with mac AAAA-DF0F404D May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.725] raopcl_create:715 [0x7f726c01e9c0]: using PCM coding May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.725] output_raop_thread_init:119 [0x5cdfa8]: init output raop May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.725] output_init_common:290 allocating 1764000 May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.725] mDNSsearchCallback:746 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.726] slimproto:834 squeezelite [0x5cdfa8] <=> player [0x628670] May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.726] slimproto:835 [0x5cdfa8] connecting to 192.168.100.49:3483 May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.726] slimproto:865 [0x5cdfa8] connected May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.726] sendHELO:107 [0x5cdfa8] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.728] output_flush:331 [0x5cdfa8]: flush output buffer (full) May 16 22:15:05 volumio squeezeboxserver[940]: [22:15:05.730] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31 May 16 22:15:05 volumio mpd[2504]: May 16 22:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 16 22:15:05 volumio systemd[1]: Started Music Player Daemon. May 16 22:15:05 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root May 16 22:15:05 volumio sudo[2486]: pam_unix(sudo:session): session closed for user root May 16 22:15:05 volumio volumio[1012]: error: MPD error: The expression evaluated to a falsy value: May 16 22:15:05 volumio volumio[1012]: assert.ok(self.idling) May 16 22:15:05 volumio volumio[1012]: error: The expression evaluated to a falsy value: May 16 22:15:05 volumio volumio[1012]: assert.ok(self.idling) May 16 22:15:05 volumio volumio[1012]: error: updateQueue error: null May 16 22:15:08 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:08 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:11 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri May 16 22:15:11 volumio volumio[1012]: info: Preload queue cleared May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/5ffc5a920839c26c909000e1ad043e20 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/9599e43649711a5b6e113339e8630f5a May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/9390183d36cb3cca37fa8803942e3334 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/c72232e19fceeda65c9eb83ea1373122 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/e3a23b3991a856f75998c0af0fe2d7e7 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/9c6e158e96cba3a2966d99bc885e06a0 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/f3e72aab826e8593cc74d281b79987bc May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/77749d17d96ca2bbdbf35d518d22bf61 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/7ef5ece35fcea790e541d297584ef3dd May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/0e2e823a6bb89a3860cc287f97ebfc28 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/44f0b0ba49cd95f53b668bc1fcd53803 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/ed88e5c33385ad3856e64b19ef544148 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/b717de8ecc8ddfafb6479dfb446adf50 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/e58ff62eb2f58037ad9c14a7f17429a3 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/4523940b766d5c38e580efa5b94e2192 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/56483db58831a8b27f8c1694f411c06b May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/049aeccd6f464ec76859794b54032b11 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/05ea96e16cb47a6c63806d7c0faeda4a May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/191a495c0c04a3eac2a152c4256cd46d May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/a7e90bc34233b6bfca7c9f7c220b1155 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/da00ec90f4c579305874d22d651bcf08 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/0a588e64278e2a87ec99ce791dd33bfd May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/0687ad8cffa134ebfa3ef80849298037 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/9981c0283d1653c6d1e24d06d612ab1d May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/35c199753ecf24f40b617cc94c783408 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/8f14a8b862774566dbbfce8ab66d930a May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/f97ff6a92072601dbf7155a7c0ea53b6 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/a3e4d694991f515f336e85e1dbcba271 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/161623cdb21ba78db9aac2ed81e2eff0 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/fbc0163bef57946e6a9ee2288c205041 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/707975f01f19937f3636dbceaeefc004 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/d75ca83073d5549b3850438353c26a91 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/bb954aedd583b25dcd4256cc79bd8535 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/7bc0069bb7a5eb8676147f7fe3cd0d50 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/050ed916ad0f797cf91221d0b10ac594 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/3677ef2be78f4ad28c7221e30eac7a2f May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/8c7de28465df6fdefb03a594bc287ad3 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/4014e61031189761a754bf2439802d0c May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/166ccd83baa34cba5068458f97276bfb May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/2b835c84c5393957a9e7b8e27b7a8952 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/b34826f94ac4ba157f832d62d936bbd7 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/0ba6db620f14ccabfc2208963accc9e0 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/6ecb7261e21da0cab74d2dcd77ab4dbc May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/d918ce156e23221a160efc4de07fce36 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/ab9905c6f836df0f4729c8b3f99feeb4 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/ea8bea3fde7f94a6028061f83cfe91ab May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/a43919a3e0768914195b9292ff4ada7e May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/b2bcb6eab44c64f5b8309f4d27d92571 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/d217e870416dc4f82814736f3ee5f953 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/11e300ae6ad544f68bc35fc312b9508d May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/3008090978e08593c8d1b9a4f6f09a69 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/fba80fc6d308669d8299fb29bcd32c41 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/767286d3f7ca4c10da3cb572e8f98efc May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/205542d91e1e8875a79345ca9e2ee5df May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/498a2b3c1b9f796b2fe6b0f10aa8b4ea May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/fb468086f20fd70b287e6b92500ad009 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/730c04a3c33a3967d8238912c5c517ae May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/6a0ca757957a2850672118fa2397203b May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/87876f7ea5bec5d1a6074a78f988952c May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/98125db302159be0cf4996c24b9a6288 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/2aecac07b64b510833687f0b522e635f May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/80e7f34f736bc0e516cbd23a2cf48c5d May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/dca81c5717af7121157c7492024e71cd May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/c401a9a6a1ad759b4a7288e263602ec7 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/90563226e111d50c4ddfc905d4ba6df8 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/adf51676d73797876dd02b72a09dbaaf May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/4be1776ab168bc463cdc551a6bdb3ec9 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/21acec09ae14212ce444a53697540ed0 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/282b064c9e9ed341e079d2c177629dd8 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/b6203feffb000bd8a3d6d36d3410db7f May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/e4acb768f696d9a427f06afea3e3c081 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/d2f03bf9ecec0bc9b2e47b84bd174194 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/2ea9ab6c6986f0a76abdf2d2466cda1b May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/50392c8970b71f4420119fd69a37774a May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/c3d4c0598211bdfb30b7608743ccfe7b May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/f009772368123f269ece70122c94a44a May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/f88ae8e006a6eb8cc5785772291533a2 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/547c20ca3389fbb7f10db85891adec56 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/a72dc556502ab46fe0bf07bf3668456e May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/cfcab02265dcd80f464e08db26cc1914 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/1e4ac2aa24a3b7d3e12d9deef70c15dd May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/ce99cefd0fbfd00c2d90b4ced7617978 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/5073c49a6e71b7aeb9ea1e9cd5e8f9f3 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/ba478e4029c5562a2283f8e39cc16cd4 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/1155ce477abdd878d3e68fd8a2eb7f2e May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/658727a0f7ea4ff4e560ab615a0b70ae May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/52f209471615fcd1c85122bf66059763 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/5f47f035bf362f01722001d6274561b1 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/16c893e6c06064f14a43a65b451c7cc0 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/6f3483eb5a1e50e178b800715be0885d May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/88979d35cb16125e254f4e9c9ae625b0 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/53685fdcbb12c5f7c16f43ccb8f3baa3 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/760ef864bea4a44a213a1d673d6672ca May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/b390a4b3de39e518ee988df4a14d3931 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/e439f3420c90fb93b12b02caeac5f933 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/87d6ff669347b02d86b469cd4c68fb9d May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/cb53838fc8d3e24ab7f9ffbc599d1333 May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/480a9d20e9e037fffaff35545bf5159a May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/5fe78ce4a490354614b503b26c942c9e May 16 22:15:11 volumio volumio[1012]: info: Preloading song: volusonic/track/0e497e2bb3852d202b2269acb0064d3a May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/5ffc5a920839c26c909000e1ad043e20 in service volusonic May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/9599e43649711a5b6e113339e8630f5a in service volusonic May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/9390183d36cb3cca37fa8803942e3334 in service volusonic May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/c72232e19fceeda65c9eb83ea1373122 in service volusonic May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/e3a23b3991a856f75998c0af0fe2d7e7 in service volusonic May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/9c6e158e96cba3a2966d99bc885e06a0 in service volusonic May 16 22:15:11 volumio volumio[1012]: info: Exploding uri volusonic/track/f3e72aab826e8593cc74d281b79987bc in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/77749d17d96ca2bbdbf35d518d22bf61 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/7ef5ece35fcea790e541d297584ef3dd in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/0e2e823a6bb89a3860cc287f97ebfc28 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/44f0b0ba49cd95f53b668bc1fcd53803 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/ed88e5c33385ad3856e64b19ef544148 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/b717de8ecc8ddfafb6479dfb446adf50 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/e58ff62eb2f58037ad9c14a7f17429a3 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/4523940b766d5c38e580efa5b94e2192 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/56483db58831a8b27f8c1694f411c06b in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/049aeccd6f464ec76859794b54032b11 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/05ea96e16cb47a6c63806d7c0faeda4a in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/191a495c0c04a3eac2a152c4256cd46d in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/a7e90bc34233b6bfca7c9f7c220b1155 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/da00ec90f4c579305874d22d651bcf08 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/0a588e64278e2a87ec99ce791dd33bfd in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/0687ad8cffa134ebfa3ef80849298037 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/9981c0283d1653c6d1e24d06d612ab1d in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/35c199753ecf24f40b617cc94c783408 in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/8f14a8b862774566dbbfce8ab66d930a in service volusonic May 16 22:15:12 volumio volumio[1012]: info: Exploding uri volusonic/track/f97ff6a92072601dbf7155a7c0ea53b6 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/a3e4d694991f515f336e85e1dbcba271 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/161623cdb21ba78db9aac2ed81e2eff0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:13 volumio volumio[1012]: info: Listing playlists May 16 22:15:13 volumio volumio[1012]: info: Listing playlists May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/fbc0163bef57946e6a9ee2288c205041 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/707975f01f19937f3636dbceaeefc004 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Preload queue cleared May 16 22:15:13 volumio volumio[1012]: info: CoreCommandRouter::volumioReplaceandPlayItems May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::ClearQueue May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::stop May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::clearPlayQueue May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::saveQueue May 16 22:15:13 volumio volumio[1012]: info: CoreCommandRouter::volumioPushQueue May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::addQueueItems May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::addQueueItems May 16 22:15:13 volumio volumio[1012]: info: Preload queue cleared May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/5ffc5a920839c26c909000e1ad043e20 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/5ffc5a920839c26c909000e1ad043e20 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: CoreCommandRouter::volumioPushQueue May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::saveQueue May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:15:13 volumio volumio[1012]: info: CoreCommandRouter::volumioPlay May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::play index 0 May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::addQueueItems May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::addQueueItems May 16 22:15:13 volumio volumio[1012]: info: Preload queue cleared May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/9599e43649711a5b6e113339e8630f5a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/9599e43649711a5b6e113339e8630f5a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/9390183d36cb3cca37fa8803942e3334 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/9390183d36cb3cca37fa8803942e3334 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/c72232e19fceeda65c9eb83ea1373122 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/c72232e19fceeda65c9eb83ea1373122 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/e3a23b3991a856f75998c0af0fe2d7e7 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/e3a23b3991a856f75998c0af0fe2d7e7 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/9c6e158e96cba3a2966d99bc885e06a0 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/9c6e158e96cba3a2966d99bc885e06a0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/f3e72aab826e8593cc74d281b79987bc May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/f3e72aab826e8593cc74d281b79987bc in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/77749d17d96ca2bbdbf35d518d22bf61 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/77749d17d96ca2bbdbf35d518d22bf61 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/7ef5ece35fcea790e541d297584ef3dd May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/7ef5ece35fcea790e541d297584ef3dd in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/0e2e823a6bb89a3860cc287f97ebfc28 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/0e2e823a6bb89a3860cc287f97ebfc28 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/44f0b0ba49cd95f53b668bc1fcd53803 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/44f0b0ba49cd95f53b668bc1fcd53803 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/ed88e5c33385ad3856e64b19ef544148 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/ed88e5c33385ad3856e64b19ef544148 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/b717de8ecc8ddfafb6479dfb446adf50 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/b717de8ecc8ddfafb6479dfb446adf50 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/e58ff62eb2f58037ad9c14a7f17429a3 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/e58ff62eb2f58037ad9c14a7f17429a3 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/4523940b766d5c38e580efa5b94e2192 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/4523940b766d5c38e580efa5b94e2192 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/56483db58831a8b27f8c1694f411c06b May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/56483db58831a8b27f8c1694f411c06b in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/049aeccd6f464ec76859794b54032b11 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/049aeccd6f464ec76859794b54032b11 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/05ea96e16cb47a6c63806d7c0faeda4a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/05ea96e16cb47a6c63806d7c0faeda4a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/191a495c0c04a3eac2a152c4256cd46d May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/191a495c0c04a3eac2a152c4256cd46d in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/a7e90bc34233b6bfca7c9f7c220b1155 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/a7e90bc34233b6bfca7c9f7c220b1155 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/da00ec90f4c579305874d22d651bcf08 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/da00ec90f4c579305874d22d651bcf08 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/0a588e64278e2a87ec99ce791dd33bfd May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/0a588e64278e2a87ec99ce791dd33bfd in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/0687ad8cffa134ebfa3ef80849298037 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/0687ad8cffa134ebfa3ef80849298037 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/9981c0283d1653c6d1e24d06d612ab1d May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/9981c0283d1653c6d1e24d06d612ab1d in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/35c199753ecf24f40b617cc94c783408 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/35c199753ecf24f40b617cc94c783408 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/8f14a8b862774566dbbfce8ab66d930a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/8f14a8b862774566dbbfce8ab66d930a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/f97ff6a92072601dbf7155a7c0ea53b6 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/f97ff6a92072601dbf7155a7c0ea53b6 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/a3e4d694991f515f336e85e1dbcba271 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/a3e4d694991f515f336e85e1dbcba271 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/161623cdb21ba78db9aac2ed81e2eff0 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/161623cdb21ba78db9aac2ed81e2eff0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/fbc0163bef57946e6a9ee2288c205041 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/fbc0163bef57946e6a9ee2288c205041 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/707975f01f19937f3636dbceaeefc004 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/707975f01f19937f3636dbceaeefc004 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/d75ca83073d5549b3850438353c26a91 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/d75ca83073d5549b3850438353c26a91 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/bb954aedd583b25dcd4256cc79bd8535 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/bb954aedd583b25dcd4256cc79bd8535 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/7bc0069bb7a5eb8676147f7fe3cd0d50 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/7bc0069bb7a5eb8676147f7fe3cd0d50 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/050ed916ad0f797cf91221d0b10ac594 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/050ed916ad0f797cf91221d0b10ac594 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/3677ef2be78f4ad28c7221e30eac7a2f May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/3677ef2be78f4ad28c7221e30eac7a2f in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/8c7de28465df6fdefb03a594bc287ad3 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/8c7de28465df6fdefb03a594bc287ad3 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/4014e61031189761a754bf2439802d0c May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/4014e61031189761a754bf2439802d0c in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/166ccd83baa34cba5068458f97276bfb May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/166ccd83baa34cba5068458f97276bfb in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/2b835c84c5393957a9e7b8e27b7a8952 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/2b835c84c5393957a9e7b8e27b7a8952 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/b34826f94ac4ba157f832d62d936bbd7 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/b34826f94ac4ba157f832d62d936bbd7 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/0ba6db620f14ccabfc2208963accc9e0 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/0ba6db620f14ccabfc2208963accc9e0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/6ecb7261e21da0cab74d2dcd77ab4dbc May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/6ecb7261e21da0cab74d2dcd77ab4dbc in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/d918ce156e23221a160efc4de07fce36 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/d918ce156e23221a160efc4de07fce36 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/ab9905c6f836df0f4729c8b3f99feeb4 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/ab9905c6f836df0f4729c8b3f99feeb4 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/ea8bea3fde7f94a6028061f83cfe91ab May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/ea8bea3fde7f94a6028061f83cfe91ab in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/a43919a3e0768914195b9292ff4ada7e May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/a43919a3e0768914195b9292ff4ada7e in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/b2bcb6eab44c64f5b8309f4d27d92571 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/b2bcb6eab44c64f5b8309f4d27d92571 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/d217e870416dc4f82814736f3ee5f953 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/d217e870416dc4f82814736f3ee5f953 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/11e300ae6ad544f68bc35fc312b9508d May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/11e300ae6ad544f68bc35fc312b9508d in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/3008090978e08593c8d1b9a4f6f09a69 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/3008090978e08593c8d1b9a4f6f09a69 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/fba80fc6d308669d8299fb29bcd32c41 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/fba80fc6d308669d8299fb29bcd32c41 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/767286d3f7ca4c10da3cb572e8f98efc May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/767286d3f7ca4c10da3cb572e8f98efc in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/205542d91e1e8875a79345ca9e2ee5df May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/205542d91e1e8875a79345ca9e2ee5df in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/498a2b3c1b9f796b2fe6b0f10aa8b4ea May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/498a2b3c1b9f796b2fe6b0f10aa8b4ea in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/fb468086f20fd70b287e6b92500ad009 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/fb468086f20fd70b287e6b92500ad009 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/730c04a3c33a3967d8238912c5c517ae May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/730c04a3c33a3967d8238912c5c517ae in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/6a0ca757957a2850672118fa2397203b May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/6a0ca757957a2850672118fa2397203b in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/87876f7ea5bec5d1a6074a78f988952c May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/87876f7ea5bec5d1a6074a78f988952c in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/98125db302159be0cf4996c24b9a6288 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/98125db302159be0cf4996c24b9a6288 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/2aecac07b64b510833687f0b522e635f May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/2aecac07b64b510833687f0b522e635f in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/80e7f34f736bc0e516cbd23a2cf48c5d May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/80e7f34f736bc0e516cbd23a2cf48c5d in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/dca81c5717af7121157c7492024e71cd May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/dca81c5717af7121157c7492024e71cd in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/c401a9a6a1ad759b4a7288e263602ec7 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/c401a9a6a1ad759b4a7288e263602ec7 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/90563226e111d50c4ddfc905d4ba6df8 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/90563226e111d50c4ddfc905d4ba6df8 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/adf51676d73797876dd02b72a09dbaaf May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/adf51676d73797876dd02b72a09dbaaf in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/4be1776ab168bc463cdc551a6bdb3ec9 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/4be1776ab168bc463cdc551a6bdb3ec9 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/21acec09ae14212ce444a53697540ed0 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/21acec09ae14212ce444a53697540ed0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/282b064c9e9ed341e079d2c177629dd8 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/282b064c9e9ed341e079d2c177629dd8 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/b6203feffb000bd8a3d6d36d3410db7f May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/b6203feffb000bd8a3d6d36d3410db7f in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/e4acb768f696d9a427f06afea3e3c081 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/e4acb768f696d9a427f06afea3e3c081 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/d2f03bf9ecec0bc9b2e47b84bd174194 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/d2f03bf9ecec0bc9b2e47b84bd174194 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/2ea9ab6c6986f0a76abdf2d2466cda1b May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/2ea9ab6c6986f0a76abdf2d2466cda1b in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/50392c8970b71f4420119fd69a37774a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/50392c8970b71f4420119fd69a37774a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/c3d4c0598211bdfb30b7608743ccfe7b May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/c3d4c0598211bdfb30b7608743ccfe7b in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/f009772368123f269ece70122c94a44a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/f009772368123f269ece70122c94a44a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/f88ae8e006a6eb8cc5785772291533a2 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/f88ae8e006a6eb8cc5785772291533a2 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/547c20ca3389fbb7f10db85891adec56 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/547c20ca3389fbb7f10db85891adec56 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/a72dc556502ab46fe0bf07bf3668456e May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/a72dc556502ab46fe0bf07bf3668456e in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/cfcab02265dcd80f464e08db26cc1914 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/cfcab02265dcd80f464e08db26cc1914 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/1e4ac2aa24a3b7d3e12d9deef70c15dd May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/1e4ac2aa24a3b7d3e12d9deef70c15dd in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/ce99cefd0fbfd00c2d90b4ced7617978 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/ce99cefd0fbfd00c2d90b4ced7617978 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/5073c49a6e71b7aeb9ea1e9cd5e8f9f3 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/5073c49a6e71b7aeb9ea1e9cd5e8f9f3 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/ba478e4029c5562a2283f8e39cc16cd4 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/ba478e4029c5562a2283f8e39cc16cd4 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/1155ce477abdd878d3e68fd8a2eb7f2e May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/1155ce477abdd878d3e68fd8a2eb7f2e in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/658727a0f7ea4ff4e560ab615a0b70ae May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/658727a0f7ea4ff4e560ab615a0b70ae in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/52f209471615fcd1c85122bf66059763 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/52f209471615fcd1c85122bf66059763 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/5f47f035bf362f01722001d6274561b1 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/5f47f035bf362f01722001d6274561b1 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/16c893e6c06064f14a43a65b451c7cc0 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/16c893e6c06064f14a43a65b451c7cc0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/6f3483eb5a1e50e178b800715be0885d May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/6f3483eb5a1e50e178b800715be0885d in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/88979d35cb16125e254f4e9c9ae625b0 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/88979d35cb16125e254f4e9c9ae625b0 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/53685fdcbb12c5f7c16f43ccb8f3baa3 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/53685fdcbb12c5f7c16f43ccb8f3baa3 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/760ef864bea4a44a213a1d673d6672ca May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/760ef864bea4a44a213a1d673d6672ca in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/b390a4b3de39e518ee988df4a14d3931 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/b390a4b3de39e518ee988df4a14d3931 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/e439f3420c90fb93b12b02caeac5f933 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/e439f3420c90fb93b12b02caeac5f933 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/87d6ff669347b02d86b469cd4c68fb9d May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/87d6ff669347b02d86b469cd4c68fb9d in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/cb53838fc8d3e24ab7f9ffbc599d1333 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/cb53838fc8d3e24ab7f9ffbc599d1333 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/480a9d20e9e037fffaff35545bf5159a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/480a9d20e9e037fffaff35545bf5159a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/5fe78ce4a490354614b503b26c942c9e May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/5fe78ce4a490354614b503b26c942c9e in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/0e497e2bb3852d202b2269acb0064d3a May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/0e497e2bb3852d202b2269acb0064d3a in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/17da92fe72f8db552d8586987e62254f May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/17da92fe72f8db552d8586987e62254f in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/41678e22615535b63acc860d13c16cee May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/41678e22615535b63acc860d13c16cee in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/91f27fc81889ffe6fba8a786a23102cb May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/91f27fc81889ffe6fba8a786a23102cb in service volusonic May 16 22:15:13 volumio volumio[1012]: info: Adding Item to queue: volusonic/track/143b02db0270d1efd165e80b196aba66 May 16 22:15:13 volumio volumio[1012]: info: Exploding uri volusonic/track/143b02db0270d1efd165e80b196aba66 in service volusonic May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::stop May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::play index undefined May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::startPlaybackTimer May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:13 volumio volumio[1012]: info: [1747404913311] ControllerVolusonic::clearAddPlayTrack May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand stop May 16 22:15:13 volumio volumio[1012]: info: sendMpdCommand stop took 121 milliseconds May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand clear May 16 22:15:13 volumio volumio[1012]: info: May 16 22:15:13 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:13 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:13 volumio volumio[1012]: info: sendMpdCommand clear took 10 milliseconds May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" May 16 22:15:13 volumio volumio[1012]: info: May 16 22:15:13 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:13 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:13 volumio volumio[1012]: error: updateQueue error: null May 16 22:15:13 volumio volumio[1012]: info: ------------------------------ 3ms May 16 22:15:13 volumio volumio[1012]: error: updateQueue error: null May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" May 16 22:15:13 volumio volumio[1012]: info: ------------------------------ 183ms May 16 22:15:13 volumio volumio[1012]: info: May 16 22:15:13 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:13 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:13 volumio volumio[1012]: info: sendMpdCommand add "http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" took 5 milliseconds May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand play May 16 22:15:13 volumio volumio[1012]: info: May 16 22:15:13 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:13 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:13 volumio volumio[1012]: info: [1747404913675] ControllerVolusonic: MPD player state update May 16 22:15:13 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:13 volumio volumio[1012]: info: May 16 22:15:13 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:13 volumio volumio[1012]: info: ------------------------------ 45ms May 16 22:15:13 volumio volumio[1012]: info: sendMpdCommand play took 44 milliseconds May 16 22:15:13 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:13 volumio volumio[1012]: info: ------------------------------ 17ms May 16 22:15:13 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:13 volumio volumio[1012]: info: May 16 22:15:13 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:13 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:13 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:13 volumio volumio[1012]: info: [now-playing] OpenWeatherMap API key obtained. May 16 22:15:13 volumio volumio[1012]: info: CoreCommandRouter::volumioPushQueue May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::saveQueue May 16 22:15:13 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:15:13 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand status took 825 milliseconds May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:14 volumio volumio[1012]: info: May 16 22:15:14 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:14 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:14 volumio volumio[1012]: info: May 16 22:15:14 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:14 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:14 volumio volumio[1012]: info: May 16 22:15:14 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:14 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:14 volumio volumio[1012]: info: May 16 22:15:14 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:14 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand status took 829 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand status took 829 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand status took 825 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 22:15:14 volumio volumio[1012]: info: ------------------------------ 5ms May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: [1747404914509] ControllerVolusonic::pushState May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"4 In Love","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:14 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::syncState stateService play May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand status took 8 milliseconds May 16 22:15:14 volumio volumio[1012]: info: ------------------------------ 7ms May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand status took 7 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 5 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:14 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:14 volumio volumio[1012]: info: [1747404914515] ControllerVolusonic::pushState May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"4 In Love","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:14 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::syncState stateService play May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus play May 16 22:15:14 volumio volumio[1012]: info: Received an update from plugin. extracting info from payload May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:14 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:14 volumio volumio[1012]: info: ------------------------------ 851ms May 16 22:15:14 volumio volumio[1012]: info: ------------------------------ 860ms May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 29 milliseconds May 16 22:15:14 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 29 milliseconds May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:14 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:14 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:14 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:14 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:14 volumio volumio[1012]: info: ------------------------------ 41ms May 16 22:15:14 volumio volumio[1012]: info: ------------------------------ 40ms May 16 22:15:14 volumio volumio[1012]: info: CoreCommandRouter::volumioGetQueue May 16 22:15:14 volumio volumio[1012]: info: CoreStateMachine::getQueue May 16 22:15:14 volumio volumio[1012]: info: CorePlayQueue::getQueue May 16 22:15:23 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:23 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::volumioPause May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::pause May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::stPlaybackTimer May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::servicePause May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::servicePause May 16 22:15:33 volumio volumio[1012]: info: [1747404933079] ControllerVolusonic::pause May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::pause May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand pause May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: [1747404933086] ControllerVolusonic: MPD player state update May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:33 volumio volumio[1012]: info: May 16 22:15:33 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand pause took 7 milliseconds May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:33 volumio volumio[1012]: info: May 16 22:15:33 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand status took 4 milliseconds May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand status took 3 milliseconds May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand status took 3 milliseconds May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand status took 2 milliseconds May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 3 milliseconds May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 22:15:33 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 3 milliseconds May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:33 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: [1747404933099] ControllerVolusonic::pushState May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19488,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"4 In Love","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:33 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::syncState stateService pause May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus pause May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::stPlaybackTimer May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:33 volumio volumio[1012]: info: [1747404933111] ControllerVolusonic::pushState May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":19488,"duration":258,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"一千零一个愿望","artist":"4 In Love","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=5ffc5a920839c26c909000e1ad043e20&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:33 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::syncState stateService pause May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus pause May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:33 volumio volumio[1012]: info: CoreStateMachine::stPlaybackTimer May 16 22:15:33 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:33 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:33 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:33 volumio volumio[1012]: info: ------------------------------ 39ms May 16 22:15:33 volumio volumio[1012]: info: ------------------------------ 37ms May 16 22:15:33 volumio volumio[1012]: info: Listing playlists May 16 22:15:33 volumio volumio[1012]: info: Listing playlists May 16 22:15:37 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 16 22:15:37 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 16 22:15:40 volumio volumio[1012]: info: Enabling plugin peppyspectrum May 16 22:15:40 volumio volumio[1012]: info: Loading plugin "peppyspectrum"... May 16 22:15:40 volumio volumio[1012]: info: Preparing to generate the ALSA configuration file May 16 22:15:40 volumio volumio[1012]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 16 22:15:40 volumio volumio[1012]: info: Reading ALSA contributions from plugins. May 16 22:15:40 volumio volumio[1012]: info: Asound.conf file written May 16 22:15:41 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 16 22:15:41 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio volumio[1012]: info: Output device has changed, restarting MPD May 16 22:15:41 volumio sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 22:15:41 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio volumio[1012]: info: Output device has changed, restarting Shairport Sync May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:41 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio sudo[2595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 22:15:41 volumio sudo[2595]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio systemd[1]: Stopping Music Player Daemon... May 16 22:15:41 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 22:15:41 volumio volumio[1012]: info: PLUGIN START: peppyspectrum May 16 22:15:41 volumio systemd[1]: mpd.service: Succeeded. May 16 22:15:41 volumio systemd[1]: Stopped Music Player Daemon. May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 16 22:15:41 volumio volumio[1012]: info: Preparing to generate the ALSA configuration file May 16 22:15:41 volumio systemd[1]: Starting Music Player Daemon... May 16 22:15:41 volumio volumio[1012]: info: Done. May 16 22:15:41 volumio volumio[1012]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 16 22:15:41 volumio volumio[1012]: info: Reading ALSA contributions from plugins. May 16 22:15:41 volumio volumio[1012]: info: MPD Permissions set May 16 22:15:41 volumio volumio[1012]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 22:15:41 volumio sudo[2600]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 22:15:41 volumio sudo[2600]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio sudo[2600]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio volumio[1012]: info: Starting Shairport Sync May 16 22:15:41 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 22:15:41 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio volumio[1012]: info: Asound.conf file unchanged, so no further update is needed May 16 22:15:41 volumio volumio[1012]: info: Output device has changed, restarting MPD May 16 22:15:41 volumio squeezeboxserver[940]: [22:15:41.453] mDNSsearchCallback:682 [0x628670]: removing renderer (volumio) May 16 22:15:41 volumio squeezeboxserver[940]: [22:15:41.453] slimproto_close:906 [0x5cdfa8] slimproto stop for volumio May 16 22:15:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 22:15:41 volumio systemd[1]: shairport-sync.service: Succeeded. May 16 22:15:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 22:15:41 volumio sudo[2614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 16 22:15:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 22:15:41 volumio sudo[2614]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio sudo[2614]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio volumio[1012]: info: Output device has changed, restarting Shairport Sync May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:41 volumio sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 16 22:15:41 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio squeezeboxserver[940]: [22:15:41.568] DelRaopDevice:1011 [0x628670]: Raop device stopped May 16 22:15:41 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 22:15:41 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 16 22:15:41 volumio systemd[1]: mpd.service: Succeeded. May 16 22:15:41 volumio systemd[1]: Stopped Music Player Daemon. May 16 22:15:41 volumio systemd[1]: Starting Music Player Daemon... May 16 22:15:41 volumio volumio[1012]: info: MPD Permissions set May 16 22:15:41 volumio volumio[1012]: info: Shairport-Sync Started May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 16 22:15:41 volumio sudo[2624]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 16 22:15:41 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 22:15:41 volumio volumio[1012]: info: Starting Shairport Sync May 16 22:15:41 volumio sudo[2637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 16 22:15:41 volumio sudo[2637]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 16 22:15:41 volumio systemd[1]: shairport-sync.service: Succeeded. May 16 22:15:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 16 22:15:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 16 22:15:41 volumio sudo[2637]: pam_unix(sudo:session): session closed for user root May 16 22:15:41 volumio volumio[1012]: info: Shairport-Sync Started May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.702] AddRaopDevice:865 [0x628670]: AirPort Express May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.703] AddRaopDevice:929 [0x628670]: adding renderer (volumio@192.168.100.49) with mac AAAA-DF0F404D May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.703] raopcl_create:715 [0x7f726c01e9c0]: using PCM coding May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.703] output_raop_thread_init:119 [0x5cdfa8]: init output raop May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.703] output_init_common:290 allocating 1764000 May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.704] mDNSsearchCallback:746 Updating configuration /var/lib/squeezeboxserver/prefs/raopbridge.xml May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.704] slimproto:834 squeezelite [0x5cdfa8] <=> player [0x628670] May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.704] slimproto:835 [0x5cdfa8] connecting to 192.168.100.49:3483 May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.705] slimproto:865 [0x5cdfa8] connected May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.705] sendHELO:107 [0x5cdfa8] cap: CanHTTPS=1,Model=squeezelite,ModelName=RaopBridge,AccuratePlayPoints=0,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,ops,ogf,flc,alc,wav,aif,pcm,mp3 May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.710] output_flush:331 [0x5cdfa8]: flush output buffer (full) May 16 22:15:42 volumio squeezeboxserver[940]: [22:15:42.711] PlayerThread:598 [0x628670]: processing volume device:49 request:-15.31 May 16 22:15:42 volumio mpd[2634]: May 16 22:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 16 22:15:42 volumio systemd[1]: Started Music Player Daemon. May 16 22:15:42 volumio sudo[2595]: pam_unix(sudo:session): session closed for user root May 16 22:15:42 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root May 16 22:15:42 volumio volumio[1012]: error: MPD error: The expression evaluated to a falsy value: May 16 22:15:42 volumio volumio[1012]: assert.ok(self.idling) May 16 22:15:42 volumio volumio[1012]: error: The expression evaluated to a falsy value: May 16 22:15:42 volumio volumio[1012]: assert.ok(self.idling) May 16 22:15:42 volumio volumio[1012]: error: updateQueue error: null May 16 22:15:43 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:43 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:44 volumio volumio[1012]: info: CoreCommandRouter::volumioPlay May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::play index undefined May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:44 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::startPlaybackTimer May 16 22:15:44 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:44 volumio volumio[1012]: info: [1747404944133] ControllerVolusonic::resume May 16 22:15:44 volumio volumio[1012]: info: ControllerMpd::resume May 16 22:15:44 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand play May 16 22:15:44 volumio volumio[1012]: info: sendMpdCommand play took 0 milliseconds May 16 22:15:44 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:44 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:44 volumio volumio[1012]: info: sendMpdCommand status took 4 milliseconds May 16 22:15:44 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:44 volumio volumio[1012]: info: [1747404944140] ControllerVolusonic::pushState May 16 22:15:44 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:44 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:44 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:44 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:44 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:44 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 22:15:44 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus pause May 16 22:15:44 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:44 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:44 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:44 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status pause May 16 22:15:44 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status pause May 16 22:15:44 volumio sudo[2653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:44 volumio sudo[2653]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:44 volumio sudo[2653]: pam_unix(sudo:session): session closed for user root May 16 22:15:44 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:44 volumio sudo[2656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:44 volumio sudo[2656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:44 volumio sudo[2656]: pam_unix(sudo:session): session closed for user root May 16 22:15:44 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:47 volumio volumio[1012]: info: CoreCommandRouter::volumioPlay May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::play index undefined May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:47 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::startPlaybackTimer May 16 22:15:47 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:47 volumio volumio[1012]: info: [1747404947253] ControllerVolusonic::resume May 16 22:15:47 volumio volumio[1012]: info: ControllerMpd::resume May 16 22:15:47 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand play May 16 22:15:47 volumio volumio[1012]: info: sendMpdCommand play took 1 milliseconds May 16 22:15:47 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:47 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:47 volumio volumio[1012]: info: sendMpdCommand status took 0 milliseconds May 16 22:15:47 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:47 volumio volumio[1012]: info: [1747404947256] ControllerVolusonic::pushState May 16 22:15:47 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:47 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:47 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:47 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:47 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:47 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 22:15:47 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus pause May 16 22:15:47 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:47 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:47 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:47 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status pause May 16 22:15:47 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status pause May 16 22:15:47 volumio sudo[2659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:47 volumio sudo[2659]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:47 volumio sudo[2659]: pam_unix(sudo:session): session closed for user root May 16 22:15:47 volumio sudo[2662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:47 volumio sudo[2662]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:47 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:47 volumio sudo[2662]: pam_unix(sudo:session): session closed for user root May 16 22:15:47 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:48 volumio volumio[1012]: info: CoreCommandRouter::volumioPlay May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::play index undefined May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:48 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::startPlaybackTimer May 16 22:15:48 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:48 volumio volumio[1012]: info: [1747404948839] ControllerVolusonic::resume May 16 22:15:48 volumio volumio[1012]: info: ControllerMpd::resume May 16 22:15:48 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand play May 16 22:15:48 volumio volumio[1012]: info: sendMpdCommand play took 1 milliseconds May 16 22:15:48 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:48 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:48 volumio volumio[1012]: info: sendMpdCommand status took 0 milliseconds May 16 22:15:48 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:48 volumio volumio[1012]: info: [1747404948843] ControllerVolusonic::pushState May 16 22:15:48 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:48 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:48 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:48 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:48 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:48 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 22:15:48 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus pause May 16 22:15:48 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:48 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:48 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:48 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status pause May 16 22:15:48 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status pause May 16 22:15:48 volumio sudo[2665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:48 volumio sudo[2665]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:48 volumio sudo[2665]: pam_unix(sudo:session): session closed for user root May 16 22:15:48 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:48 volumio sudo[2668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:48 volumio sudo[2668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:48 volumio sudo[2668]: pam_unix(sudo:session): session closed for user root May 16 22:15:48 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioNext May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::next May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::stop May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::stPlaybackTimer May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::serviceStop May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::serviceStop May 16 22:15:51 volumio volumio[1012]: info: [1747404951437] ControllerVolusonic::stop May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::stop May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand stop May 16 22:15:51 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand stop took 32 milliseconds May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 2 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: info: [1747404951476] ControllerVolusonic::pushState May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:51 volumio sudo[2686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:51 volumio sudo[2686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:51 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 22:15:51 volumio volumio[1012]: verbose: CURRENT POSITION 0 May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:51 volumio volumio[1012]: info: No code May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:51 volumio sudo[2686]: pam_unix(sudo:session): session closed for user root May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::play index undefined May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::startPlaybackTimer May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: [1747404951533] ControllerVolusonic::clearAddPlayTrack May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand stop May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand stop took 1 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand clear May 16 22:15:51 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:51 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:51 volumio sudo[2689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:51 volumio sudo[2689]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:51 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:15:51 volumio sudo[2689]: pam_unix(sudo:session): session closed for user root May 16 22:15:51 volumio sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:51 volumio sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:51 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:51 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand clear took 95 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:51 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:51 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:51 volumio sudo[2692]: pam_unix(sudo:session): session closed for user root May 16 22:15:51 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:51 volumio sudo[2695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:15:51 volumio sudo[2695]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:51 volumio volumio[1012]: error: updateQueue error: null May 16 22:15:51 volumio volumio[1012]: error: updateQueue error: null May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 27ms May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 25ms May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:51 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand add "http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" took 1 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand play May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:51 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 3ms May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand play took 3 milliseconds May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 3ms May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 1 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 0 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:51 volumio volumio[1012]: info: [1747404951666] ControllerVolusonic::pushState May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:51 volumio volumio[1012]: verbose: CURRENT POSITION 1 May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::syncState stateService play May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:15:51 volumio sudo[2695]: pam_unix(sudo:session): session closed for user root May 16 22:15:51 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:15:51 volumio volumio[1012]: info: [1747404951686] ControllerVolusonic: MPD player state update May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:51 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 266 milliseconds May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 266 milliseconds May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 263 milliseconds May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 4ms May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:15:51 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:15:51 volumio volumio[1012]: info: May 16 22:15:51 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 5 milliseconds May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 6 milliseconds May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 6 milliseconds May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 3 milliseconds May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 4ms May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: [1747404951967] ControllerVolusonic::pushState May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"好久不见","artist":"5566","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:15:51 volumio volumio[1012]: verbose: CURRENT POSITION 1 May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::syncState stateService play May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus play May 16 22:15:51 volumio volumio[1012]: info: Received an update from plugin. extracting info from payload May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:51 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:51 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:51 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 308ms May 16 22:15:51 volumio volumio[1012]: info: ------------------------------ 306ms May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand status took 37 milliseconds May 16 22:15:51 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 35 milliseconds May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:15:51 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:15:52 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:52 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:52 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:52 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:52 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:52 volumio volumio[1012]: info: ------------------------------ 46ms May 16 22:15:52 volumio volumio[1012]: info: CoreCommandRouter::volumioGetQueue May 16 22:15:52 volumio volumio[1012]: info: CoreStateMachine::getQueue May 16 22:15:52 volumio volumio[1012]: info: CorePlayQueue::getQueue May 16 22:15:52 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status play May 16 22:15:52 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status play May 16 22:15:52 volumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 16 22:15:52 volumio sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:52 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 73 milliseconds May 16 22:15:52 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:15:52 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:15:52 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:15:52 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:52 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:15:52 volumio volumio[1012]: info: ------------------------------ 118ms May 16 22:15:52 volumio sudo[2707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 16 22:15:52 volumio sudo[2707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:15:52 volumio systemd[1]: Started peppyspectrum Daemon. May 16 22:15:52 volumio sudo[2704]: pam_unix(sudo:session): session closed for user root May 16 22:15:52 volumio volumio[1012]: info: peppyspectrum Daemon Started May 16 22:15:52 volumio sudo[2707]: pam_unix(sudo:session): session closed for user root May 16 22:15:52 volumio volumio[1012]: info: peppyspectrum Daemon Started May 16 22:15:52 volumio volumio[2708]: Traceback (most recent call last): May 16 22:15:52 volumio volumio[2708]: File "spectrum.py", line 18, in May 16 22:15:52 volumio volumio[2708]: import pygame May 16 22:15:52 volumio volumio[2708]: ModuleNotFoundError: No module named 'pygame' May 16 22:15:52 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 16 22:15:52 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 16 22:15:53 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:15:53 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:15:53 volumio volumio[1012]: info: Listing playlists May 16 22:15:53 volumio volumio[1012]: info: Listing playlists May 16 22:15:58 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:15:58 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] May 16 22:15:59 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy May 16 22:15:59 volumio volumio[1012]: aaaaaaaaaaa 800,480 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800480 May 16 22:15:59 volumio volumio[1012]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined May 16 22:15:59 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:15:59 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:15:59 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:16:00 volumio volumio[1012]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] May 16 22:16:00 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 May 16 22:16:01 volumio volumio[1012]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum -----------------------spectrum May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum -----------------------$basefolder May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum -----------------------screensize800x480 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum -----------------------screenwidth May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum -----------------------screenheight May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum -----------------------spectrumsize30 May 16 22:16:01 volumio sudo[2776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service May 16 22:16:01 volumio sudo[2776]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:01 volumio volumio[1012]: error: PeppySpectrum ---Error writing config null May 16 22:16:01 volumio systemd[1]: Started peppyspectrum Daemon. May 16 22:16:01 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:16:01 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent May 16 22:16:01 volumio sudo[2776]: pam_unix(sudo:session): session closed for user root May 16 22:16:01 volumio volumio[1012]: info: peppyspectrum Daemon Started May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:16:01 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:16:01 volumio volumio[2778]: Traceback (most recent call last): May 16 22:16:01 volumio volumio[2778]: File "spectrum.py", line 18, in May 16 22:16:01 volumio volumio[2778]: import pygame May 16 22:16:01 volumio volumio[2778]: ModuleNotFoundError: No module named 'pygame' May 16 22:16:01 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 16 22:16:01 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 16 22:16:03 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:16:03 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioNext May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::next May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::stop May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::stPlaybackTimer May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::serviceStop May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::serviceStop May 16 22:16:04 volumio volumio[1012]: info: [1747404964035] ControllerVolusonic::stop May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::stop May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand stop May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio volumio[1012]: info: [1747404964073] ControllerVolusonic: MPD player state update May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand stop took 40 milliseconds May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 8 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 8 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 8 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 2 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio sudo[2785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 4 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: [1747404964091] ControllerVolusonic::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: 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":"好久不见","artist":"5566","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:16:04 volumio volumio[1012]: verbose: CURRENT POSITION 1 May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio sudo[2785]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio volumio[1012]: info: No code May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:16:04 volumio volumio[1012]: info: [1747404964121] ControllerVolusonic::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: 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":"好久不见","artist":"5566","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=9599e43649711a5b6e113339e8630f5a&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:16:04 volumio volumio[1012]: verbose: CURRENT POSITION 1 May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: No code May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 1 May 16 22:16:04 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 130ms May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 126ms May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::play index undefined May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::startPlaybackTimer May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: [1747404964208] ControllerVolusonic::clearAddPlayTrack May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand stop May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand stop took 2 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand clear May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2788]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2788]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio sudo[2791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2791]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2791]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio sudo[2794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio sudo[2797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2797]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2797]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio sudo[2800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2800]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio sudo[2803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status stop May 16 22:16:04 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio sudo[2806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio sudo[2806]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:16:04 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand clear took 238 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.100.43:4533/rest/stream.view?id=9390183d36cb3cca37fa8803942e3334&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:16:04 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:16:04 volumio sudo[2806]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio sudo[2809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio sudo[2809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: error: updateQueue error: null May 16 22:16:04 volumio volumio[1012]: error: updateQueue error: null May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 20ms May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.43:4533/rest/stream.view?id=9390183d36cb3cca37fa8803942e3334&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 19ms May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:16:04 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand add "http://192.168.100.43:4533/rest/stream.view?id=9390183d36cb3cca37fa8803942e3334&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic" took 1 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand play May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:16:04 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 2ms May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand play took 3 milliseconds May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 2ms May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 1 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 0 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: info: [1747404964475] ControllerVolusonic::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=9390183d36cb3cca37fa8803942e3334&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.100.43:4533/rest/stream.view?id=9390183d36cb3cca37fa8803942e3334&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:16:04 volumio volumio[1012]: verbose: CURRENT POSITION 2 May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState stateService play May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop May 16 22:16:04 volumio sudo[2809]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Stop May 16 22:16:04 volumio volumio[1012]: info: [1747404964486] ControllerVolusonic: MPD player state update May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:16:04 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 231 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 230 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 228 milliseconds May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 4ms May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 3 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 3 milliseconds May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 2 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: [1747404964724] ControllerVolusonic::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"我不能随便说","artist":"Ado","album":"2025年4月份","uri":"http://192.168.100.43:4533/rest/stream.view?id=9390183d36cb3cca37fa8803942e3334&format=raw&u=admin&t=c19c05109b9a7c7a48411306869587d2&s=4nwbr&v=1.10.2&f=json&c=volusonic","trackType":""} May 16 22:16:04 volumio volumio[1012]: verbose: CURRENT POSITION 2 May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState stateService play May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus play May 16 22:16:04 volumio volumio[1012]: info: Received an update from plugin. extracting info from payload May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::pushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 267ms May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 265ms May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces system playlist update May 16 22:16:04 volumio volumio[1012]: info: Ignoring MPD Status Update May 16 22:16:04 volumio volumio[1012]: info: May 16 22:16:04 volumio volumio[1012]: ---------------------------- MPD announces state update: player May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::getState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 38 milliseconds May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 3ms May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 45ms May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::volumioGetQueue May 16 22:16:04 volumio volumio[1012]: info: CoreStateMachine::getQueue May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getQueue May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status play May 16 22:16:04 volumio volumio[1012]: info: PeppySpectrum ---peppyspectrum status play May 16 22:16:04 volumio sudo[2815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 16 22:16:04 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand status took 66 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseState May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 16 22:16:04 volumio volumio[1012]: info: sendMpdCommand playlistinfo took 6 milliseconds May 16 22:16:04 volumio volumio[1012]: verbose: ControllerMpd::parseTrackInfo May 16 22:16:04 volumio volumio[1012]: info: ControllerMpd::pushState May 16 22:16:04 volumio volumio[1012]: info: CoreCommandRouter::servicePushState May 16 22:16:04 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:04 volumio volumio[1012]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd May 16 22:16:04 volumio volumio[1012]: info: ------------------------------ 75ms May 16 22:16:04 volumio sudo[2818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service May 16 22:16:04 volumio sudo[2818]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:04 volumio systemd[1]: Started peppyspectrum Daemon. May 16 22:16:04 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Started May 16 22:16:04 volumio sudo[2818]: pam_unix(sudo:session): session closed for user root May 16 22:16:04 volumio volumio[1012]: info: peppyspectrum Daemon Started May 16 22:16:04 volumio volumio[2819]: Traceback (most recent call last): May 16 22:16:04 volumio volumio[2819]: File "spectrum.py", line 18, in May 16 22:16:04 volumio volumio[2819]: import pygame May 16 22:16:04 volumio volumio[2819]: ModuleNotFoundError: No module named 'pygame' May 16 22:16:04 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 16 22:16:04 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 16 22:16:12 volumio volumio[1012]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] May 16 22:16:12 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy May 16 22:16:12 volumio volumio[1012]: aaaaaaaaaaa 800,480 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800480 May 16 22:16:12 volumio volumio[1012]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined May 16 22:16:12 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:16:12 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:16:12 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:16:13 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:16:13 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:13 volumio volumio[1012]: info: Listing playlists May 16 22:16:13 volumio volumio[1012]: info: Listing playlists May 16 22:16:15 volumio volumio[1012]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] May 16 22:16:15 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy May 16 22:16:15 volumio volumio[1012]: aaaaaaaaaaa 320,240 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---320240 May 16 22:16:15 volumio volumio[1012]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined May 16 22:16:15 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:16:15 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:16:15 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: CALLMETHOD: user_interface peppyspectrum savepeppy1 [object Object] May 16 22:16:16 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy1 May 16 22:16:16 volumio volumio[1012]: error: Failed callmethod call: TypeError: Cannot read property 'then' of undefined May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum -----------------------spectrum May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum -----------------------$basefolder May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum -----------------------screensize320x240 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum -----------------------screenwidth May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum -----------------------screenheight May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum -----------------------spectrumsize30 May 16 22:16:16 volumio volumio[1012]: error: PeppySpectrum ---Error writing config null May 16 22:16:16 volumio sudo[2838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppyspectrum.service May 16 22:16:16 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 22:16:16 volumio systemd[1]: Started peppyspectrum Daemon. May 16 22:16:16 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root May 16 22:16:16 volumio volumio[1012]: info: peppyspectrum Daemon Started May 16 22:16:16 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:16:16 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:16:16 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:16:16 volumio volumio[2840]: Traceback (most recent call last): May 16 22:16:16 volumio volumio[2840]: File "spectrum.py", line 18, in May 16 22:16:16 volumio volumio[2840]: import pygame May 16 22:16:16 volumio volumio[2840]: ModuleNotFoundError: No module named 'pygame' May 16 22:16:16 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE May 16 22:16:16 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. May 16 22:16:23 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:16:23 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:33 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState May 16 22:16:33 volumio volumio[1012]: info: CorePlayQueue::getTrack 2 May 16 22:16:33 volumio volumio[1012]: info: Listing playlists May 16 22:16:33 volumio volumio[1012]: info: Listing playlists May 16 22:16:36 volumio volumio[1012]: info: CALLMETHOD: user_interface peppyspectrum updatelist May 16 22:16:36 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist May 16 22:16:36 volumio volumio[1012]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' May 16 22:16:39 volumio volumio[1012]: --2025-05-16 22:16:36-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt May 16 22:16:39 volumio volumio[1012]: Resolving github.com (github.com)... 20.205.243.166 May 16 22:16:39 volumio volumio[1012]: Connecting to github.com (github.com)|20.205.243.166|:443... connected. May 16 22:16:39 volumio volumio[1012]: HTTP request sent, awaiting response... 302 Found May 16 22:16:39 volumio volumio[1012]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] May 16 22:16:39 volumio volumio[1012]: --2025-05-16 22:16:37-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt May 16 22:16:39 volumio volumio[1012]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.109.133, 185.199.110.133, ... May 16 22:16:39 volumio volumio[1012]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. May 16 22:16:39 volumio volumio[1012]: HTTP request sent, awaiting response... 200 OK May 16 22:16:39 volumio volumio[1012]: Length: 471 [text/plain] May 16 22:16:39 volumio volumio[1012]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ May 16 22:16:39 volumio volumio[1012]: 0K 100% 12.1M=0s May 16 22:16:39 volumio volumio[1012]: 2025-05-16 22:16:39 (12.1 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] May 16 22:16:39 volumio volumio[1012]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 22:16:39 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---list is Random,s.1,s.2,s.3,s.4,s.5,s.6 May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-30bars-balbuze May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-custom May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1280x400+30-Gelo5 May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1280x400+99-99bars-balbuze May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1480x320+99-99bars-balbuze May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+13-Gelo5 May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+30-Gelo5 May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1920x1080+55-Gelo5 May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---1920x720+99-99bars-balbuze May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x100+99-99bars May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x46+99-99bars-balbuze May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+20-marshall May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+230-230-bars-freq May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-30bars-balbuze May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-Gelo5 May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+30-marshall May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+55-marshall May 16 22:16:39 volumio volumio[1012]: info: PeppySpectrum ---800x480+99-99bars-balbuze May 16 22:16:42 volumio volumio[1012]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 22:16:42 volumio volumio[1012]: Error: connect ETIMEDOUT 75.126.33.156:443 May 16 22:16:42 volumio volumio[1012]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 16 22:16:42 volumio volumio[1012]: errno: -110, May 16 22:16:42 volumio volumio[1012]: code: 'ETIMEDOUT', May 16 22:16:42 volumio volumio[1012]: syscall: 'connect', May 16 22:16:42 volumio volumio[1012]: address: '75.126.33.156', May 16 22:16:42 volumio volumio[1012]: port: 443 May 16 22:16:42 volumio volumio[1012]: } May 16 22:16:42 volumio volumio[1012]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 22:16:43 volumio sudo[2902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 22:15 May 16 22:16:43 volumio sudo[2902]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"