-- Logs begin at Thu 2024-10-17 10:33:32 CST, end at Thu 2024-10-17 10:39:31 CST. -- Oct 17 10:38:01 volumio-x86 mpd[2659]: Oct 17 10:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 10:38:01 volumio-x86 systemd[1]: Started Music Player Daemon. Oct 17 10:38:01 volumio-x86 sudo[2615]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:01 volumio-x86 sudo[2645]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:01 volumio-x86 volumio[859]: error: MPD error: The expression evaluated to a falsy value: Oct 17 10:38:01 volumio-x86 volumio[859]: assert.ok(self.idling) Oct 17 10:38:01 volumio-x86 volumio[859]: error: The expression evaluated to a falsy value: Oct 17 10:38:01 volumio-x86 volumio[859]: assert.ok(self.idling) Oct 17 10:38:01 volumio-x86 volumio[859]: error: updateQueue error: null Oct 17 10:38:03 volumio-x86 volumio[859]: error: Upnp client error: Error: This socket has been ended by the other party Oct 17 10:38:03 volumio-x86 volumio[859]: info: Enabling plugin peppy_screensaver Oct 17 10:38:03 volumio-x86 volumio[859]: info: Loading plugin "peppy_screensaver"... Oct 17 10:38:03 volumio-x86 volumio[859]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 17 10:38:03 volumio-x86 volumio[859]: info: Preparing to generate the ALSA configuration file Oct 17 10:38:03 volumio-x86 volumio[859]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 17 10:38:03 volumio-x86 volumio[859]: info: Reading ALSA contributions from plugins. Oct 17 10:38:03 volumio-x86 volumio[859]: info: Asound.conf file unchanged, so no further update is needed Oct 17 10:38:03 volumio-x86 volumio[859]: info: Output device has changed, restarting MPD Oct 17 10:38:03 volumio-x86 sudo[2679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 10:38:03 volumio-x86 sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:03 volumio-x86 volumio[859]: info: Output device has changed, restarting Shairport Sync Oct 17 10:38:03 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:03 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:38:03 volumio-x86 sudo[2679]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:03 volumio-x86 sudo[2682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 10:38:03 volumio-x86 sudo[2682]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:03 volumio-x86 systemd[1]: Stopping Music Player Daemon... Oct 17 10:38:04 volumio-x86 volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 10:38:04 volumio-x86 volumio[859]: info: PLUGIN START: peppy_screensaver Oct 17 10:38:04 volumio-x86 systemd[1]: mpd.service: Succeeded. Oct 17 10:38:04 volumio-x86 volumio[859]: info: Loading i18n strings for locale zh_TW Oct 17 10:38:04 volumio-x86 systemd[1]: Stopped Music Player Daemon. Oct 17 10:38:04 volumio-x86 systemd[1]: Starting Music Player Daemon... Oct 17 10:38:04 volumio-x86 volumio[859]: info: /tmp/myfifo created Oct 17 10:38:04 volumio-x86 volumio[859]: info: /tmp/myfifosa created Oct 17 10:38:04 volumio-x86 sudo[2693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 17 10:38:04 volumio-x86 sudo[2693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 sudo[2693]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:04 volumio-x86 volumio[859]: info: snd-dummy loaded Oct 17 10:38:04 volumio-x86 sudo[2687]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 10:38:04 volumio-x86 sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 volumio[859]: info: Error: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 17 10:38:04 volumio-x86 volumio[859]: info: MPD Permissions set Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 sudo[2687]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: Starting Shairport Sync Oct 17 10:38:04 volumio-x86 sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 10:38:04 volumio-x86 sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 10:38:04 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Oct 17 10:38:04 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 10:38:04 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 10:38:04 volumio-x86 sudo[2704]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:04 volumio-x86 volumio[859]: info: Shairport-Sync Started Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: Enabling plugin peppy_screensaver Oct 17 10:38:04 volumio-x86 volumio[859]: info: Loading plugin "peppy_screensaver"... Oct 17 10:38:04 volumio-x86 volumio[859]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 17 10:38:04 volumio-x86 volumio[859]: info: Preparing to generate the ALSA configuration file Oct 17 10:38:04 volumio-x86 volumio[859]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 17 10:38:04 volumio-x86 volumio[859]: info: Reading ALSA contributions from plugins. Oct 17 10:38:04 volumio-x86 volumio[859]: info: Asound.conf file unchanged, so no further update is needed Oct 17 10:38:04 volumio-x86 volumio[859]: info: Output device has changed, restarting MPD Oct 17 10:38:04 volumio-x86 volumio[859]: info: Output device has changed, restarting Shairport Sync Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:38:04 volumio-x86 sudo[2712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 10:38:04 volumio-x86 sudo[2712]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 10:38:04 volumio-x86 sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 sudo[2710]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:04 volumio-x86 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 17 10:38:04 volumio-x86 systemd[1]: mpd.service: Succeeded. Oct 17 10:38:04 volumio-x86 systemd[1]: Stopped Music Player Daemon. Oct 17 10:38:04 volumio-x86 volumio[859]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 10:38:04 volumio-x86 volumio[859]: info: PLUGIN START: peppy_screensaver Oct 17 10:38:04 volumio-x86 systemd[1]: Starting Music Player Daemon... Oct 17 10:38:04 volumio-x86 volumio[859]: info: Loading i18n strings for locale zh_TW Oct 17 10:38:04 volumio-x86 volumio[859]: info: /tmp/myfifo created Oct 17 10:38:04 volumio-x86 volumio[859]: info: /tmp/myfifosa created Oct 17 10:38:04 volumio-x86 sudo[2724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 17 10:38:04 volumio-x86 sudo[2724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 sudo[2724]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:04 volumio-x86 volumio[859]: info: snd-dummy loaded Oct 17 10:38:04 volumio-x86 volumio[859]: info: Error: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 17 10:38:04 volumio-x86 volumio[859]: info: MPD Permissions set Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 sudo[2718]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 10:38:04 volumio-x86 sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 sudo[2718]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 10:38:04 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:04 volumio-x86 volumio[859]: info: Starting Shairport Sync Oct 17 10:38:04 volumio-x86 sudo[2735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 10:38:04 volumio-x86 sudo[2735]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:38:04 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 10:38:05 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Oct 17 10:38:05 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 10:38:05 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 10:38:05 volumio-x86 sudo[2735]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:05 volumio-x86 volumio[859]: info: Shairport-Sync Started Oct 17 10:38:05 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:05 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:38:06 volumio-x86 mpd[2728]: Oct 17 10:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 10:38:06 volumio-x86 systemd[1]: Started Music Player Daemon. Oct 17 10:38:06 volumio-x86 sudo[2682]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:06 volumio-x86 sudo[2712]: pam_unix(sudo:session): session closed for user root Oct 17 10:38:06 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPlay Oct 17 10:38:06 volumio-x86 volumio[859]: info: CoreStateMachine::play index undefined Oct 17 10:38:06 volumio-x86 volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 10:38:06 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:06 volumio-x86 volumio[859]: info: CoreStateMachine::startPlaybackTimer Oct 17 10:38:06 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:06 volumio-x86 volumio[859]: verbose: ControllerMpd::clearAddPlayTracks USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac Oct 17 10:38:06 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 10:38:06 volumio-x86 volumio[859]: error: MPD error: The expression evaluated to a falsy value: Oct 17 10:38:06 volumio-x86 volumio[859]: assert.ok(self.idling) Oct 17 10:38:06 volumio-x86 volumio[859]: error: The expression evaluated to a falsy value: Oct 17 10:38:06 volumio-x86 volumio[859]: assert.ok(self.idling) Oct 17 10:38:06 volumio-x86 volumio[859]: error: MPD error: The expression evaluated to a falsy value: Oct 17 10:38:06 volumio-x86 volumio[859]: assert.ok(self.idling) Oct 17 10:38:06 volumio-x86 volumio[859]: error: The expression evaluated to a falsy value: Oct 17 10:38:06 volumio-x86 volumio[859]: assert.ok(self.idling) Oct 17 10:38:06 volumio-x86 volumio[859]: error: updateQueue error: null Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPlay Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::play index undefined Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::startPlaybackTimer Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::clearAddPlayTracks USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand stop took 1 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces system playlist update Oct 17 10:38:11 volumio-x86 volumio[859]: info: Ignoring MPD Status Update Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand clear took 1 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand add "USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac" Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces system playlist update Oct 17 10:38:11 volumio-x86 volumio[859]: info: Ignoring MPD Status Update Oct 17 10:38:11 volumio-x86 volumio[859]: error: updateQueue error: null Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces system playlist update Oct 17 10:38:11 volumio-x86 volumio[859]: info: Ignoring MPD Status Update Oct 17 10:38:11 volumio-x86 volumio[859]: info: ------------------------------ 4ms Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand add "USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac" took 3 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: info: ------------------------------ 3ms Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand play Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces system playlist update Oct 17 10:38:11 volumio-x86 volumio[859]: info: Ignoring MPD Status Update Oct 17 10:38:11 volumio-x86 volumio[859]: info: ------------------------------ 19ms Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand play took 18 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: info: ------------------------------ 19ms Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces state update: player Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::getState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces state update: player Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::getState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand status took 13 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces state update: player Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::getState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:38:11 volumio-x86 volumio[859]: info: Oct 17 10:38:11 volumio-x86 volumio[859]: ---------------------------- MPD announces state update: player Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::getState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand status took 19 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::pushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"那年的情书","artist":"孙露","album":"《原来的我》","uri":"USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac","trackType":"flac"} Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: CURRENT POSITION 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::syncState stateService play Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::syncState currentStatus stop Oct 17 10:38:11 volumio-x86 volumio[859]: info: ------------------------------ 27ms Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand status took 8 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand status took 5 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseState Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::pushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"那年的情书","artist":"孙露","album":"《原来的我》","uri":"USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac","trackType":"flac"} Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: CURRENT POSITION 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::syncState stateService play Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:38:11 volumio-x86 volumio[859]: info: Received an update from plugin. extracting info from payload Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: ------------------------------ 79ms Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand playlistinfo took 53 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:38:11 volumio-x86 volumio[859]: info: ControllerMpd::pushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"472 Kbps","isStreaming":false,"title":"那年的情书","artist":"孙露","album":"《原来的我》","uri":"USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac","trackType":"flac"} Oct 17 10:38:11 volumio-x86 volumio[859]: verbose: CURRENT POSITION 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::syncState stateService play Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:38:11 volumio-x86 volumio[859]: info: Received an update from plugin. extracting info from payload Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:38:11 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:38:11 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: ControllerMpd::pushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:12 volumio-x86 volumio[859]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"472 Kbps","isStreaming":false,"title":"那年的情书","artist":"孙露","album":"《原来的我》","uri":"USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac","trackType":"flac"} Oct 17 10:38:12 volumio-x86 volumio[859]: verbose: CURRENT POSITION 10 Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreStateMachine::syncState stateService play Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:38:12 volumio-x86 volumio[859]: info: Received an update from plugin. extracting info from payload Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:38:12 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:38:12 volumio-x86 volumio[859]: info: ------------------------------ 172ms Oct 17 10:38:12 volumio-x86 volumio[859]: info: ------------------------------ 169ms Oct 17 10:38:50 volumio-x86 volumiologrotate[627]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Oct 17 10:38:50 volumio-x86 volumiologrotate[627]: ls: cannot access 'X86': No such file or directory Oct 17 10:39:13 volumio-x86 volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 10:39:13 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:39:19 volumio-x86 volumio[859]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Oct 17 10:39:19 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Oct 17 10:39:19 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:39:19 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:39:19 volumio-x86 volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 10:39:19 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:39:20 volumio-x86 ntpd[1233]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Oct 17 10:39:28 volumio-x86 volumio[859]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Oct 17 10:39:28 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Oct 17 10:39:28 volumio-x86 volumio[859]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 10:39:28 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::pause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::servicePause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePause Oct 17 10:39:31 volumio-x86 volumio[859]: info: ControllerMpd::pause Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand pause Oct 17 10:39:31 volumio-x86 volumio[859]: info: sendMpdCommand pause took 1 milliseconds Oct 17 10:39:31 volumio-x86 volumio[859]: info: Oct 17 10:39:31 volumio-x86 volumio[859]: ---------------------------- MPD announces state update: player Oct 17 10:39:31 volumio-x86 volumio[859]: info: ControllerMpd::getState Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:39:31 volumio-x86 volumio[859]: info: Oct 17 10:39:31 volumio-x86 volumio[859]: ---------------------------- MPD announces state update: player Oct 17 10:39:31 volumio-x86 volumio[859]: info: ControllerMpd::getState Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:39:31 volumio-x86 volumio[859]: info: sendMpdCommand status took 2 milliseconds Oct 17 10:39:31 volumio-x86 volumio[859]: info: sendMpdCommand status took 1 milliseconds Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::parseState Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::parseState Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:39:31 volumio-x86 volumio[859]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 17 10:39:31 volumio-x86 volumio[859]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:39:31 volumio-x86 volumio[859]: info: ControllerMpd::pushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79955,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"810 Kbps","isStreaming":false,"title":"那年的情书","artist":"孙露","album":"《原来的我》","uri":"USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac","trackType":"flac"} Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: CURRENT POSITION 10 Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::syncState stateService pause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::syncState currentStatus pause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:39:31 volumio-x86 volumio[859]: info: ControllerMpd::pushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::servicePushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":79955,"duration":256,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"810 Kbps","isStreaming":false,"title":"那年的情书","artist":"孙露","album":"《原来的我》","uri":"USB/Windows/Music/孙露(flc 合輯)/孙露-那年的情书.flac","trackType":"flac"} Oct 17 10:39:31 volumio-x86 volumio[859]: verbose: CURRENT POSITION 10 Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::syncState stateService pause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::syncState currentStatus pause Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::pushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CorePlayQueue::getTrack 10 Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreCommandRouter::volumioPushState Oct 17 10:39:31 volumio-x86 volumio[859]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:39:31 volumio-x86 volumio[859]: info: ------------------------------ 63ms Oct 17 10:39:31 volumio-x86 volumio[859]: info: ------------------------------ 70ms Oct 17 10:39:31 volumio-x86 volumio[859]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 10:39:31 volumio-x86 volumio[859]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 17 10:39:31 volumio-x86 volumio[859]: at Object.readdirSync (fs.js:1048:3) Oct 17 10:39:31 volumio-x86 volumio[859]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 17 10:39:31 volumio-x86 volumio[859]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 17 10:39:31 volumio-x86 volumio[859]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 17 10:39:31 volumio-x86 volumio[859]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 17 10:39:31 volumio-x86 volumio[859]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 17 10:39:31 volumio-x86 volumio[859]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 10:39:31 volumio-x86 volumio[859]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 17 10:39:31 volumio-x86 volumio[859]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 17 10:39:31 volumio-x86 volumio[859]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 17 10:39:31 volumio-x86 volumio[859]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 10:39:31 volumio-x86 volumio[859]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 17 10:39:31 volumio-x86 volumio[859]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 17 10:39:31 volumio-x86 volumio[859]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 10:39:31 volumio-x86 volumio[859]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 17 10:39:31 volumio-x86 volumio[859]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 17 10:39:31 volumio-x86 volumio[859]: errno: -20, Oct 17 10:39:31 volumio-x86 volumio[859]: syscall: 'scandir', Oct 17 10:39:31 volumio-x86 volumio[859]: code: 'ENOTDIR', Oct 17 10:39:31 volumio-x86 volumio[859]: path: '/tmp/peppyrunning' Oct 17 10:39:31 volumio-x86 volumio[859]: } Oct 17 10:39:31 volumio-x86 volumio[859]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 10:39:31 volumio-x86 sudo[2833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 10:38 Oct 17 10:39:31 volumio-x86 sudo[2833]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"