-- Logs begin at Fri 2024-04-05 10:23:54 UTC, end at Fri 2024-04-05 10:53:40 UTC. -- Apr 05 10:52:00 volumio volumio[6145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Apr 05 10:52:00 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:00 volumio volumio[6145]: info: CorePlayQueue::getTrack 0 Apr 05 10:52:10 volumio volumio[6145]: info: CoreCommandRouter::volumioPlay Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::play index 9 Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::stop Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::stPlaybackTimer Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::updateTrackBlock Apr 05 10:52:10 volumio volumio[6145]: info: CorePlayQueue::getTrackBlock Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:10 volumio volumio[6145]: info: CorePlayQueue::getTrack 0 Apr 05 10:52:10 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:52:10 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:10 volumio volumio[6145]: info: CoreStateMachine::serviceStop Apr 05 10:52:10 volumio volumio[6145]: info: CorePlayQueue::getTrack 0 Apr 05 10:52:10 volumio volumio[6145]: info: CoreCommandRouter::serviceStop Apr 05 10:52:10 volumio volumio[6145]: info: ControllerMpd::stop Apr 05 10:52:10 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand stop took 155 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::play index undefined Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::startPlaybackTimer Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::clearAddPlayTracks USB/0005-7451/003 大地 (DJ版).mp3 Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand status took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand stop took 1 milliseconds Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces system playlist update Apr 05 10:52:11 volumio volumio[6145]: info: Ignoring MPD Status Update Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand clear took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand status took 1 milliseconds Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand add "USB/0005-7451/003 大地 (DJ版).mp3" Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: 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":"001 (夜舞).mp3","artist":"沐风音乐工作室","album":"沐风音乐汇","uri":"USB/0005-7451/001 (夜舞).mp3","trackType":"mp3"} Apr 05 10:52:11 volumio volumio[6145]: verbose: CURRENT POSITION 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState stateService stop Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus stop Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: No code Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: 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} Apr 05 10:52:11 volumio volumio[6145]: verbose: CURRENT POSITION 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState stateService stop Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus stop Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: No code Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 28ms Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 24ms Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces system playlist update Apr 05 10:52:11 volumio volumio[6145]: info: Ignoring MPD Status Update Apr 05 10:52:11 volumio volumio[6145]: error: updateQueue error: null Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces system playlist update Apr 05 10:52:11 volumio volumio[6145]: info: Ignoring MPD Status Update Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 25ms Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand add "USB/0005-7451/003 大地 (DJ版).mp3" took 24 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 3ms Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand play Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces system playlist update Apr 05 10:52:11 volumio volumio[6145]: info: Ignoring MPD Status Update Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 17ms Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand play took 17 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 16ms Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand status took 3 milliseconds Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand status took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand status took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":384,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"大地 (DJ版)","artist":"沐风音乐汇","album":"沐风音乐工作室","uri":"USB/0005-7451/003 大地 (DJ版).mp3","trackType":"mp3"} Apr 05 10:52:11 volumio volumio[6145]: verbose: CURRENT POSITION 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState stateService play Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus stop Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 7ms Apr 05 10:52:11 volumio volumio[6145]: info: Apr 05 10:52:11 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand status took 1 milliseconds Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":384,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"大地 (DJ版)","artist":"沐风音乐汇","album":"沐风音乐工作室","uri":"USB/0005-7451/003 大地 (DJ版).mp3","trackType":"mp3"} Apr 05 10:52:11 volumio volumio[6145]: verbose: CURRENT POSITION 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState stateService play Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus play Apr 05 10:52:11 volumio volumio[6145]: info: Received an update from plugin. extracting info from payload Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":384,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"大地 (DJ版)","artist":"沐风音乐汇","album":"沐风音乐工作室","uri":"USB/0005-7451/003 大地 (DJ版).mp3","trackType":"mp3"} Apr 05 10:52:11 volumio volumio[6145]: verbose: CURRENT POSITION 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState stateService play Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus play Apr 05 10:52:11 volumio volumio[6145]: info: Received an update from plugin. extracting info from payload Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 23ms Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 22ms Apr 05 10:52:11 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 05 10:52:11 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:52:11 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":384,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"大地 (DJ版)","artist":"沐风音乐汇","album":"沐风音乐工作室","uri":"USB/0005-7451/003 大地 (DJ版).mp3","trackType":"mp3"} Apr 05 10:52:11 volumio volumio[6145]: verbose: CURRENT POSITION 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState stateService play Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus play Apr 05 10:52:11 volumio volumio[6145]: info: Received an update from plugin. extracting info from payload Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:52:11 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:11 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:52:11 volumio volumio[6145]: info: ------------------------------ 25ms Apr 05 10:52:12 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:12 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:13 volumio volumio[6145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Apr 05 10:52:13 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:13 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:36 volumio volumio[6145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 05 10:52:41 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:41 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:42 volumio volumio[6145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Apr 05 10:52:42 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:42 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:50 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 05 10:52:51 volumio volumio[6145]: info: CoreCommandRouter::Close All Modals sent Apr 05 10:52:54 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:54 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:52:55 volumio volumio[6145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 6 Apr 05 10:52:55 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:52:55 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:53:06 volumio volumio[6145]: info: CoreStateMachine::stop Apr 05 10:53:06 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:53:06 volumio volumio[6145]: info: CoreStateMachine::stPlaybackTimer Apr 05 10:53:06 volumio volumio[6145]: info: CoreStateMachine::updateTrackBlock Apr 05 10:53:06 volumio volumio[6145]: info: CorePlayQueue::getTrackBlock Apr 05 10:53:06 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:53:06 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:53:06 volumio volumio[6145]: info: CoreStateMachine::serviceStop Apr 05 10:53:06 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::serviceStop Apr 05 10:53:06 volumio volumio[6145]: info: ControllerMpd::stop Apr 05 10:53:06 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 10:53:06 volumio volumio[6145]: info: Disabling plugin peppy_screensaver Apr 05 10:53:06 volumio volumio[6145]: info: Preparing to generate the ALSA configuration file Apr 05 10:53:06 volumio volumio[6145]: info: Asound.conf file written Apr 05 10:53:06 volumio sudo[6791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 05 10:53:06 volumio sudo[6791]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:06 volumio sudo[6791]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:06 volumio volumio[6145]: info: Output device has changed, restarting MPD Apr 05 10:53:06 volumio sudo[6796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 10:53:06 volumio sudo[6796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:06 volumio sudo[6796]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:06 volumio volumio[6145]: info: Output device has changed, restarting Shairport Sync Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:06 volumio sudo[6799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 10:53:06 volumio sudo[6799]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:06 volumio systemd[1]: Stopping Music Player Daemon... Apr 05 10:53:06 volumio volumio[6145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 10:53:06 volumio volumio[6145]: info: Done. Apr 05 10:53:06 volumio volumio[6145]: info: MPD Permissions set Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: Apr 05 10:53:06 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:53:06 volumio volumio[6145]: info: sendMpdCommand stop took 119 milliseconds Apr 05 10:53:06 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:53:06 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:53:06 volumio volumio[6145]: error: MPD error: Error: write EPIPE Apr 05 10:53:06 volumio volumio[6145]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 05 10:53:06 volumio systemd[1]: mpd.service: Succeeded. Apr 05 10:53:06 volumio systemd[1]: Stopped Music Player Daemon. Apr 05 10:53:06 volumio systemd[1]: Starting Music Player Daemon... Apr 05 10:53:06 volumio volumio[6145]: info: peppy_screensaver: air template already unmounted Apr 05 10:53:06 volumio volumio[6145]: info: Apr 05 10:53:06 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:53:06 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:53:06 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:53:06 volumio volumio[6145]: error: MPD error: Error: write EPIPE Apr 05 10:53:06 volumio volumio[6145]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Apr 05 10:53:06 volumio volumio[6145]: info: Starting Shairport Sync Apr 05 10:53:06 volumio sudo[6815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Apr 05 10:53:06 volumio sudo[6815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:06 volumio sudo[6815]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:06 volumio systemd[1]: volumio-app-plugins-music_service-mpd-mpd.conf.tmpl.mount: Succeeded. Apr 05 10:53:06 volumio sudo[6818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 10:53:06 volumio sudo[6818]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Apr 05 10:53:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 05 10:53:06 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 05 10:53:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 05 10:53:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 10:53:06 volumio sudo[6818]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio sudo[6822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 10:53:06 volumio sudo[6822]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:06 volumio sudo[6822]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 05 10:53:06 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:06 volumio volumio[6145]: info: MPD Permissions set Apr 05 10:53:06 volumio volumio[6145]: info: Shairport-Sync Started Apr 05 10:53:07 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:53:07 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:53:07 volumio mpd[6831]: Apr 05 10:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 10:53:07 volumio mpd[6831]: Apr 05 10:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 05 10:53:07 volumio mpd[6831]: Apr 05 10:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 05 10:53:07 volumio systemd[1]: Started Music Player Daemon. Apr 05 10:53:07 volumio sudo[6799]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:07 volumio volumio[6145]: error: updateQueue error: null Apr 05 10:53:07 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:07 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:07 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 05 10:53:07 volumio sudo[6844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 10:53:07 volumio sudo[6844]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:07 volumio systemd[1]: Stopping Music Player Daemon... Apr 05 10:53:07 volumio systemd[1]: mpd.service: Succeeded. Apr 05 10:53:07 volumio systemd[1]: Stopped Music Player Daemon. Apr 05 10:53:07 volumio systemd[1]: Starting Music Player Daemon... Apr 05 10:53:07 volumio mpd[6848]: Apr 05 10:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 10:53:07 volumio mpd[6848]: Apr 05 10:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 05 10:53:07 volumio mpd[6848]: Apr 05 10:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 05 10:53:07 volumio systemd[1]: Started Music Player Daemon. Apr 05 10:53:07 volumio sudo[6844]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:07 volumio volumio[6145]: error: updateQueue error: null Apr 05 10:53:07 volumio volumio[6145]: info: Enabling plugin peppy_screensaver Apr 05 10:53:07 volumio volumio[6145]: info: Loading plugin "peppy_screensaver"... Apr 05 10:53:07 volumio volumio[6145]: info: Applying required configuration parameters for plugin peppy_screensaver Apr 05 10:53:07 volumio volumio[6145]: info: Preparing to generate the ALSA configuration file Apr 05 10:53:07 volumio volumio[6145]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Apr 05 10:53:07 volumio volumio[6145]: info: Reading ALSA contributions from plugins. Apr 05 10:53:07 volumio volumio[6145]: info: Asound.conf file written Apr 05 10:53:07 volumio sudo[6852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 05 10:53:07 volumio sudo[6852]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:07 volumio sudo[6852]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:07 volumio volumio[6145]: info: Output device has changed, restarting MPD Apr 05 10:53:08 volumio sudo[6857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 10:53:08 volumio sudo[6857]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio sudo[6857]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: info: Output device has changed, restarting Shairport Sync Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:08 volumio sudo[6860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 10:53:08 volumio sudo[6860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio systemd[1]: Stopping Music Player Daemon... Apr 05 10:53:08 volumio systemd[1]: mpd.service: Succeeded. Apr 05 10:53:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 05 10:53:08 volumio systemd[1]: Starting Music Player Daemon... Apr 05 10:53:08 volumio volumio[6145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 10:53:08 volumio volumio[6145]: info: PLUGIN START: peppy_screensaver Apr 05 10:53:08 volumio volumio[6145]: info: Loading i18n strings for locale zh Apr 05 10:53:08 volumio volumio[6145]: info: /tmp/myfifo created Apr 05 10:53:08 volumio sudo[6870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 Apr 05 10:53:08 volumio sudo[6870]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio sudo[6870]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: info: snd-dummy loaded Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 05 10:53:08 volumio volumio[6145]: info: Preparing to generate the ALSA configuration file Apr 05 10:53:08 volumio volumio[6145]: info: Done. Apr 05 10:53:08 volumio volumio[6145]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Apr 05 10:53:08 volumio volumio[6145]: info: Reading ALSA contributions from plugins. Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:53:08 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:53:08 volumio volumio[6145]: info: MPD Permissions set Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: Starting Shairport Sync Apr 05 10:53:08 volumio volumio[6145]: info: Asound.conf file unchanged, so no further update is needed Apr 05 10:53:08 volumio volumio[6145]: info: Output device has changed, restarting MPD Apr 05 10:53:08 volumio sudo[6883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 10:53:08 volumio sudo[6883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 05 10:53:08 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 05 10:53:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 05 10:53:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 10:53:08 volumio sudo[6886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 10:53:08 volumio sudo[6886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio sudo[6883]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: info: Output device has changed, restarting Shairport Sync Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:08 volumio sudo[6886]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio sudo[6890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 10:53:08 volumio sudo[6890]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 05 10:53:08 volumio systemd[1]: mpd.service: Succeeded. Apr 05 10:53:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 05 10:53:08 volumio systemd[1]: Starting Music Player Daemon... Apr 05 10:53:08 volumio volumio[6145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 05 10:53:08 volumio volumio[6145]: info: MPD Permissions set Apr 05 10:53:08 volumio volumio[6145]: info: Shairport-Sync Started Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio sudo[6902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /data/configuration/miscellanea/peppy_screensaver/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Apr 05 10:53:08 volumio sudo[6902]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio sudo[6902]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Apr 05 10:53:08 volumio volumio[6145]: info: Starting Shairport Sync Apr 05 10:53:08 volumio sudo[6913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 05 10:53:08 volumio sudo[6913]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio sudo[6913]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: info: MPD Permissions set Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio sudo[6916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 05 10:53:08 volumio sudo[6916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 05 10:53:08 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 05 10:53:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 05 10:53:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 05 10:53:08 volumio sudo[6916]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: Shairport-Sync Started Apr 05 10:53:08 volumio mpd[6911]: Apr 05 10:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 10:53:08 volumio mpd[6911]: Apr 05 10:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 05 10:53:08 volumio mpd[6911]: Apr 05 10:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 05 10:53:08 volumio systemd[1]: Started Music Player Daemon. Apr 05 10:53:08 volumio sudo[6860]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio sudo[6890]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:08 volumio volumio[6145]: error: MPD error: The expression evaluated to a falsy value: Apr 05 10:53:08 volumio volumio[6145]: assert.ok(self.idling) Apr 05 10:53:08 volumio volumio[6145]: error: The expression evaluated to a falsy value: Apr 05 10:53:08 volumio volumio[6145]: assert.ok(self.idling) Apr 05 10:53:08 volumio volumio[6145]: error: updateQueue error: null Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 05 10:53:08 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 05 10:53:08 volumio sudo[6930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 05 10:53:08 volumio sudo[6930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 05 10:53:08 volumio systemd[1]: Stopping Music Player Daemon... Apr 05 10:53:08 volumio systemd[1]: mpd.service: Succeeded. Apr 05 10:53:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 05 10:53:08 volumio systemd[1]: Starting Music Player Daemon... Apr 05 10:53:09 volumio mpd[6934]: Apr 05 10:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 05 10:53:09 volumio mpd[6934]: Apr 05 10:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 05 10:53:09 volumio mpd[6934]: Apr 05 10:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 05 10:53:09 volumio systemd[1]: Started Music Player Daemon. Apr 05 10:53:09 volumio sudo[6930]: pam_unix(sudo:session): session closed for user root Apr 05 10:53:09 volumio volumio[6145]: error: updateQueue error: null Apr 05 10:53:10 volumio volumio[6145]: info: Disabling plugin autostart Apr 05 10:53:10 volumio volumio[6145]: info: Done. Apr 05 10:53:11 volumio volumio[6145]: info: Enabling plugin autostart Apr 05 10:53:11 volumio volumio[6145]: info: Loading plugin "autostart"... Apr 05 10:53:11 volumio volumio[6145]: info: Applying required configuration parameters for plugin autostart Apr 05 10:53:11 volumio volumio[6145]: info: PLUGIN START: autostart Apr 05 10:53:11 volumio volumio[6145]: info: Done. Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 9 Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::volumioNext Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::next Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::stop Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::play index undefined Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::startPlaybackTimer Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::clearAddPlayTracks USB/0005-7451/01.(重金修改)抖音热播DJ歌曲400首/252 失忆(DJR7版) 吕口口.mp3 Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand stop Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::updateTrackBlock Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrackBlock Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand stop took 2 milliseconds Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand clear Apr 05 10:53:18 volumio volumio[6145]: info: Apr 05 10:53:18 volumio volumio[6145]: ---------------------------- MPD announces system playlist update Apr 05 10:53:18 volumio volumio[6145]: info: Ignoring MPD Status Update Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand clear took 0 milliseconds Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand add "USB/0005-7451/01.(重金修改)抖音热播DJ歌曲400首/252 失忆(DJR7版) 吕口口.mp3" Apr 05 10:53:18 volumio volumio[6145]: error: updateQueue error: null Apr 05 10:53:18 volumio volumio[6145]: info: Apr 05 10:53:18 volumio volumio[6145]: ---------------------------- MPD announces system playlist update Apr 05 10:53:18 volumio volumio[6145]: info: Ignoring MPD Status Update Apr 05 10:53:18 volumio volumio[6145]: info: ------------------------------ 1ms Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand add "USB/0005-7451/01.(重金修改)抖音热播DJ歌曲400首/252 失忆(DJR7版) 吕口口.mp3" took 0 milliseconds Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand play Apr 05 10:53:18 volumio volumio[6145]: info: ------------------------------ 1ms Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand play took 1 milliseconds Apr 05 10:53:18 volumio volumio[6145]: info: Apr 05 10:53:18 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:53:18 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:53:18 volumio volumio[6145]: info: Apr 05 10:53:18 volumio volumio[6145]: ---------------------------- MPD announces state update: player Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand status took 19 milliseconds Apr 05 10:53:18 volumio volumio[6145]: info: ControllerMpd::getState Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand status Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand status took 2 milliseconds Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::parseState Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:53:18 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:18 volumio volumio[6145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"252 失忆(DJR7版) 吕口口.mp3","artist":"沐风音乐工作室","album":"沐风音乐汇","uri":"USB/0005-7451/01.(重金修改)抖音热播DJ歌曲400首/252 失忆(DJR7版) 吕口口.mp3","trackType":"mp3"} Apr 05 10:53:18 volumio volumio[6145]: verbose: CURRENT POSITION 151 Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::syncState stateService play Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus stop Apr 05 10:53:18 volumio volumio[6145]: info: ------------------------------ 23ms Apr 05 10:53:18 volumio volumio[6145]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 05 10:53:18 volumio volumio[6145]: verbose: ControllerMpd::parseTrackInfo Apr 05 10:53:18 volumio volumio[6145]: info: ControllerMpd::pushState Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::servicePushState Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:18 volumio volumio[6145]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":246,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"252 失忆(DJR7版) 吕口口.mp3","artist":"沐风音乐工作室","album":"沐风音乐汇","uri":"USB/0005-7451/01.(重金修改)抖音热播DJ歌曲400首/252 失忆(DJR7版) 吕口口.mp3","trackType":"mp3"} Apr 05 10:53:18 volumio volumio[6145]: verbose: CURRENT POSITION 151 Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::syncState stateService play Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::syncState currentStatus play Apr 05 10:53:18 volumio volumio[6145]: info: Received an update from plugin. extracting info from payload Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:53:18 volumio volumio[6145]: info: CoreStateMachine::pushState Apr 05 10:53:18 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:18 volumio volumio[6145]: info: CoreCommandRouter::volumioPushState Apr 05 10:53:18 volumio volumio[6145]: info: ------------------------------ 13ms Apr 05 10:53:21 volumio volumio[6145]: info: AutoStart - getting queue Apr 05 10:53:21 volumio volumio[6145]: info: CoreCommandRouter::volumioGetQueue Apr 05 10:53:21 volumio volumio[6145]: info: CoreStateMachine::getQueue Apr 05 10:53:21 volumio volumio[6145]: info: CorePlayQueue::getQueue Apr 05 10:53:21 volumio volumio[6145]: info: AutoStart - start playing -> queue is not empty Apr 05 10:53:21 volumio volumio[6145]: info: CoreCommandRouter::volumioPlay Apr 05 10:53:21 volumio volumio[6145]: info: CoreStateMachine::play index undefined Apr 05 10:53:21 volumio volumio[6145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 05 10:53:21 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:29 volumio volumio[6145]: info: CoreCommandRouter::volumioGetState Apr 05 10:53:29 volumio volumio[6145]: info: CorePlayQueue::getTrack 151 Apr 05 10:53:40 volumio volumio[6145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 10:53:40 volumio volumio[6145]: Error: connect ETIMEDOUT 202.160.130.66:443 Apr 05 10:53:40 volumio volumio[6145]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Apr 05 10:53:40 volumio volumio[6145]: errno: -110, Apr 05 10:53:40 volumio volumio[6145]: code: 'ETIMEDOUT', Apr 05 10:53:40 volumio volumio[6145]: syscall: 'connect', Apr 05 10:53:40 volumio volumio[6145]: address: '202.160.130.66', Apr 05 10:53:40 volumio volumio[6145]: port: 443 Apr 05 10:53:40 volumio volumio[6145]: } Apr 05 10:53:40 volumio volumio[6145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 05 10:53:40 volumio sudo[6983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-05 10:52 Apr 05 10:53:40 volumio sudo[6983]: 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="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 06:13:59 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6a6571e2ab129da64c127254a7a779b9"