-- Logs begin at Sun 2024-10-13 06:22:52 CST, end at Tue 2024-10-22 19:04:05 CST. -- Oct 22 11:17:01 volumio CRON[30840]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 11:17:01 volumio CRON[30841]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 11:17:01 volumio CRON[30840]: pam_unix(cron:session): session closed for user root Oct 22 11:30:01 volumio CRON[30937]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 11:30:01 volumio CRON[30936]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 11:30:01 volumio CRON[30939]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 11:30:01 volumio CRON[30938]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 11:30:01 volumio CRON[30936]: pam_unix(cron:session): session closed for user root Oct 22 11:30:01 volumio cron[559]: 2024-10-22 11:30:01 1t35ar-000834-5E Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 11:30:01 volumio cron[559]: 2024-10-22 11:30:01 1t35ar-000834-5E <= root@volumio U=root P=local S=580 Oct 22 11:30:01 volumio cron[559]: 2024-10-22 11:30:01 1t35ar-000834-5E Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 11:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 11:30:01 volumio exim[30942]: 2024-10-22 11:30:01 1t35ar-000834-5E <= root@volumio U=root P=local S=580 Oct 22 11:30:01 volumio exim[30942]: 2024-10-22 11:30:01 1t35ar-000834-5E Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 11:30:01 volumio exim[30942]: exim: could not open panic log - aborting: see message(s) above Oct 22 11:30:01 volumio CRON[30937]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 11:30:01 volumio CRON[30937]: pam_unix(cron:session): session closed for user root Oct 22 11:32:05 volumio systemd[1]: Started Run anacron jobs. Oct 22 11:32:05 volumio anacron[30970]: Anacron 2.3 started on 2024-10-22 Oct 22 11:32:05 volumio anacron[30970]: Normal exit (0 jobs run) Oct 22 11:32:05 volumio systemd[1]: anacron.service: Succeeded. Oct 22 12:00:01 volumio CRON[31178]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 12:00:01 volumio CRON[31179]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 12:00:01 volumio cron[559]: 2024-10-22 12:00:01 1t363t-00086x-7W Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:00:01 volumio cron[559]: 2024-10-22 12:00:01 1t363t-00086x-7W <= root@volumio U=root P=local S=580 Oct 22 12:00:01 volumio cron[559]: 2024-10-22 12:00:01 1t363t-00086x-7W Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:00:01 volumio exim[31183]: 2024-10-22 12:00:01 1t363t-00086x-7W <= root@volumio U=root P=local S=580 Oct 22 12:00:01 volumio exim[31183]: 2024-10-22 12:00:01 1t363t-00086x-7W Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:00:01 volumio exim[31183]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:00:01 volumio CRON[31178]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 12:00:01 volumio CRON[31178]: pam_unix(cron:session): session closed for user root Oct 22 12:17:01 volumio CRON[31324]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 12:17:01 volumio CRON[31325]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 12:17:01 volumio CRON[31324]: pam_unix(cron:session): session closed for user root Oct 22 12:29:01 volumio CRON[31418]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 12:29:01 volumio CRON[31417]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 12:29:01 volumio CRON[31420]: (root) CMD (/Easeware/shell/play_music ) Oct 22 12:29:01 volumio CRON[31419]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioGetState Oct 22 12:29:01 volumio cron[559]: 2024-10-22 12:29:01 1t36Vx-0008Av-Aq Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:29:01 volumio cron[559]: 2024-10-22 12:29:01 1t36Vx-0008Av-Aq <= root@volumio U=root P=local S=580 Oct 22 12:29:01 volumio cron[559]: 2024-10-22 12:29:01 1t36Vx-0008Av-Aq Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:29:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:29:01 volumio exim[31429]: 2024-10-22 12:29:01 1t36Vx-0008Av-Aq <= root@volumio U=root P=local S=580 Oct 22 12:29:01 volumio exim[31429]: 2024-10-22 12:29:01 1t36Vx-0008Av-Aq Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:29:01 volumio exim[31429]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:29:01 volumio CRON[31417]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 12:29:01 volumio CRON[31417]: pam_unix(cron:session): session closed for user root Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 101 Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- Client requests Volume 70 Oct 22 12:29:01 volumio volumio[6135]: info: VolumeController::SetAlsaVolume70 Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 101 Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- Client requests Volumio next Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioNext Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::next Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 12:29:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::play index undefined Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 822 Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 822 Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::clearAddPlayTracks NAS/AD/Avril Lavigne - When You're Gone.mp3 Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand stop took 7 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:29:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand clear took 2 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/Avril Lavigne - When You're Gone.mp3" Oct 22 12:29:01 volumio volumio[6135]: error: updateQueue error: null Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:29:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 4ms Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/Avril Lavigne - When You're Gone.mp3" took 2 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand play Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand play took 2 milliseconds Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- Client requests Volumio next Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioNext Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::next Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 12:29:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::play index undefined Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::clearAddPlayTracks NAS/AD/古风/en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』(動態歌詞Lyrics).m4a Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand stop took 13 milliseconds Oct 22 12:29:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:29:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand clear took 3 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/古风/en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』(動態歌詞Lyrics).m4a" Oct 22 12:29:01 volumio volumio[6135]: error: updateQueue error: null Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:29:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 6ms Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/古风/en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』(動態歌詞Lyrics).m4a" took 3 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand play Oct 22 12:29:01 volumio volumio[6135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 12ms Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 6ms Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand play took 3 milliseconds Oct 22 12:29:01 volumio cron[559]: 2024-10-22 12:29:01 1t36Vx-0008B5-IZ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:29:01 volumio cron[559]: 2024-10-22 12:29:01 1t36Vx-0008B5-IZ <= root@volumio U=root P=local S=1652 Oct 22 12:29:01 volumio cron[559]: 2024-10-22 12:29:01 1t36Vx-0008B5-IZ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:29:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:29:01 volumio exim[31439]: 2024-10-22 12:29:01 1t36Vx-0008B5-IZ <= root@volumio U=root P=local S=1652 Oct 22 12:29:01 volumio exim[31439]: 2024-10-22 12:29:01 1t36Vx-0008B5-IZ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:29:01 volumio exim[31439]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:29:01 volumio CRON[31418]: (root) MAIL (mailed 1097 bytes of output but got status 0x0001 from MTA ) Oct 22 12:29:01 volumio CRON[31418]: pam_unix(cron:session): session closed for user root Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:29:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:29:01 volumio volumio[6135]: info: Oct 22 12:29:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand status took 6 milliseconds Oct 22 12:29:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:29:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』","artist":"SpiderMan-Music","album":null,"uri":"NAS/AD/古风/en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』(動態歌詞Lyrics).m4a","trackType":"m4a"} Oct 22 12:29:01 volumio volumio[6135]: verbose: CURRENT POSITION 1313 Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 23ms Oct 22 12:29:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 22 12:29:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:29:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":274,"duration":239,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』","artist":"SpiderMan-Music","album":null,"uri":"NAS/AD/古风/en - 天龍八部之宿敵『 當恩怨各一半我怎麼圈攬，看燈籠血紅染尋仇已太晚。』(動態歌詞Lyrics).m4a","trackType":"m4a"} Oct 22 12:29:01 volumio volumio[6135]: verbose: CURRENT POSITION 1313 Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:29:01 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:29:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:29:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:29:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:29:01 volumio volumio[6135]: info: ------------------------------ 31ms Oct 22 12:30:01 volumio CRON[31444]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 12:30:01 volumio CRON[31445]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 12:30:01 volumio CRON[31446]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 12:30:01 volumio CRON[31447]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 12:30:01 volumio CRON[31444]: pam_unix(cron:session): session closed for user root Oct 22 12:30:01 volumio cron[559]: 2024-10-22 12:30:01 1t36Wv-0008BG-Km Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:30:01 volumio cron[559]: 2024-10-22 12:30:01 1t36Wv-0008BG-Km <= root@volumio U=root P=local S=580 Oct 22 12:30:01 volumio cron[559]: 2024-10-22 12:30:01 1t36Wv-0008BG-Km Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:30:01 volumio exim[31450]: 2024-10-22 12:30:01 1t36Wv-0008BG-Km <= root@volumio U=root P=local S=580 Oct 22 12:30:01 volumio exim[31450]: 2024-10-22 12:30:01 1t36Wv-0008BG-Km Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 12:30:01 volumio exim[31450]: exim: could not open panic log - aborting: see message(s) above Oct 22 12:30:01 volumio CRON[31445]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 12:30:01 volumio CRON[31445]: pam_unix(cron:session): session closed for user root Oct 22 12:32:55 volumio volumio[6135]: info: CorePlayQueue::getTrack 1313 Oct 22 12:32:55 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:32:55 volumio volumio[6135]: info: Prefetching next song Oct 22 12:32:55 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:32:55 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/MC 張天賦 - 記憶棉.mp3" Oct 22 12:32:55 volumio volumio[6135]: info: Oct 22 12:32:55 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:32:55 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:32:55 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/MC 張天賦 - 記憶棉.mp3" took 2 milliseconds Oct 22 12:32:55 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:32:55 volumio volumio[6135]: info: ------------------------------ 2ms Oct 22 12:32:55 volumio volumio[6135]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 22 12:32:59 volumio volumio[6135]: info: Oct 22 12:32:59 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:32:59 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:32:59 volumio volumio[6135]: info: Oct 22 12:32:59 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:32:59 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:32:59 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:32:59 volumio volumio[6135]: info: ------------------------------ 2ms Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: info: Oct 22 12:33:00 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:33:00 volumio volumio[6135]: info: sendMpdCommand status took 1002 milliseconds Oct 22 12:33:00 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:33:00 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 12:33:00 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:33:00 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"MC 張天賦 - 記憶棉.mp3","artist":null,"album":null,"uri":"NAS/AD/MC 張天賦 - 記憶棉.mp3","trackType":"mp3"} Oct 22 12:33:00 volumio volumio[6135]: verbose: CURRENT POSITION 36 Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:33:00 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:33:00 volumio volumio[6135]: info: ------------------------------ 1019ms Oct 22 12:33:00 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 22 12:33:00 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:33:00 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":293,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"MC 張天賦 - 記憶棉.mp3","artist":null,"album":null,"uri":"NAS/AD/MC 張天賦 - 記憶棉.mp3","trackType":"mp3"} Oct 22 12:33:00 volumio volumio[6135]: verbose: CURRENT POSITION 36 Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:33:00 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:33:00 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:33:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:33:00 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:33:00 volumio volumio[6135]: info: ------------------------------ 26ms Oct 22 12:33:55 volumio systemd[1]: Started Run anacron jobs. Oct 22 12:33:55 volumio anacron[31487]: Anacron 2.3 started on 2024-10-22 Oct 22 12:33:55 volumio anacron[31487]: Normal exit (0 jobs run) Oct 22 12:33:55 volumio systemd[1]: anacron.service: Succeeded. Oct 22 12:37:47 volumio volumio[6135]: info: CorePlayQueue::getTrack 36 Oct 22 12:37:47 volumio volumio[6135]: info: CorePlayQueue::getTrack 1053 Oct 22 12:37:47 volumio volumio[6135]: info: Prefetching next song Oct 22 12:37:47 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:37:47 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/玩具 - 岛屿心情.mp3" Oct 22 12:37:47 volumio volumio[6135]: info: Oct 22 12:37:47 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:37:47 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:37:47 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/玩具 - 岛屿心情.mp3" took 3 milliseconds Oct 22 12:37:47 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:37:47 volumio volumio[6135]: info: ------------------------------ 2ms Oct 22 12:37:47 volumio volumio[6135]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 22 12:37:52 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:37:52 volumio volumio[6135]: info: CorePlayQueue::getTrack 1053 Oct 22 12:37:53 volumio volumio[6135]: info: Oct 22 12:37:53 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:37:53 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:37:53 volumio volumio[6135]: info: Oct 22 12:37:53 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:37:53 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:37:53 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:37:53 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:37:53 volumio volumio[6135]: info: sendMpdCommand status took 1 milliseconds Oct 22 12:37:53 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:37:53 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:37:53 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 22 12:37:53 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:37:53 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:37:53 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:37:53 volumio volumio[6135]: info: CorePlayQueue::getTrack 1053 Oct 22 12:37:53 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":80,"duration":297,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"玩具","artist":"岛屿心情","album":"纷纭","uri":"NAS/AD/玩具 - 岛屿心情.mp3","trackType":"mp3"} Oct 22 12:37:53 volumio volumio[6135]: verbose: CURRENT POSITION 1053 Oct 22 12:37:53 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:37:53 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:37:53 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:37:53 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:37:53 volumio volumio[6135]: info: CorePlayQueue::getTrack 1053 Oct 22 12:37:53 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:37:53 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:37:53 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:37:53 volumio volumio[6135]: info: CorePlayQueue::getTrack 1053 Oct 22 12:37:53 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:37:53 volumio volumio[6135]: info: ------------------------------ 19ms Oct 22 12:42:45 volumio volumio[6135]: info: CorePlayQueue::getTrack 1053 Oct 22 12:42:45 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:45 volumio volumio[6135]: info: Prefetching next song Oct 22 12:42:45 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:42:45 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/古风/封茗囧菌 - 霜雪千年【歌詞字幕 _ 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜.m4a" Oct 22 12:42:45 volumio volumio[6135]: info: Oct 22 12:42:45 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:42:45 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:42:45 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/古风/封茗囧菌 - 霜雪千年【歌詞字幕 _ 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜.m4a" took 2 milliseconds Oct 22 12:42:45 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:42:45 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:42:45 volumio volumio[6135]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 22 12:42:50 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:42:50 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:50 volumio volumio[6135]: info: Oct 22 12:42:50 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:42:50 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:42:50 volumio volumio[6135]: info: Oct 22 12:42:50 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:42:50 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:42:50 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:42:50 volumio volumio[6135]: info: Oct 22 12:42:50 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:42:50 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:42:50 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:42:50 volumio volumio[6135]: info: ------------------------------ 4ms Oct 22 12:42:51 volumio volumio[6135]: info: Oct 22 12:42:51 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:42:51 volumio volumio[6135]: info: sendMpdCommand status took 1003 milliseconds Oct 22 12:42:51 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:42:51 volumio volumio[6135]: info: sendMpdCommand status took 1004 milliseconds Oct 22 12:42:51 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 12:42:51 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:42:51 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"封茗囧菌 - 霜雪千年【歌詞字幕 / 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜雪 幾載開了又敗...」Feng Ming Jiong Jun - Thousand Years Of Snow","artist":"BELLA PING MUSIC CHANNEL","album":null,"uri":"NAS/AD/古风/封茗囧菌 - 霜雪千年【歌詞字幕 _ 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜.m4a","trackType":"m4a"} Oct 22 12:42:51 volumio volumio[6135]: verbose: CURRENT POSITION 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:42:51 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:42:51 volumio volumio[6135]: info: ------------------------------ 1019ms Oct 22 12:42:51 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 22 12:42:51 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:42:51 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"封茗囧菌 - 霜雪千年【歌詞字幕 / 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜雪 幾載開了又敗...」Feng Ming Jiong Jun - Thousand Years Of Snow","artist":"BELLA PING MUSIC CHANNEL","album":null,"uri":"NAS/AD/古风/封茗囧菌 - 霜雪千年【歌詞字幕 _ 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜.m4a","trackType":"m4a"} Oct 22 12:42:51 volumio volumio[6135]: verbose: CURRENT POSITION 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:42:51 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:42:51 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":248,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"封茗囧菌 - 霜雪千年【歌詞字幕 / 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜雪 幾載開了又敗...」Feng Ming Jiong Jun - Thousand Years Of Snow","artist":"BELLA PING MUSIC CHANNEL","album":null,"uri":"NAS/AD/古风/封茗囧菌 - 霜雪千年【歌詞字幕 _ 完整高清音質】ft.雙笙 ♫「瞥見離人的喜悲霜.m4a","trackType":"m4a"} Oct 22 12:42:51 volumio volumio[6135]: verbose: CURRENT POSITION 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:42:51 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:42:51 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:42:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:42:51 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:42:51 volumio volumio[6135]: info: ------------------------------ 1036ms Oct 22 12:42:51 volumio volumio[6135]: info: ------------------------------ 34ms Oct 22 12:46:53 volumio volumio[6135]: info: CorePlayQueue::getTrack 1322 Oct 22 12:46:53 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:53 volumio volumio[6135]: info: Prefetching next song Oct 22 12:46:53 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:46:53 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/za/HIM - Close To The Flame.mp3" Oct 22 12:46:53 volumio volumio[6135]: info: Oct 22 12:46:53 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:46:53 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:46:53 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/za/HIM - Close To The Flame.mp3" took 3 milliseconds Oct 22 12:46:53 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:46:53 volumio volumio[6135]: info: ------------------------------ 5ms Oct 22 12:46:53 volumio volumio[6135]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 22 12:46:58 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:46:58 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:58 volumio volumio[6135]: info: Oct 22 12:46:58 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:46:58 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:46:58 volumio volumio[6135]: info: Oct 22 12:46:58 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:46:58 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:46:58 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:46:58 volumio volumio[6135]: info: Oct 22 12:46:58 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:46:58 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:46:58 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:46:59 volumio volumio[6135]: info: Oct 22 12:46:59 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:46:59 volumio volumio[6135]: info: ------------------------------ 1005ms Oct 22 12:46:59 volumio volumio[6135]: info: sendMpdCommand status took 1003 milliseconds Oct 22 12:46:59 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:46:59 volumio volumio[6135]: info: sendMpdCommand status took 6 milliseconds Oct 22 12:46:59 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:46:59 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Close To The Flame","artist":"HIM","album":"And Love Said No: The Greatest Hits 1997-2004","uri":"NAS/AD/za/HIM - Close To The Flame.mp3","trackType":"mp3"} Oct 22 12:46:59 volumio volumio[6135]: verbose: CURRENT POSITION 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:46:59 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:46:59 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Close To The Flame","artist":"HIM","album":"And Love Said No: The Greatest Hits 1997-2004","uri":"NAS/AD/za/HIM - Close To The Flame.mp3","trackType":"mp3"} Oct 22 12:46:59 volumio volumio[6135]: verbose: CURRENT POSITION 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:46:59 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:46:59 volumio volumio[6135]: info: ------------------------------ 1039ms Oct 22 12:46:59 volumio volumio[6135]: info: ------------------------------ 1039ms Oct 22 12:46:59 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 22 12:46:59 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:46:59 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":227,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Close To The Flame","artist":"HIM","album":"And Love Said No: The Greatest Hits 1997-2004","uri":"NAS/AD/za/HIM - Close To The Flame.mp3","trackType":"mp3"} Oct 22 12:46:59 volumio volumio[6135]: verbose: CURRENT POSITION 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:46:59 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:46:59 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:46:59 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:46:59 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:46:59 volumio volumio[6135]: info: ------------------------------ 53ms Oct 22 12:50:40 volumio volumio[6135]: info: CorePlayQueue::getTrack 1250 Oct 22 12:50:40 volumio volumio[6135]: info: CorePlayQueue::getTrack 1010 Oct 22 12:50:40 volumio volumio[6135]: info: Prefetching next song Oct 22 12:50:40 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:50:40 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/曹秦,大乔小乔 - 生命的意义.mp3" Oct 22 12:50:40 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/曹秦,大乔小乔 - 生命的意义.mp3" took 2 milliseconds Oct 22 12:50:40 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:50:40 volumio volumio[6135]: info: Oct 22 12:50:40 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:50:40 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:50:40 volumio volumio[6135]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 22 12:50:40 volumio volumio[6135]: info: ------------------------------ 2ms Oct 22 12:50:45 volumio volumio[6135]: info: Oct 22 12:50:45 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:50:45 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:50:45 volumio volumio[6135]: info: Oct 22 12:50:45 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:50:45 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:50:45 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:50:45 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:50:45 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 12:50:45 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:50:45 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:50:45 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 22 12:50:45 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:50:45 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:50:45 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:50:45 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:50:45 volumio volumio[6135]: info: CorePlayQueue::getTrack 1010 Oct 22 12:50:45 volumio volumio[6135]: info: CorePlayQueue::getTrack 1010 Oct 22 12:50:45 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":247,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"生命的意义","artist":"曹秦/大乔小乔","album":"曹秦单曲集","uri":"NAS/AD/曹秦,大乔小乔 - 生命的意义.mp3","trackType":"mp3"} Oct 22 12:50:45 volumio volumio[6135]: verbose: CURRENT POSITION 1010 Oct 22 12:50:45 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:50:45 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:50:45 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:50:45 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:50:45 volumio volumio[6135]: info: CorePlayQueue::getTrack 1010 Oct 22 12:50:45 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:50:45 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:50:45 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:50:45 volumio volumio[6135]: info: CorePlayQueue::getTrack 1010 Oct 22 12:50:45 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:50:45 volumio volumio[6135]: info: ------------------------------ 17ms Oct 22 12:54:47 volumio volumio[6135]: info: CorePlayQueue::getTrack 1010 Oct 22 12:54:47 volumio volumio[6135]: info: CorePlayQueue::getTrack 881 Oct 22 12:54:47 volumio volumio[6135]: info: Prefetching next song Oct 22 12:54:47 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:54:47 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/程响 - 今夜的风.mp3" Oct 22 12:54:47 volumio volumio[6135]: info: Oct 22 12:54:47 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:54:47 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:54:47 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/程响 - 今夜的风.mp3" took 3 milliseconds Oct 22 12:54:47 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:54:47 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:54:47 volumio volumio[6135]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 22 12:54:51 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:54:51 volumio volumio[6135]: info: CorePlayQueue::getTrack 881 Oct 22 12:54:52 volumio volumio[6135]: info: Oct 22 12:54:52 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:54:52 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:54:52 volumio volumio[6135]: info: Oct 22 12:54:52 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:54:52 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:54:52 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:54:52 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:54:52 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 12:54:52 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:54:52 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:54:52 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 22 12:54:52 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:54:52 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:54:52 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:54:52 volumio volumio[6135]: info: CorePlayQueue::getTrack 881 Oct 22 12:54:52 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"今夜的风","artist":"程响","album":"今夜的风","uri":"NAS/AD/程响 - 今夜的风.mp3","trackType":"mp3"} Oct 22 12:54:52 volumio volumio[6135]: verbose: CURRENT POSITION 881 Oct 22 12:54:52 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:54:52 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:54:52 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:54:52 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:54:52 volumio volumio[6135]: info: CorePlayQueue::getTrack 881 Oct 22 12:54:52 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:54:52 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:54:52 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:54:52 volumio volumio[6135]: info: CorePlayQueue::getTrack 881 Oct 22 12:54:52 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:54:52 volumio volumio[6135]: info: ------------------------------ 13ms Oct 22 12:57:56 volumio volumio[6135]: info: CorePlayQueue::getTrack 881 Oct 22 12:57:56 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 12:57:56 volumio volumio[6135]: info: Prefetching next song Oct 22 12:57:56 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 12:57:56 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/Beyond - 真的爱妳.mp3" Oct 22 12:57:56 volumio volumio[6135]: info: Oct 22 12:57:56 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:57:56 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:57:56 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/Beyond - 真的爱妳.mp3" took 2 milliseconds Oct 22 12:57:56 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 12:57:56 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:57:56 volumio volumio[6135]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 22 12:58:00 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:58:00 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 12:58:01 volumio volumio[6135]: info: Oct 22 12:58:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 12:58:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 12:58:01 volumio volumio[6135]: info: Oct 22 12:58:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 12:58:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 12:58:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 12:58:01 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 12:58:01 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 12:58:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 12:58:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 12:58:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 22 12:58:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 12:58:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 12:58:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 12:58:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 12:58:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"真的爱妳","artist":"Beyond","album":"Beyond Ⅳ","uri":"NAS/AD/Beyond - 真的爱妳.mp3","trackType":"mp3"} Oct 22 12:58:01 volumio volumio[6135]: verbose: CURRENT POSITION 244 Oct 22 12:58:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 12:58:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 12:58:01 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 12:58:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:58:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 12:58:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:58:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:58:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 12:58:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 12:58:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 12:58:01 volumio volumio[6135]: info: ------------------------------ 13ms Oct 22 13:00:00 volumio kernel: Under-voltage detected! (0x00050005) Oct 22 13:00:01 volumio CRON[31708]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 13:00:01 volumio CRON[31709]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 13:00:01 volumio CRON[31710]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 13:00:01 volumio CRON[31711]: (root) CMD (/Easeware/shell/stop_music ) Oct 22 13:00:01 volumio volumio[6135]: info: Oct 22 13:00:01 volumio volumio[6135]: ---------------------------- Client requests Volumio stop Oct 22 13:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioStop Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 13:00:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::stPlaybackTimer Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 13:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 13:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 13:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 13:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::serviceStop Oct 22 13:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 13:00:01 volumio volumio[6135]: info: CoreCommandRouter::serviceStop Oct 22 13:00:01 volumio volumio[6135]: info: ControllerMpd::stop Oct 22 13:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 13:00:01 volumio volumio[6135]: info: Oct 22 13:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 13:00:01 volumio volumio[6135]: info: sendMpdCommand stop took 157 milliseconds Oct 22 13:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 13:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 13:00:01 volumio volumio[6135]: info: sendMpdCommand status took 5 milliseconds Oct 22 13:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 13:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 13:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 22 13:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 13:00:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 13:00:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 13:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 13:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 13:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 13:00:02 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 13:00:02 volumio volumio[6135]: 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":"Beyond","album":"Beyond Ⅳ","uri":"NAS/AD/Beyond - 真的爱妳.mp3","trackType":"mp3"} Oct 22 13:00:02 volumio volumio[6135]: verbose: CURRENT POSITION 244 Oct 22 13:00:02 volumio volumio[6135]: info: CoreStateMachine::syncState stateService stop Oct 22 13:00:02 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 13:00:02 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 13:00:02 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 13:00:02 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 13:00:02 volumio volumio[6135]: info: No code Oct 22 13:00:02 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 13:00:02 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 13:00:02 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 13:00:02 volumio volumio[6135]: info: ------------------------------ 35ms Oct 22 13:00:02 volumio cron[559]: 2024-10-22 13:00:02 1t36zx-0008FY-Pi Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:00:02 volumio cron[559]: 2024-10-22 13:00:02 1t36zx-0008FY-Pi <= root@volumio U=root P=local S=580 Oct 22 13:00:02 volumio cron[559]: 2024-10-22 13:00:02 1t36zx-0008FY-Pi Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:00:02 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:00:02 volumio exim[31716]: 2024-10-22 13:00:02 1t36zx-0008FY-Pi <= root@volumio U=root P=local S=580 Oct 22 13:00:02 volumio exim[31716]: 2024-10-22 13:00:02 1t36zx-0008FY-Pi Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:00:02 volumio exim[31716]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:00:02 volumio CRON[31708]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 13:00:02 volumio CRON[31708]: pam_unix(cron:session): session closed for user root Oct 22 13:00:02 volumio cron[559]: 2024-10-22 13:00:02 1t36zy-0008Fa-1n Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:00:02 volumio cron[559]: 2024-10-22 13:00:02 1t36zy-0008Fa-1n <= root@volumio U=root P=local S=920 Oct 22 13:00:02 volumio cron[559]: 2024-10-22 13:00:02 1t36zy-0008Fa-1n Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:00:02 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:00:02 volumio exim[31718]: 2024-10-22 13:00:02 1t36zy-0008Fa-1n <= root@volumio U=root P=local S=920 Oct 22 13:00:02 volumio exim[31718]: 2024-10-22 13:00:02 1t36zy-0008Fa-1n Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:00:02 volumio exim[31718]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:00:02 volumio CRON[31709]: (root) MAIL (mailed 365 bytes of output but got status 0x0001 from MTA ) Oct 22 13:00:02 volumio CRON[31709]: pam_unix(cron:session): session closed for user root Oct 22 13:00:06 volumio kernel: Voltage normalised (0x00000000) Oct 22 13:17:01 volumio CRON[31892]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 13:17:01 volumio CRON[31893]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 13:17:01 volumio CRON[31892]: pam_unix(cron:session): session closed for user root Oct 22 13:30:01 volumio CRON[31989]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 13:30:01 volumio CRON[31990]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 13:30:01 volumio CRON[31992]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 13:30:01 volumio CRON[31991]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 13:30:01 volumio CRON[31989]: pam_unix(cron:session): session closed for user root Oct 22 13:30:01 volumio cron[559]: 2024-10-22 13:30:01 1t37Sz-0008K3-Cm Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:30:01 volumio cron[559]: 2024-10-22 13:30:01 1t37Sz-0008K3-Cm <= root@volumio U=root P=local S=580 Oct 22 13:30:01 volumio cron[559]: 2024-10-22 13:30:01 1t37Sz-0008K3-Cm Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:30:01 volumio exim[31995]: 2024-10-22 13:30:01 1t37Sz-0008K3-Cm <= root@volumio U=root P=local S=580 Oct 22 13:30:01 volumio exim[31995]: 2024-10-22 13:30:01 1t37Sz-0008K3-Cm Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:30:01 volumio exim[31995]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:30:01 volumio CRON[31990]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 13:30:01 volumio CRON[31990]: pam_unix(cron:session): session closed for user root Oct 22 13:34:10 volumio systemd[1]: Started Run anacron jobs. Oct 22 13:34:10 volumio anacron[32035]: Anacron 2.3 started on 2024-10-22 Oct 22 13:34:10 volumio anacron[32035]: Normal exit (0 jobs run) Oct 22 13:34:10 volumio systemd[1]: anacron.service: Succeeded. Oct 22 13:59:01 volumio CRON[32223]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 13:59:01 volumio CRON[32224]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 13:59:01 volumio cron[559]: 2024-10-22 13:59:01 1t37v3-0008Nn-F3 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:59:01 volumio cron[559]: 2024-10-22 13:59:01 1t37v3-0008Nn-F3 <= root@volumio U=root P=local S=580 Oct 22 13:59:01 volumio cron[559]: 2024-10-22 13:59:01 1t37v3-0008Nn-F3 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:59:01 volumio exim[32227]: 2024-10-22 13:59:01 1t37v3-0008Nn-F3 <= root@volumio U=root P=local S=580 Oct 22 13:59:01 volumio exim[32227]: 2024-10-22 13:59:01 1t37v3-0008Nn-F3 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 13:59:01 volumio exim[32227]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:59:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 13:59:01 volumio CRON[32223]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 13:59:01 volumio CRON[32223]: pam_unix(cron:session): session closed for user root Oct 22 14:00:01 volumio CRON[32231]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 14:00:01 volumio CRON[32230]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 14:00:01 volumio CRON[32233]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 14:00:01 volumio CRON[32232]: (root) CMD (/Easeware/shell/play_music ) Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioGetState Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 14:00:01 volumio cron[559]: 2024-10-22 14:00:01 1t37w1-0008O2-HW Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:00:01 volumio cron[559]: 2024-10-22 14:00:01 1t37w1-0008O2-HW <= root@volumio U=root P=local S=580 Oct 22 14:00:01 volumio cron[559]: 2024-10-22 14:00:01 1t37w1-0008O2-HW Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:00:01 volumio exim[32242]: 2024-10-22 14:00:01 1t37w1-0008O2-HW <= root@volumio U=root P=local S=580 Oct 22 14:00:01 volumio exim[32242]: 2024-10-22 14:00:01 1t37w1-0008O2-HW Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:00:01 volumio exim[32242]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:00:01 volumio CRON[32230]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 14:00:01 volumio CRON[32230]: pam_unix(cron:session): session closed for user root Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- Client requests Volume 70 Oct 22 14:00:01 volumio volumio[6135]: info: VolumeController::SetAlsaVolume70 Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 244 Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- Client requests Volumio next Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioNext Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::next Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 14:00:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::play index undefined Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1017 Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1017 Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::clearAddPlayTracks NAS/AD/梁咏琪 - 胆小鬼.mp3 Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand stop took 2 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 14:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand clear took 2 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/梁咏琪 - 胆小鬼.mp3" Oct 22 14:00:01 volumio volumio[6135]: error: updateQueue error: null Oct 22 14:00:01 volumio volumio[6135]: info: ------------------------------ 4ms Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 14:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/梁咏琪 - 胆小鬼.mp3" took 4 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand play Oct 22 14:00:01 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand play took 2 milliseconds Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- Client requests Volumio next Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioNext Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::next Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 14:00:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::play index undefined Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::clearAddPlayTracks NAS/AD/Yanni - With An Orchid.mp3 Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand stop took 23 milliseconds Oct 22 14:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 14:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand clear took 2 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/Yanni - With An Orchid.mp3" Oct 22 14:00:01 volumio volumio[6135]: error: updateQueue error: null Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 14:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 14:00:01 volumio volumio[6135]: info: ------------------------------ 5ms Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/Yanni - With An Orchid.mp3" took 4 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand play Oct 22 14:00:01 volumio volumio[6135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 14:00:01 volumio volumio[6135]: info: ------------------------------ 12ms Oct 22 14:00:01 volumio volumio[6135]: info: ------------------------------ 6ms Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand play took 2 milliseconds Oct 22 14:00:01 volumio cron[559]: 2024-10-22 14:00:01 1t37w1-0008OC-P5 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:00:01 volumio cron[559]: 2024-10-22 14:00:01 1t37w1-0008OC-P5 <= root@volumio U=root P=local S=1652 Oct 22 14:00:01 volumio cron[559]: 2024-10-22 14:00:01 1t37w1-0008OC-P5 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:00:01 volumio exim[32252]: 2024-10-22 14:00:01 1t37w1-0008OC-P5 <= root@volumio U=root P=local S=1652 Oct 22 14:00:01 volumio exim[32252]: 2024-10-22 14:00:01 1t37w1-0008OC-P5 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:00:01 volumio exim[32252]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:00:01 volumio CRON[32231]: (root) MAIL (mailed 1097 bytes of output but got status 0x0001 from MTA ) Oct 22 14:00:01 volumio CRON[32231]: pam_unix(cron:session): session closed for user root Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 14:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:00:01 volumio volumio[6135]: info: Oct 22 14:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand status took 4 milliseconds Oct 22 14:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 14:00:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":306,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"With An Orchid","artist":"Yanni","album":"If I Could Tell You","uri":"NAS/AD/Yanni - With An Orchid.mp3","trackType":"mp3"} Oct 22 14:00:01 volumio volumio[6135]: verbose: CURRENT POSITION 426 Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:00:01 volumio volumio[6135]: info: ------------------------------ 18ms Oct 22 14:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 22 14:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 14:00:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":306,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"With An Orchid","artist":"Yanni","album":"If I Could Tell You","uri":"NAS/AD/Yanni - With An Orchid.mp3","trackType":"mp3"} Oct 22 14:00:01 volumio volumio[6135]: verbose: CURRENT POSITION 426 Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 14:00:01 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 14:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:00:02 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:00:02 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:00:02 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:00:02 volumio volumio[6135]: info: ------------------------------ 28ms Oct 22 14:05:01 volumio CRON[32294]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 14:05:01 volumio CRON[32295]: (root) CMD (/Easeware/shell/stop_music ) Oct 22 14:05:01 volumio volumio[6135]: info: Oct 22 14:05:01 volumio volumio[6135]: ---------------------------- Client requests Volumio stop Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::volumioStop Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 14:05:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::stPlaybackTimer Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 14:05:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:05:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::serviceStop Oct 22 14:05:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::serviceStop Oct 22 14:05:01 volumio volumio[6135]: info: ControllerMpd::stop Oct 22 14:05:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 14:05:01 volumio volumio[6135]: info: Oct 22 14:05:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 14:05:01 volumio volumio[6135]: info: sendMpdCommand stop took 118 milliseconds Oct 22 14:05:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 14:05:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 14:05:01 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 14:05:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 14:05:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 14:05:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 14:05:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 14:05:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:05:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:05:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:05:01 volumio volumio[6135]: 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":"With An Orchid","artist":"Yanni","album":"If I Could Tell You","uri":"NAS/AD/Yanni - With An Orchid.mp3","trackType":"mp3"} Oct 22 14:05:01 volumio volumio[6135]: verbose: CURRENT POSITION 426 Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService stop Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 14:05:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:05:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:05:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:05:02 volumio volumio[6135]: info: No code Oct 22 14:05:02 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 14:05:02 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 14:05:02 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 14:05:02 volumio volumio[6135]: info: ------------------------------ 25ms Oct 22 14:05:02 volumio cron[559]: 2024-10-22 14:05:02 1t380s-0008Ox-0d Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:05:02 volumio cron[559]: 2024-10-22 14:05:02 1t380s-0008Ox-0d <= root@volumio U=root P=local S=920 Oct 22 14:05:02 volumio cron[559]: 2024-10-22 14:05:02 1t380s-0008Ox-0d Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:05:02 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:05:02 volumio exim[32299]: 2024-10-22 14:05:02 1t380s-0008Ox-0d <= root@volumio U=root P=local S=920 Oct 22 14:05:02 volumio exim[32299]: 2024-10-22 14:05:02 1t380s-0008Ox-0d Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:05:02 volumio exim[32299]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:05:02 volumio CRON[32294]: (root) MAIL (mailed 365 bytes of output but got status 0x0001 from MTA ) Oct 22 14:05:02 volumio CRON[32294]: pam_unix(cron:session): session closed for user root Oct 22 14:17:01 volumio CRON[32398]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 14:17:01 volumio CRON[32399]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 14:17:01 volumio CRON[32398]: pam_unix(cron:session): session closed for user root Oct 22 14:30:01 volumio CRON[32506]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 14:30:01 volumio CRON[32505]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 14:30:01 volumio CRON[32507]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 14:30:01 volumio CRON[32508]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 14:30:01 volumio CRON[32505]: pam_unix(cron:session): session closed for user root Oct 22 14:30:01 volumio cron[559]: 2024-10-22 14:30:01 1t38P3-0008SN-3r Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:30:01 volumio cron[559]: 2024-10-22 14:30:01 1t38P3-0008SN-3r <= root@volumio U=root P=local S=580 Oct 22 14:30:01 volumio cron[559]: 2024-10-22 14:30:01 1t38P3-0008SN-3r Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:30:01 volumio exim[32511]: 2024-10-22 14:30:01 1t38P3-0008SN-3r <= root@volumio U=root P=local S=580 Oct 22 14:30:01 volumio exim[32511]: 2024-10-22 14:30:01 1t38P3-0008SN-3r Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 14:30:01 volumio exim[32511]: exim: could not open panic log - aborting: see message(s) above Oct 22 14:30:01 volumio CRON[32506]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 14:30:01 volumio CRON[32506]: pam_unix(cron:session): session closed for user root Oct 22 14:32:05 volumio systemd[1]: Started Run anacron jobs. Oct 22 14:32:05 volumio anacron[32538]: Anacron 2.3 started on 2024-10-22 Oct 22 14:32:05 volumio anacron[32538]: Normal exit (0 jobs run) Oct 22 14:32:05 volumio systemd[1]: anacron.service: Succeeded. Oct 22 15:00:01 volumio CRON[32737]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 15:00:01 volumio CRON[32738]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 15:00:01 volumio cron[559]: 2024-10-22 15:00:01 1t38s5-0008W5-69 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:00:01 volumio cron[559]: 2024-10-22 15:00:01 1t38s5-0008W5-69 <= root@volumio U=root P=local S=580 Oct 22 15:00:01 volumio cron[559]: 2024-10-22 15:00:01 1t38s5-0008W5-69 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 15:00:01 volumio exim[32741]: 2024-10-22 15:00:01 1t38s5-0008W5-69 <= root@volumio U=root P=local S=580 Oct 22 15:00:01 volumio exim[32741]: 2024-10-22 15:00:01 1t38s5-0008W5-69 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:00:01 volumio exim[32741]: exim: could not open panic log - aborting: see message(s) above Oct 22 15:00:01 volumio CRON[32737]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 15:00:01 volumio CRON[32737]: pam_unix(cron:session): session closed for user root Oct 22 15:17:01 volumio CRON[418]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 15:17:01 volumio CRON[419]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 15:17:01 volumio CRON[418]: pam_unix(cron:session): session closed for user root Oct 22 15:30:01 volumio CRON[531]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 15:30:01 volumio CRON[532]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 15:30:01 volumio CRON[533]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 15:30:01 volumio CRON[534]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 15:30:01 volumio CRON[531]: pam_unix(cron:session): session closed for user root Oct 22 15:30:01 volumio cron[559]: 2024-10-22 15:30:01 1t39L7-00008f-9W Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:30:01 volumio cron[559]: 2024-10-22 15:30:01 1t39L7-00008f-9W <= root@volumio U=root P=local S=580 Oct 22 15:30:01 volumio cron[559]: 2024-10-22 15:30:01 1t39L7-00008f-9W Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 15:30:01 volumio exim[537]: 2024-10-22 15:30:01 1t39L7-00008f-9W <= root@volumio U=root P=local S=580 Oct 22 15:30:01 volumio exim[537]: 2024-10-22 15:30:01 1t39L7-00008f-9W Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:30:01 volumio exim[537]: exim: could not open panic log - aborting: see message(s) above Oct 22 15:30:01 volumio CRON[532]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 15:30:01 volumio CRON[532]: pam_unix(cron:session): session closed for user root Oct 22 15:33:10 volumio systemd[1]: Started Run anacron jobs. Oct 22 15:33:10 volumio anacron[575]: Anacron 2.3 started on 2024-10-22 Oct 22 15:33:10 volumio anacron[575]: Normal exit (0 jobs run) Oct 22 15:33:10 volumio systemd[1]: anacron.service: Succeeded. Oct 22 15:59:01 volumio CRON[788]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 15:59:01 volumio CRON[789]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 15:59:01 volumio cron[559]: 2024-10-22 15:59:01 1t39nB-0000Cm-Bn Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:59:01 volumio cron[559]: 2024-10-22 15:59:01 1t39nB-0000Cm-Bn <= root@volumio U=root P=local S=580 Oct 22 15:59:01 volumio cron[559]: 2024-10-22 15:59:01 1t39nB-0000Cm-Bn Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:59:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 15:59:01 volumio exim[792]: 2024-10-22 15:59:01 1t39nB-0000Cm-Bn <= root@volumio U=root P=local S=580 Oct 22 15:59:01 volumio exim[792]: 2024-10-22 15:59:01 1t39nB-0000Cm-Bn Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 15:59:01 volumio exim[792]: exim: could not open panic log - aborting: see message(s) above Oct 22 15:59:01 volumio CRON[788]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 15:59:01 volumio CRON[788]: pam_unix(cron:session): session closed for user root Oct 22 16:00:01 volumio CRON[798]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 16:00:01 volumio CRON[799]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 16:00:01 volumio CRON[800]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 16:00:01 volumio CRON[801]: (root) CMD (/Easeware/shell/play_music ) Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioGetState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 16:00:01 volumio cron[559]: 2024-10-22 16:00:01 1t39o9-0000D4-EA Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:00:01 volumio cron[559]: 2024-10-22 16:00:01 1t39o9-0000D4-EA <= root@volumio U=root P=local S=580 Oct 22 16:00:01 volumio cron[559]: 2024-10-22 16:00:01 1t39o9-0000D4-EA Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:00:01 volumio exim[810]: 2024-10-22 16:00:01 1t39o9-0000D4-EA <= root@volumio U=root P=local S=580 Oct 22 16:00:01 volumio exim[810]: 2024-10-22 16:00:01 1t39o9-0000D4-EA Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:00:01 volumio exim[810]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:00:01 volumio CRON[798]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 16:00:01 volumio CRON[798]: pam_unix(cron:session): session closed for user root Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- Client requests Volume 70 Oct 22 16:00:01 volumio volumio[6135]: info: VolumeController::SetAlsaVolume70 Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 426 Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- Client requests Volumio next Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioNext Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::next Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 16:00:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::play index undefined Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 674 Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 674 Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::clearAddPlayTracks NAS/AD/Bryan Adams - Here I Am.mp3 Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand stop took 2 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand clear took 3 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/Bryan Adams - Here I Am.mp3" Oct 22 16:00:01 volumio volumio[6135]: error: updateQueue error: null Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 4ms Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/Bryan Adams - Here I Am.mp3" took 3 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand play Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand play took 2 milliseconds Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- Client requests Volumio next Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioNext Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::next Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 16:00:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::play index undefined Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::clearAddPlayTracks NAS/AD/古风/大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」.m4a Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand stop took 17 milliseconds Oct 22 16:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand clear took 3 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/古风/大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」.m4a" Oct 22 16:00:01 volumio volumio[6135]: error: updateQueue error: null Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:00:01 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 6ms Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/古风/大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」.m4a" took 3 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand play Oct 22 16:00:01 volumio volumio[6135]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 13ms Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 6ms Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand play took 3 milliseconds Oct 22 16:00:01 volumio cron[559]: 2024-10-22 16:00:01 1t39o9-0000DE-Lo Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:00:01 volumio cron[559]: 2024-10-22 16:00:01 1t39o9-0000DE-Lo <= root@volumio U=root P=local S=1652 Oct 22 16:00:01 volumio cron[559]: 2024-10-22 16:00:01 1t39o9-0000DE-Lo Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:00:01 volumio exim[820]: 2024-10-22 16:00:01 1t39o9-0000DE-Lo <= root@volumio U=root P=local S=1652 Oct 22 16:00:01 volumio exim[820]: 2024-10-22 16:00:01 1t39o9-0000DE-Lo Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:00:01 volumio exim[820]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:00:01 volumio CRON[799]: (root) MAIL (mailed 1097 bytes of output but got status 0x0001 from MTA ) Oct 22 16:00:01 volumio CRON[799]: pam_unix(cron:session): session closed for user root Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:00:01 volumio volumio[6135]: info: Oct 22 16:00:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand status took 4 milliseconds Oct 22 16:00:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:00:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」","artist":"魔力可可","album":null,"uri":"NAS/AD/古风/大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」.m4a","trackType":"m4a"} Oct 22 16:00:01 volumio volumio[6135]: verbose: CURRENT POSITION 1321 Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 18ms Oct 22 16:00:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 22 16:00:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:00:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":271,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」","artist":"魔力可可","album":null,"uri":"NAS/AD/古风/大柯 - 刚烈女子「我所爱的 一笔就带过，我经历着 一生都坎坷」.m4a","trackType":"m4a"} Oct 22 16:00:01 volumio volumio[6135]: verbose: CURRENT POSITION 1321 Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 16:00:01 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:00:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:00:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:00:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:00:01 volumio volumio[6135]: info: ------------------------------ 27ms Oct 22 16:04:27 volumio volumio[6135]: info: CorePlayQueue::getTrack 1321 Oct 22 16:04:27 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:27 volumio volumio[6135]: info: Prefetching next song Oct 22 16:04:27 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 16:04:27 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/Patrick Fiori - Plus Je Pense à Toi.mp3" Oct 22 16:04:27 volumio volumio[6135]: info: Oct 22 16:04:27 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:04:27 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:04:27 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/Patrick Fiori - Plus Je Pense à Toi.mp3" took 3 milliseconds Oct 22 16:04:27 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 16:04:27 volumio volumio[6135]: info: ------------------------------ 2ms Oct 22 16:04:27 volumio volumio[6135]: info: sendMpdCommand consume 1 took 2 milliseconds Oct 22 16:04:31 volumio volumio[6135]: info: Oct 22 16:04:31 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:04:31 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:04:31 volumio volumio[6135]: info: Oct 22 16:04:31 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:04:31 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:04:31 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:04:31 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: info: Oct 22 16:04:32 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:04:32 volumio volumio[6135]: info: sendMpdCommand status took 992 milliseconds Oct 22 16:04:32 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:04:32 volumio volumio[6135]: info: sendMpdCommand status took 3 milliseconds Oct 22 16:04:32 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:04:32 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Plus Je Pense à Toi","artist":"Patrick Fiori","album":"4 Mots Sur un Piano","uri":"NAS/AD/Patrick Fiori - Plus Je Pense à Toi.mp3","trackType":"mp3"} Oct 22 16:04:32 volumio volumio[6135]: verbose: CURRENT POSITION 158 Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 16:04:32 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:04:32 volumio volumio[6135]: info: ------------------------------ 1015ms Oct 22 16:04:32 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 22 16:04:32 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:04:32 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":284,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Plus Je Pense à Toi","artist":"Patrick Fiori","album":"4 Mots Sur un Piano","uri":"NAS/AD/Patrick Fiori - Plus Je Pense à Toi.mp3","trackType":"mp3"} Oct 22 16:04:32 volumio volumio[6135]: verbose: CURRENT POSITION 158 Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 16:04:32 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:04:32 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:04:32 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:04:32 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:04:32 volumio volumio[6135]: info: ------------------------------ 33ms Oct 22 16:09:10 volumio volumio[6135]: info: CorePlayQueue::getTrack 158 Oct 22 16:09:10 volumio volumio[6135]: info: CorePlayQueue::getTrack 907 Oct 22 16:09:10 volumio volumio[6135]: info: Prefetching next song Oct 22 16:09:10 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 16:09:10 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/小嘉玲 - 北木南.mp3" Oct 22 16:09:10 volumio volumio[6135]: info: Oct 22 16:09:10 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:09:10 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:09:10 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/小嘉玲 - 北木南.mp3" took 3 milliseconds Oct 22 16:09:10 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 16:09:10 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 16:09:10 volumio volumio[6135]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 22 16:09:15 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 16:09:15 volumio volumio[6135]: info: CorePlayQueue::getTrack 907 Oct 22 16:09:16 volumio volumio[6135]: info: Oct 22 16:09:16 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:09:16 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:09:16 volumio volumio[6135]: info: Oct 22 16:09:16 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:09:16 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:09:16 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:09:16 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 16:09:16 volumio volumio[6135]: info: sendMpdCommand status took 1 milliseconds Oct 22 16:09:16 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:09:16 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:09:16 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 22 16:09:16 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:09:16 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:09:16 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:09:16 volumio volumio[6135]: info: CorePlayQueue::getTrack 907 Oct 22 16:09:16 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":198,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"北木南","artist":"小嘉玲","album":"北木南","uri":"NAS/AD/小嘉玲 - 北木南.mp3","trackType":"mp3"} Oct 22 16:09:16 volumio volumio[6135]: verbose: CURRENT POSITION 907 Oct 22 16:09:16 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 16:09:16 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 16:09:16 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 16:09:16 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:09:16 volumio volumio[6135]: info: CorePlayQueue::getTrack 907 Oct 22 16:09:16 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:09:16 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:09:16 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:09:16 volumio volumio[6135]: info: CorePlayQueue::getTrack 907 Oct 22 16:09:16 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:09:16 volumio volumio[6135]: info: ------------------------------ 13ms Oct 22 16:12:29 volumio volumio[6135]: info: CorePlayQueue::getTrack 907 Oct 22 16:12:29 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:12:29 volumio volumio[6135]: info: Prefetching next song Oct 22 16:12:29 volumio volumio[6135]: info: DOING PREFETCH IN MPD Oct 22 16:12:29 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand add "NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3" Oct 22 16:12:29 volumio volumio[6135]: info: Oct 22 16:12:29 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:12:29 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:12:29 volumio volumio[6135]: info: sendMpdCommand add "NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3" took 2 milliseconds Oct 22 16:12:29 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 22 16:12:29 volumio volumio[6135]: info: ------------------------------ 2ms Oct 22 16:12:29 volumio volumio[6135]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 22 16:12:33 volumio volumio[6135]: info: CoreStateMachine::startPlaybackTimer Oct 22 16:12:33 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:12:34 volumio volumio[6135]: info: Oct 22 16:12:34 volumio volumio[6135]: ---------------------------- MPD announces system playlist update Oct 22 16:12:34 volumio volumio[6135]: info: Ignoring MPD Status Update Oct 22 16:12:34 volumio volumio[6135]: info: Oct 22 16:12:34 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:12:34 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:12:34 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:12:34 volumio volumio[6135]: info: ------------------------------ 3ms Oct 22 16:12:34 volumio volumio[6135]: info: sendMpdCommand status took 1 milliseconds Oct 22 16:12:34 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:12:34 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:12:34 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 22 16:12:34 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:12:34 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:12:34 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:12:34 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:12:34 volumio volumio[6135]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":189,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"当真","artist":"蒋家驹（蒋蒋）/曲肖冰","album":"当真","uri":"NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3","trackType":"mp3"} Oct 22 16:12:34 volumio volumio[6135]: verbose: CURRENT POSITION 955 Oct 22 16:12:34 volumio volumio[6135]: info: CoreStateMachine::syncState stateService play Oct 22 16:12:34 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus play Oct 22 16:12:34 volumio volumio[6135]: info: Received an update from plugin. extracting info from payload Oct 22 16:12:34 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:12:34 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:12:34 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:12:34 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:12:34 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:12:34 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:12:34 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:12:34 volumio volumio[6135]: info: ------------------------------ 13ms Oct 22 16:15:01 volumio CRON[938]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 16:15:01 volumio CRON[939]: (root) CMD (/Easeware/shell/stop_music ) Oct 22 16:15:01 volumio volumio[6135]: info: Oct 22 16:15:01 volumio volumio[6135]: ---------------------------- Client requests Volumio stop Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::volumioStop Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::stop Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 16:15:01 volumio volumio[6135]: verbose: UNSET VOLATILE Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::stPlaybackTimer Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::updateTrackBlock Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrackBlock Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::serviceStop Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::serviceStop Oct 22 16:15:01 volumio volumio[6135]: info: ControllerMpd::stop Oct 22 16:15:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 16:15:01 volumio volumio[6135]: info: Oct 22 16:15:01 volumio volumio[6135]: ---------------------------- MPD announces state update: player Oct 22 16:15:01 volumio volumio[6135]: info: sendMpdCommand stop took 77 milliseconds Oct 22 16:15:01 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 16:15:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 16:15:01 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 16:15:01 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 16:15:01 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 16:15:01 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 16:15:01 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 16:15:01 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:15:01 volumio volumio[6135]: 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":"蒋家驹（蒋蒋）/曲肖冰","album":"当真","uri":"NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3","trackType":"mp3"} Oct 22 16:15:01 volumio volumio[6135]: verbose: CURRENT POSITION 955 Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::syncState stateService stop Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:15:01 volumio volumio[6135]: info: No code Oct 22 16:15:01 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 16:15:01 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 16:15:01 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 16:15:01 volumio volumio[6135]: info: ------------------------------ 21ms Oct 22 16:15:01 volumio cron[559]: 2024-10-22 16:15:01 1t3A2f-0000FD-SJ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:15:01 volumio cron[559]: 2024-10-22 16:15:01 1t3A2f-0000FD-SJ <= root@volumio U=root P=local S=920 Oct 22 16:15:01 volumio cron[559]: 2024-10-22 16:15:01 1t3A2f-0000FD-SJ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:15:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:15:01 volumio exim[943]: 2024-10-22 16:15:01 1t3A2f-0000FD-SJ <= root@volumio U=root P=local S=920 Oct 22 16:15:01 volumio exim[943]: 2024-10-22 16:15:01 1t3A2f-0000FD-SJ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:15:01 volumio exim[943]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:15:01 volumio CRON[938]: (root) MAIL (mailed 365 bytes of output but got status 0x0001 from MTA ) Oct 22 16:15:01 volumio CRON[938]: pam_unix(cron:session): session closed for user root Oct 22 16:17:01 volumio CRON[972]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 16:17:01 volumio CRON[973]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 16:17:01 volumio CRON[972]: pam_unix(cron:session): session closed for user root Oct 22 16:30:01 volumio CRON[1080]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 16:30:01 volumio CRON[1079]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 16:30:01 volumio CRON[1081]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 16:30:01 volumio CRON[1082]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 16:30:01 volumio CRON[1079]: pam_unix(cron:session): session closed for user root Oct 22 16:30:01 volumio cron[559]: 2024-10-22 16:30:01 1t3AHB-0000HV-VV Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:30:01 volumio cron[559]: 2024-10-22 16:30:01 1t3AHB-0000HV-VV <= root@volumio U=root P=local S=580 Oct 22 16:30:01 volumio cron[559]: 2024-10-22 16:30:01 1t3AHB-0000HV-VV Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:30:01 volumio exim[1085]: 2024-10-22 16:30:01 1t3AHB-0000HV-VV <= root@volumio U=root P=local S=580 Oct 22 16:30:01 volumio exim[1085]: 2024-10-22 16:30:01 1t3AHB-0000HV-VV Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 16:30:01 volumio exim[1085]: exim: could not open panic log - aborting: see message(s) above Oct 22 16:30:01 volumio CRON[1080]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 16:30:01 volumio CRON[1080]: pam_unix(cron:session): session closed for user root Oct 22 16:32:05 volumio systemd[1]: Started Run anacron jobs. Oct 22 16:32:05 volumio anacron[1112]: Anacron 2.3 started on 2024-10-22 Oct 22 16:32:05 volumio anacron[1112]: Normal exit (0 jobs run) Oct 22 16:32:05 volumio systemd[1]: anacron.service: Succeeded. Oct 22 17:00:01 volumio CRON[1355]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 17:00:01 volumio CRON[1356]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 17:00:01 volumio cron[559]: 2024-10-22 17:00:01 1t3AkD-0000Lv-1b Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 17:00:01 volumio cron[559]: 2024-10-22 17:00:01 1t3AkD-0000Lv-1b <= root@volumio U=root P=local S=580 Oct 22 17:00:01 volumio cron[559]: 2024-10-22 17:00:01 1t3AkD-0000Lv-1b Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 17:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 17:00:01 volumio exim[1359]: 2024-10-22 17:00:01 1t3AkD-0000Lv-1b <= root@volumio U=root P=local S=580 Oct 22 17:00:01 volumio exim[1359]: 2024-10-22 17:00:01 1t3AkD-0000Lv-1b Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 17:00:01 volumio exim[1359]: exim: could not open panic log - aborting: see message(s) above Oct 22 17:00:01 volumio CRON[1355]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 17:00:01 volumio CRON[1355]: pam_unix(cron:session): session closed for user root Oct 22 17:17:01 volumio CRON[1505]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 17:17:01 volumio CRON[1506]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 17:17:01 volumio CRON[1505]: pam_unix(cron:session): session closed for user root Oct 22 17:30:01 volumio CRON[1613]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 17:30:01 volumio CRON[1612]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 17:30:01 volumio CRON[1614]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 17:30:01 volumio CRON[1615]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 17:30:01 volumio CRON[1612]: pam_unix(cron:session): session closed for user root Oct 22 17:30:01 volumio cron[559]: 2024-10-22 17:30:01 1t3BDF-0000Q6-4n Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 17:30:01 volumio cron[559]: 2024-10-22 17:30:01 1t3BDF-0000Q6-4n <= root@volumio U=root P=local S=580 Oct 22 17:30:01 volumio cron[559]: 2024-10-22 17:30:01 1t3BDF-0000Q6-4n Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 17:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 17:30:01 volumio exim[1618]: 2024-10-22 17:30:01 1t3BDF-0000Q6-4n <= root@volumio U=root P=local S=580 Oct 22 17:30:01 volumio exim[1618]: 2024-10-22 17:30:01 1t3BDF-0000Q6-4n Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 17:30:01 volumio exim[1618]: exim: could not open panic log - aborting: see message(s) above Oct 22 17:30:01 volumio CRON[1613]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 17:30:01 volumio CRON[1613]: pam_unix(cron:session): session closed for user root Oct 22 17:31:05 volumio systemd[1]: Started Run anacron jobs. Oct 22 17:31:05 volumio anacron[1644]: Anacron 2.3 started on 2024-10-22 Oct 22 17:31:05 volumio anacron[1644]: Normal exit (0 jobs run) Oct 22 17:31:05 volumio systemd[1]: anacron.service: Succeeded. Oct 22 18:00:01 volumio CRON[1855]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 18:00:01 volumio CRON[1856]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 18:00:01 volumio cron[559]: 2024-10-22 18:00:01 1t3BgH-0000Tz-74 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 18:00:01 volumio cron[559]: 2024-10-22 18:00:01 1t3BgH-0000Tz-74 <= root@volumio U=root P=local S=580 Oct 22 18:00:01 volumio cron[559]: 2024-10-22 18:00:01 1t3BgH-0000Tz-74 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 18:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 18:00:01 volumio exim[1859]: 2024-10-22 18:00:01 1t3BgH-0000Tz-74 <= root@volumio U=root P=local S=580 Oct 22 18:00:01 volumio exim[1859]: 2024-10-22 18:00:01 1t3BgH-0000Tz-74 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 18:00:01 volumio exim[1859]: exim: could not open panic log - aborting: see message(s) above Oct 22 18:00:01 volumio CRON[1855]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 18:00:01 volumio CRON[1855]: pam_unix(cron:session): session closed for user root Oct 22 18:17:01 volumio CRON[2000]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 18:17:01 volumio CRON[2001]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Oct 22 18:17:01 volumio CRON[2000]: pam_unix(cron:session): session closed for user root Oct 22 18:30:01 volumio CRON[2101]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 18:30:01 volumio CRON[2100]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 18:30:01 volumio CRON[2102]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi) Oct 22 18:30:01 volumio CRON[2103]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 18:30:01 volumio CRON[2100]: pam_unix(cron:session): session closed for user root Oct 22 18:30:01 volumio cron[559]: 2024-10-22 18:30:01 1t3C9J-0000Xy-AI Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 18:30:01 volumio cron[559]: 2024-10-22 18:30:01 1t3C9J-0000Xy-AI <= root@volumio U=root P=local S=580 Oct 22 18:30:01 volumio cron[559]: 2024-10-22 18:30:01 1t3C9J-0000Xy-AI Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 18:30:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 18:30:01 volumio exim[2106]: 2024-10-22 18:30:01 1t3C9J-0000Xy-AI <= root@volumio U=root P=local S=580 Oct 22 18:30:01 volumio exim[2106]: 2024-10-22 18:30:01 1t3C9J-0000Xy-AI Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 18:30:01 volumio exim[2106]: exim: could not open panic log - aborting: see message(s) above Oct 22 18:30:01 volumio CRON[2101]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 18:30:01 volumio CRON[2101]: pam_unix(cron:session): session closed for user root Oct 22 18:32:45 volumio systemd[1]: Started Run anacron jobs. Oct 22 18:32:45 volumio anacron[2136]: Anacron 2.3 started on 2024-10-22 Oct 22 18:32:45 volumio anacron[2136]: Normal exit (0 jobs run) Oct 22 18:32:45 volumio systemd[1]: anacron.service: Succeeded. Oct 22 19:00:01 volumio CRON[2351]: pam_unix(cron:session): session opened for user root by (uid=0) Oct 22 19:00:01 volumio CRON[2352]: (root) CMD (/Easeware/shell/check_mount_script.sh) Oct 22 19:00:01 volumio cron[559]: 2024-10-22 19:00:01 1t3CcL-0000bz-CZ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 19:00:01 volumio cron[559]: 2024-10-22 19:00:01 1t3CcL-0000bz-CZ <= root@volumio U=root P=local S=580 Oct 22 19:00:01 volumio cron[559]: 2024-10-22 19:00:01 1t3CcL-0000bz-CZ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 19:00:01 volumio cron[559]: exim: could not open panic log - aborting: see message(s) above Oct 22 19:00:01 volumio exim[2355]: 2024-10-22 19:00:01 1t3CcL-0000bz-CZ <= root@volumio U=root P=local S=580 Oct 22 19:00:01 volumio exim[2355]: 2024-10-22 19:00:01 1t3CcL-0000bz-CZ Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=120 egid=126 Oct 22 19:00:01 volumio exim[2355]: exim: could not open panic log - aborting: see message(s) above Oct 22 19:00:01 volumio CRON[2351]: (root) MAIL (mailed 16 bytes of output but got status 0x0001 from MTA ) Oct 22 19:00:01 volumio CRON[2351]: pam_unix(cron:session): session closed for user root Oct 22 19:01:19 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 19:01:19 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 19:01:19 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Oct 22 19:01:19 volumio sudo[2383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 19:01:19 volumio sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 19:01:19 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root Oct 22 19:01:19 volumio volumio[6135]: verbose: New Socket.io Connection to 10.10.200.61 from 10.10.1.132 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Total Clients: 3 Oct 22 19:01:19 volumio volumio[6135]: info: CoreCommandRouter::volumioGetState Oct 22 19:01:19 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:19 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 22 19:01:19 volumio volumio[6135]: info: CoreCommandRouter::volumioGetVisibleSources Oct 22 19:01:20 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 19:01:20 volumio volumio[6135]: info: Listing playlists Oct 22 19:01:20 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 22 19:01:20 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 22 19:01:20 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 22 19:01:20 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 19:01:22 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 19:01:23 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:27 volumio volumio[6135]: info: Retrieving Cloud Streaming UI Oct 22 19:01:27 volumio volumio[6135]: info: Getting Tidal Cloud Configuration Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:27 volumio volumio[6135]: info: Getting Qobuz Cloud Configuration Oct 22 19:01:27 volumio volumio[6135]: info: Asking plugin for UI Config Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:27 volumio volumio[6135]: info: Getting Spotify Cloud Configuration Oct 22 19:01:27 volumio volumio[6135]: info: Asking plugin for UI Config Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:27 volumio volumio[6135]: info: Saving Spotify Acccount Oct 22 19:01:27 volumio volumio[6135]: info: Got Tidal Cloud Configuration Oct 22 19:01:27 volumio volumio[6135]: info: Got it Oct 22 19:01:27 volumio volumio[6135]: info: Got it Oct 22 19:01:27 volumio volumio[6135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:27 volumio volumio[6135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 19:01:27 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand update Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 19:01:29 volumio volumio[6135]: info: Oct 22 19:01:29 volumio volumio[6135]: ---------------------------- MPD announces state update: update Oct 22 19:01:29 volumio volumio[6135]: info: sendMpdCommand update took 13 milliseconds Oct 22 19:01:29 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 19:01:29 volumio volumio[6135]: info: sendMpdCommand status took 5 milliseconds Oct 22 19:01:29 volumio volumio[6135]: info: sendMpdCommand status took 2 milliseconds Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 19:01:29 volumio volumio[6135]: info: Command Router : Notfying DB Updatetrue Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::Close All Modals sent Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 19:01:29 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 22 19:01:29 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 19:01:29 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 19:01:29 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:29 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:29 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:29 volumio volumio[6135]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"当真","artist":"蒋家驹（蒋蒋）/曲肖冰","album":"当真","uri":"NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3","trackType":"mp3"} Oct 22 19:01:29 volumio volumio[6135]: verbose: CURRENT POSITION 955 Oct 22 19:01:29 volumio volumio[6135]: info: CoreStateMachine::syncState stateService stop Oct 22 19:01:29 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 19:01:29 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:29 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:29 volumio volumio[6135]: info: No code Oct 22 19:01:29 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:29 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:29 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:29 volumio volumio[6135]: info: ------------------------------ 91ms Oct 22 19:01:31 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 19:01:35 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 19:01:38 volumio sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 22 19:01:38 volumio sudo[2398]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 19:01:38 volumio sudo[2398]: pam_unix(sudo:session): session closed for user root Oct 22 19:01:38 volumio sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 22 19:01:38 volumio sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 19:01:38 volumio sudo[2401]: pam_unix(sudo:session): session closed for user root Oct 22 19:01:38 volumio volumio[6135]: verbose: New Socket.io Connection to 10.10.200.61 from 10.10.1.132 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Edg/129.0.0.0 Total Clients: 3 Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:38 volumio volumio[6135]: info: Retrieving Cloud Streaming UI Oct 22 19:01:38 volumio volumio[6135]: info: Getting Tidal Cloud Configuration Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:38 volumio volumio[6135]: info: Getting Qobuz Cloud Configuration Oct 22 19:01:38 volumio volumio[6135]: info: Asking plugin for UI Config Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:38 volumio volumio[6135]: info: Getting Spotify Cloud Configuration Oct 22 19:01:38 volumio volumio[6135]: info: Asking plugin for UI Config Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:38 volumio volumio[6135]: info: Saving Spotify Acccount Oct 22 19:01:38 volumio volumio[6135]: info: Got Tidal Cloud Configuration Oct 22 19:01:38 volumio volumio[6135]: info: Got it Oct 22 19:01:38 volumio volumio[6135]: info: Got it Oct 22 19:01:38 volumio volumio[6135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:01:38 volumio volumio[6135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::volumioGetState Oct 22 19:01:38 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::volumioGetVisibleSources Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 19:01:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 22 19:01:40 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 19:01:40 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 22 19:01:42 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 19:01:43 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 19:01:43 volumio volumio[6135]: info: CURURI: albums:// Oct 22 19:01:43 volumio volumio[6135]: info: listAlbums - loading Albums from cache Oct 22 19:01:43 volumio volumio[6135]: info: CoreCommandRouter::volumioGetQueue Oct 22 19:01:43 volumio volumio[6135]: info: CoreStateMachine::getQueue Oct 22 19:01:43 volumio volumio[6135]: info: CorePlayQueue::getQueue Oct 22 19:01:43 volumio volumio[6135]: info: Listing playlists Oct 22 19:01:49 volumio volumio[6135]: Searching plugin music_service/mpd Oct 22 19:01:49 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 22 19:01:49 volumio volumio[6135]: info: All search sources collected, pushing search results Oct 22 19:01:52 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:52 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:52 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:52 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:52 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:53 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:54 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:54 volumio volumio[6135]: info: MPD Database updated - AlbumList cache refreshed Oct 22 19:01:54 volumio volumio[6135]: info: Oct 22 19:01:54 volumio volumio[6135]: ---------------------------- MPD announces state update: database Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 19:01:54 volumio volumio[6135]: info: Oct 22 19:01:54 volumio volumio[6135]: ---------------------------- MPD announces state update: update Oct 22 19:01:54 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 19:01:54 volumio volumio[6135]: info: ControllerMpd::getState Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand status Oct 22 19:01:54 volumio volumio[6135]: info: sendMpdCommand status took 244 milliseconds Oct 22 19:01:54 volumio volumio[6135]: info: sendMpdCommand status took 244 milliseconds Oct 22 19:01:54 volumio volumio[6135]: info: sendMpdCommand status took 244 milliseconds Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 19:01:54 volumio volumio[6135]: info: Command Router : Notfying DB Updatefalse Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::Close All Modals sent Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::parseState Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 22 19:01:54 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 22 19:01:54 volumio volumio[6135]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 19:01:54 volumio volumio[6135]: verbose: ControllerMpd::parseTrackInfo Oct 22 19:01:54 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: 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":"蒋家驹（蒋蒋）/曲肖冰","album":"当真","uri":"NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3","trackType":"mp3"} Oct 22 19:01:54 volumio volumio[6135]: verbose: CURRENT POSITION 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::syncState stateService stop Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:54 volumio volumio[6135]: info: No code Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:54 volumio volumio[6135]: info: ControllerMpd::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::servicePushState Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: 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":"蒋家驹（蒋蒋）/曲肖冰","album":"当真","uri":"NAS/AD/蒋家驹（蒋蒋）,曲肖冰 - 当真.mp3","trackType":"mp3"} Oct 22 19:01:54 volumio volumio[6135]: verbose: CURRENT POSITION 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::syncState stateService stop Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::syncState currentStatus stop Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:54 volumio volumio[6135]: info: No code Oct 22 19:01:54 volumio volumio[6135]: info: CoreStateMachine::pushState Oct 22 19:01:54 volumio volumio[6135]: info: CorePlayQueue::getTrack 955 Oct 22 19:01:54 volumio volumio[6135]: info: CoreCommandRouter::volumioPushState Oct 22 19:01:54 volumio volumio[6135]: info: ------------------------------ 345ms Oct 22 19:01:54 volumio volumio[6135]: info: ------------------------------ 346ms Oct 22 19:01:54 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:54 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:54 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:54 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:54 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:55 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:55 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:55 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:55 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:55 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:01:56 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 19:01:56 volumio volumio[6135]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 22 19:01:56 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 22 19:01:56 volumio volumio[6135]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 22 19:01:56 volumio volumio[6135]: info: Received Get System Version Oct 22 19:01:56 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 19:02:02 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lazer%20Boomerang/After%20Hours%20Vol%20I/5c2db080-cb40-42fb-bf59-0b84f4ec748f.jpg' Oct 22 19:02:02 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sleeping%20at%20Last/Atlas%3A%20Oceans/5a5653f7-f158-4c2d-a79c-aff32604ad7e.jpg' Oct 22 19:02:03 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A8%8B%E5%93%8D/%E5%9B%9B%E5%AD%A3%E4%BA%88%E4%BD%A0/d3597436-91b7-48d0-8bc4-400221856374.jpg' Oct 22 19:02:03 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gloria/Ave%20Maria/64f5989a-8edb-4808-a116-7e60786f16d6.jpg' Oct 22 19:02:03 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Taylor%20Swift/Carolina/d0f102e4-7f8b-4bd8-b23e-1f0ccbad68dd.jpg' Oct 22 19:02:04 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Taylor%20Swift/NOW%20That%27s%20What%20I%20Call%20Music%2C%20Vol.%2055/cb68689a-acc8-4bc5-aba5-9a6e5fa3afca.jpg' Oct 22 19:02:04 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Taylor%20Swift/reputation/92f42af7-0c36-4353-965c-7b9496be3628.jpg' Oct 22 19:02:04 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Nancy%20Kwai/You%20took%20my%20breath%20away/43034a93-d189-4943-a0fb-e8a58ccfec68.jpg' Oct 22 19:02:05 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8F%8A%E6%B1%A0%E6%A1%83%E5%AD%90/Broken%20Sunset/5a6d1098-8033-488b-a7b5-d3bde3163e38.jpg' Oct 22 19:02:05 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%AB%B9%E5%8E%9F%E3%83%94%E3%82%B9%E3%83%88%E3%83%AB/Forever%20Young/f077e416-6562-47fa-b049-61cf9772060a.jpg' Oct 22 19:02:06 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%8F%8A%E6%B1%A0%E6%A1%83%E5%AD%90/OCEAN%20SIDE/190e1a24-aba2-491f-b917-5ae2ff08c7e6.jpg' Oct 22 19:02:06 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Village%20People/Pure%20Disco/4edea175-7850-4740-b549-f7b5b434832b.jpg' Oct 22 19:02:06 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ReoNa/unknown/124dd8ce-68d3-4209-bf53-54461e363801.png' Oct 22 19:02:06 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hinano/%E3%83%B4%E3%82%A1%E3%83%BC%E3%82%B8%E3%83%8B%E3%82%A2/ea5eab22-ef90-42f8-b8a5-bacc3532f321.png' Oct 22 19:02:06 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ReoNa/%E3%81%AA%E3%81%84%E3%81%AA%E3%81%84%20(Special%20Edition)/27886c7b-d268-45d8-8c05-e3d1c4522fe1.jpg' Oct 22 19:02:06 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/back%20number/%E3%83%8F%E3%83%83%E3%83%94%E3%83%BC%E3%82%A8%E3%83%B3%E3%83%89/36eaad13-4ea1-4c49-aeab-ed4b814df5ba.jpg' Oct 22 19:02:07 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E3%82%8A%E3%82%8A%E3%81%82%E3%80%82/%E5%A4%B1%E6%81%8B%E3%82%BD%E3%83%B3%E3%82%B0%E6%B2%A2%E5%B1%B1%E8%81%B4%E3%81%84%E3%81%A6%20%E6%B3%A3%E3%81%84%E3%81%A6%E3%81%B0%E3%81%8B%E3%82%8A%E3%81%AE%E7%A7%81%E3%81%AF%E3%82%82%E3%81%86%E3%80%82/4867616e-1817-4117-8964-850ff2dcfc37.jpg' Oct 22 19:02:07 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%A7%8B%E5%B1%B1%E7%BE%8A%E5%AD%90/%E6%8C%87%E4%B8%80%E6%9C%AC%E3%81%A7%E5%80%92%E3%81%95%E3%82%8C%E3%82%8B%E3%81%A0%E3%82%8D%E3%81%86/2b3adafd-dbb3-412c-ba1e-5967c931b55f.jpg' Oct 22 19:02:07 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E6%89%8B%E5%B6%8C%E8%91%B5/%E6%98%8E%E6%97%A5%E3%81%B8%E3%81%AE%E6%89%8B%E7%B4%99(%E3%83%89%E3%83%A9%E3%83%9E%E3%83%90%E3%83%BC%E3%82%B8%E3%83%A7%E3%83%B3)/bf5a1e9a-fb48-4283-8e8b-814aa758336c.jpg' Oct 22 19:02:07 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%B8%83%E6%9C%88%E9%9A%86%E6%96%87/%E6%98%A8%E6%97%A5%E3%82%82%E3%80%81%E4%BB%8A%E6%97%A5%E3%82%82%E3%80%81%E6%98%8E%E6%97%A5%E3%81%8B%E3%82%89%E3%82%82%E3%80%82/080edbc7-2d76-4a22-be45-1edacef2ac4e.jpg' Oct 22 19:02:07 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E7%8E%8B%E8%8F%B2/%E6%B8%85%E5%B9%B3%E8%B0%83%20(%E7%8B%AC%E5%94%B1%E7%89%88)/bc75e92d-c8a4-4773-bbb1-958664bdf723.jpg' Oct 22 19:02:07 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 19:02:07 volumio volumio[6135]: info: CURURI: albums://%E6%A0%97%E6%A0%96%E3%82%86%E3%81%8D%E3%81%AA/THE%20KING%20OF%20FIGHTERS%20'97%20ARRANGE%20SOUND%20TRAX Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:02:09 volumio volumio[6135]: info: Retrieving Cloud Streaming UI Oct 22 19:02:09 volumio volumio[6135]: info: Getting Tidal Cloud Configuration Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:02:09 volumio volumio[6135]: info: Getting Qobuz Cloud Configuration Oct 22 19:02:09 volumio volumio[6135]: info: Asking plugin for UI Config Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:02:09 volumio volumio[6135]: info: Getting Spotify Cloud Configuration Oct 22 19:02:09 volumio volumio[6135]: info: Asking plugin for UI Config Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:02:09 volumio volumio[6135]: info: Saving Spotify Acccount Oct 22 19:02:09 volumio volumio[6135]: info: Got Tidal Cloud Configuration Oct 22 19:02:09 volumio volumio[6135]: info: Got it Oct 22 19:02:09 volumio volumio[6135]: info: Got it Oct 22 19:02:09 volumio volumio[6135]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 19:02:09 volumio volumio[6135]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 19:02:09 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 22 19:02:11 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E5%B7%8D/%E5%9C%A8%E5%88%AB%E5%A4%84/c007b33a-4b1f-4e06-a8d6-4c8b7b2924db.jpg' Oct 22 19:02:11 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/GALNERYUS/Angel%20of%20Salvation/f98813ee-a66d-4c8a-b947-805543f56b5e.png' Oct 22 19:02:11 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/GALNERYUS/Attitude%20to%20Live/cc2f49b8-4a4f-4e45-892d-18678bee7c97.jpg' Oct 22 19:02:12 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Plini/I/f266cf8f-f00d-41ba-befa-04ea11f206e7.jpg' Oct 22 19:02:12 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Novelists/Novelists%20Demo/99c4ba28-5e53-48ad-9218-52a9eb9458b7.jpg' Oct 22 19:02:12 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Born%20of%20Osiris/Tomorrow%20We%20Die%20%E2%88%86live/c3a7418c-d99c-45bc-a5f3-92722b3a32dd.png' Oct 22 19:02:12 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/SYU/YOU%20PLAY%20HARD/ae037615-000b-4bf2-873d-4b2d76780736.jpg' Oct 22 19:02:15 volumio volumio[6135]: Searching plugin music_service/mpd Oct 22 19:02:15 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 22 19:02:15 volumio volumio[6135]: info: All search sources collected, pushing search results Oct 22 19:02:15 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Oct 22 19:02:22 volumio volumio[6135]: Searching plugin music_service/mpd Oct 22 19:02:22 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 22 19:02:22 volumio volumio[6135]: info: All search sources collected, pushing search results Oct 22 19:02:23 volumio volumio[6135]: Searching plugin music_service/mpd Oct 22 19:02:23 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 22 19:02:23 volumio volumio[6135]: info: All search sources collected, pushing search results Oct 22 19:02:25 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 19:02:25 volumio volumio[6135]: info: CURURI: albums://%E5%AE%89%E4%BA%95%E9%82%A6%E5%BD%A6/THE%20KING%20OF%20FIGHTERS%20'97%20ARRANGE%20SOUND%20TRAX Oct 22 19:02:26 volumio volumio[6135]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AE%89%E4%BA%95%E9%82%A6%E5%BD%A6/THE%20KING%20OF%20FIGHTERS%20%2797%20ARRANGE%20SOUND%20TRAX/39f52a20-e2fb-46e4-ac07-68fda32e5517.jpg' Oct 22 19:02:34 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 19:02:34 volumio volumio[6135]: info: CURURI: albums://%E5%B1%B1%E6%B7%BB%E6%B5%A9%E5%8F%B2/THE%20KING%20OF%20FIGHTERS%20'97%20ARRANGE%20SOUND%20TRAX Oct 22 19:02:38 volumio volumio[6135]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 22 19:02:38 volumio volumio[6135]: info: CURURI: albums://%E5%B1%B1%E6%89%8B%E5%AE%89%E7%94%9F/THE%20KING%20OF%20FIGHTERS%20'97%20ARRANGE%20SOUND%20TRAX Oct 22 19:04:04 volumio volumio[6135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 19:04:04 volumio volumio[6135]: Error: connect ETIMEDOUT 168.143.162.58:443 Oct 22 19:04:04 volumio volumio[6135]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 22 19:04:04 volumio volumio[6135]: errno: -110, Oct 22 19:04:04 volumio volumio[6135]: code: 'ETIMEDOUT', Oct 22 19:04:04 volumio volumio[6135]: syscall: 'connect', Oct 22 19:04:04 volumio volumio[6135]: address: '168.143.162.58', Oct 22 19:04:04 volumio volumio[6135]: port: 443 Oct 22 19:04:04 volumio volumio[6135]: } Oct 22 19:04:04 volumio volumio[6135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 19:04:05 volumio sudo[2443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 11:03 Oct 22 19:04:05 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"