-- Logs begin at Sat 2024-04-06 00:28:46 UTC, end at Sat 2024-04-06 13:40:53 UTC. -- Apr 06 13:39:01 agitel-soundbox CRON[24978]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 06 13:39:01 agitel-soundbox CRON[24979]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 06 13:39:01 agitel-soundbox CRON[24986]: (root) CMD (/home/scripts/forceplay.sh) Apr 06 13:39:01 agitel-soundbox CRON[24987]: (root) CMD ((sleep 30; /home/scripts/forceplay.sh)) Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Apr 06 13:39:01 agitel-soundbox volumio[1463]: ---------------------------- Client requests Volumio play Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPlay Apr 06 13:39:01 agitel-soundbox volumio[1463]: UNSET VOLATILE Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::play index undefined Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::startPlaybackTimer Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::clearAddPlayTracks USB/1da Banton - Ego.mp3 Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand stop Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand clear Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Apr 06 13:39:01 agitel-soundbox volumio[1463]: ---------------------------- MPD announces system playlist update Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Ignoring MPD Status Update Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand add "USB/1da Banton - Ego.mp3" Apr 06 13:39:01 agitel-soundbox volumio[1463]: error: updateQueue error: null Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Apr 06 13:39:01 agitel-soundbox volumio[1463]: ---------------------------- MPD announces system playlist update Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Ignoring MPD Status Update Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ------------------------------ 3ms Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand play Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ------------------------------ 5ms Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Apr 06 13:39:01 agitel-soundbox volumio[1463]: ---------------------------- MPD announces state update: player Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ControllerMpd::getState Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand status Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Apr 06 13:39:01 agitel-soundbox volumio[1463]: ---------------------------- MPD announces state update: player Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ControllerMpd::getState Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand status Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::parseState Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::parseState Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::parseTrackInfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ControllerMpd::pushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::servicePushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ego","artist":"1da Banton","album":"Ego","uri":"USB/1da Banton - Ego.mp3","trackType":"mp3"} Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: CURRENT POSITION 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::syncState stateService play Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::syncState currentStatus stop Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::pushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ------------------------------ 15ms Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: ControllerMpd::parseTrackInfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ControllerMpd::pushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::servicePushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: STATE SERVICE {"status":"play","position":0,"seek":208,"duration":155,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Ego","artist":"1da Banton","album":"Ego","uri":"USB/1da Banton - Ego.mp3","trackType":"mp3"} Apr 06 13:39:01 agitel-soundbox volumio[1463]: verbose: CURRENT POSITION 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::syncState stateService play Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::syncState currentStatus play Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: Received an update from plugin. extracting info from payload Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::pushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::pushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPushState Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 13:39:01 agitel-soundbox volumio[1463]: info: ------------------------------ 27ms Apr 06 13:39:03 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioGetState Apr 06 13:39:03 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:04 agitel-soundbox cron[636]: 2024-04-06 13:39:04 1rt6G7-0006VJ-V0 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:39:04 agitel-soundbox exim[25005]: 2024-04-06 13:39:04 1rt6G7-0006VJ-V0 <= root@localhost U=root P=local S=939 Apr 06 13:39:04 agitel-soundbox exim[25005]: 2024-04-06 13:39:04 1rt6G7-0006VJ-V0 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:39:04 agitel-soundbox exim[25005]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:39:04 agitel-soundbox cron[636]: 2024-04-06 13:39:04 1rt6G7-0006VJ-V0 <= root@localhost U=root P=local S=939 Apr 06 13:39:04 agitel-soundbox cron[636]: 2024-04-06 13:39:04 1rt6G7-0006VJ-V0 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:39:04 agitel-soundbox cron[636]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:39:04 agitel-soundbox CRON[24979]: (root) MAIL (mailed 368 bytes of output but got status 0x0001 from MTA ) Apr 06 13:39:04 agitel-soundbox CRON[24979]: pam_unix(cron:session): session closed for user root Apr 06 13:39:31 agitel-soundbox volumio[1463]: info: Apr 06 13:39:31 agitel-soundbox volumio[1463]: ---------------------------- Client requests Volumio play Apr 06 13:39:31 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPlay Apr 06 13:39:31 agitel-soundbox volumio[1463]: UNSET VOLATILE Apr 06 13:39:31 agitel-soundbox volumio[1463]: info: CoreStateMachine::play index undefined Apr 06 13:39:31 agitel-soundbox volumio[1463]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 13:39:31 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:33 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioGetState Apr 06 13:39:33 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:39:34 agitel-soundbox cron[636]: 2024-04-06 13:39:34 1rt6Gb-0006WX-Vd Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:39:34 agitel-soundbox cron[636]: 2024-04-06 13:39:34 1rt6Gb-0006WX-Vd <= root@localhost U=root P=local S=951 Apr 06 13:39:34 agitel-soundbox cron[636]: 2024-04-06 13:39:34 1rt6Gb-0006WX-Vd Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:39:34 agitel-soundbox exim[25081]: 2024-04-06 13:39:34 1rt6Gb-0006WX-Vd <= root@localhost U=root P=local S=951 Apr 06 13:39:34 agitel-soundbox exim[25081]: 2024-04-06 13:39:34 1rt6Gb-0006WX-Vd Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:39:34 agitel-soundbox exim[25081]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:39:34 agitel-soundbox cron[636]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:39:34 agitel-soundbox CRON[24978]: (root) MAIL (mailed 368 bytes of output but got status 0x0001 from MTA ) Apr 06 13:39:34 agitel-soundbox CRON[24978]: pam_unix(cron:session): session closed for user root Apr 06 13:39:45 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 13:39:45 agitel-soundbox volumio[1463]: info: CURURI: playlists Apr 06 13:39:45 agitel-soundbox volumio[1463]: info: Listing playlists Apr 06 13:39:48 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 13:39:48 agitel-soundbox volumio[1463]: info: CURURI: music-library Apr 06 13:39:53 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 13:39:53 agitel-soundbox volumio[1463]: info: CURURI: artists:// Apr 06 13:40:01 agitel-soundbox CRON[25143]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 06 13:40:01 agitel-soundbox CRON[25142]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 06 13:40:01 agitel-soundbox CRON[25150]: (root) CMD ((sleep 30; /home/scripts/forceplay.sh)) Apr 06 13:40:01 agitel-soundbox CRON[25151]: (root) CMD (/home/scripts/forceplay.sh) Apr 06 13:40:01 agitel-soundbox volumio[1463]: info: Apr 06 13:40:01 agitel-soundbox volumio[1463]: ---------------------------- Client requests Volumio play Apr 06 13:40:01 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPlay Apr 06 13:40:01 agitel-soundbox volumio[1463]: UNSET VOLATILE Apr 06 13:40:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::play index undefined Apr 06 13:40:01 agitel-soundbox volumio[1463]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 13:40:01 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:40:03 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioGetState Apr 06 13:40:03 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:40:03 agitel-soundbox cron[636]: 2024-04-06 13:40:03 1rt6H5-0006YC-C9 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:03 agitel-soundbox exim[25184]: 2024-04-06 13:40:03 1rt6H5-0006YC-C9 <= root@localhost U=root P=local S=939 Apr 06 13:40:03 agitel-soundbox exim[25184]: 2024-04-06 13:40:03 1rt6H5-0006YC-C9 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:03 agitel-soundbox exim[25184]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:40:03 agitel-soundbox cron[636]: 2024-04-06 13:40:03 1rt6H5-0006YC-C9 <= root@localhost U=root P=local S=939 Apr 06 13:40:03 agitel-soundbox cron[636]: 2024-04-06 13:40:03 1rt6H5-0006YC-C9 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:03 agitel-soundbox cron[636]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:40:03 agitel-soundbox CRON[25143]: (root) MAIL (mailed 368 bytes of output but got status 0x0001 from MTA ) Apr 06 13:40:03 agitel-soundbox CRON[25143]: pam_unix(cron:session): session closed for user root Apr 06 13:40:11 agitel-soundbox volumio[1463]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 13:40:11 agitel-soundbox volumio[1463]: info: CURURI: albums:// Apr 06 13:40:11 agitel-soundbox volumio[1463]: listAlbums - loading Albums from cache Apr 06 13:40:31 agitel-soundbox volumio[1463]: info: Apr 06 13:40:31 agitel-soundbox volumio[1463]: ---------------------------- Client requests Volumio play Apr 06 13:40:31 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioPlay Apr 06 13:40:31 agitel-soundbox volumio[1463]: UNSET VOLATILE Apr 06 13:40:31 agitel-soundbox volumio[1463]: info: CoreStateMachine::play index undefined Apr 06 13:40:31 agitel-soundbox volumio[1463]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 13:40:31 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:40:33 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioGetState Apr 06 13:40:33 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:40:33 agitel-soundbox cron[636]: 2024-04-06 13:40:33 1rt6HZ-0006ZQ-Ba Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:33 agitel-soundbox exim[25260]: 2024-04-06 13:40:33 1rt6HZ-0006ZQ-Ba <= root@localhost U=root P=local S=951 Apr 06 13:40:33 agitel-soundbox exim[25260]: 2024-04-06 13:40:33 1rt6HZ-0006ZQ-Ba Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:33 agitel-soundbox exim[25260]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:40:33 agitel-soundbox cron[636]: 2024-04-06 13:40:33 1rt6HZ-0006ZQ-Ba <= root@localhost U=root P=local S=951 Apr 06 13:40:33 agitel-soundbox cron[636]: 2024-04-06 13:40:33 1rt6HZ-0006ZQ-Ba Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:33 agitel-soundbox cron[636]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:40:33 agitel-soundbox CRON[25142]: (root) MAIL (mailed 368 bytes of output but got status 0x0001 from MTA ) Apr 06 13:40:33 agitel-soundbox CRON[25142]: pam_unix(cron:session): session closed for user root Apr 06 13:40:33 agitel-soundbox volumio[1463]: info: CoreCommandRouter::volumioGetState Apr 06 13:40:33 agitel-soundbox volumio[1463]: info: CorePlayQueue::getTrack 0 Apr 06 13:40:33 agitel-soundbox cron[636]: 2024-04-06 13:40:33 1rt6HZ-0006Za-Jo Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:33 agitel-soundbox cron[636]: 2024-04-06 13:40:33 1rt6HZ-0006Za-Jo <= root@localhost U=root P=local S=10338 Apr 06 13:40:33 agitel-soundbox cron[636]: 2024-04-06 13:40:33 1rt6HZ-0006Za-Jo Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:33 agitel-soundbox exim[25270]: 2024-04-06 13:40:33 1rt6HZ-0006Za-Jo <= root@localhost U=root P=local S=10338 Apr 06 13:40:33 agitel-soundbox exim[25270]: 2024-04-06 13:40:33 1rt6HZ-0006Za-Jo Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=114 egid=120 Apr 06 13:40:33 agitel-soundbox exim[25270]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:40:33 agitel-soundbox cron[636]: exim: could not open panic log - aborting: see message(s) above Apr 06 13:40:33 agitel-soundbox CRON[24791]: (root) MAIL (mailed 9755 bytes of output but got status 0x0001 from MTA ) Apr 06 13:40:33 agitel-soundbox CRON[24791]: pam_unix(cron:session): session closed for user root Apr 06 13:40:52 agitel-soundbox volumio[1463]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 13:40:52 agitel-soundbox volumio[1463]: { Error: connect ETIMEDOUT 199.232.170.79:80 Apr 06 13:40:52 agitel-soundbox volumio[1463]: at Object._errnoException (util.js:1022:11) Apr 06 13:40:52 agitel-soundbox volumio[1463]: at _exceptionWithHostPort (util.js:1044:20) Apr 06 13:40:52 agitel-soundbox volumio[1463]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Apr 06 13:40:52 agitel-soundbox volumio[1463]: code: 'ETIMEDOUT', Apr 06 13:40:52 agitel-soundbox volumio[1463]: errno: 'ETIMEDOUT', Apr 06 13:40:52 agitel-soundbox volumio[1463]: syscall: 'connect', Apr 06 13:40:52 agitel-soundbox volumio[1463]: address: '199.232.170.79', Apr 06 13:40:52 agitel-soundbox volumio[1463]: port: 80 } Apr 06 13:40:52 agitel-soundbox volumio[1463]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 13:40:53 agitel-soundbox sudo[25322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 13:39 Apr 06 13:40:53 agitel-soundbox sudo[25322]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="03f7bbd984817e093cac62b7ff876f3c2cfabc1d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Dec 22 14:39:52 CET 2020" VOLUMIO_VERSION="2.861" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="097e28ee6263e31d0bf6b690a02dcab3"