-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-10-16 11:09:37 UTC. -- Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:28 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:28 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:28 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:28 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:28 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:28 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:28 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::ClearQueue Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::stop Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::serviceStop Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::serviceStop Oct 16 11:09:35 volumio volumio[822]: info: Airplay Stop Oct 16 11:09:35 volumio volumio[822]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::clearPlayQueue Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::saveQueue Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::addQueueItems Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::addQueueItems Oct 16 11:09:35 volumio volumio[822]: info: Adding Item to queue: music-library/USB/Backup/Music/Bizet - Carmen - Karajan (1982) Oct 16 11:09:35 volumio volumio[822]: info: Exploding uri music-library/USB/Backup/Music/Bizet - Carmen - Karajan (1982) in service mpd Oct 16 11:09:35 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1002 Oct 16 11:09:35 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 11:09:35 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root Oct 16 11:09:35 volumio volumio[822]: info: ALBUMART /albumart?cacheid=492&web=/Bizet%20-%20Carmen%2C%20Berliner%20Philharmoniker%2C%20Ltg.%20CD1/extralarge&path=%2Fmnt%2FUSB%2FBackup%2FMusic%2FBizet%20-%20Carmen%20-%20Karajan%20(1982)%2FCD1%2FCD1.ape&metadata=false Oct 16 11:09:35 volumio volumio[822]: info: URI /mnt/USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD1/CD1.ape Oct 16 11:09:35 volumio volumio[822]: info: ALBUMART /albumart?cacheid=492&web=/Bizet%20-%20Carmen%2C%20Berliner%20Philharmoniker%2C%20Ltg.%20CD2/extralarge&path=%2Fmnt%2FUSB%2FBackup%2FMusic%2FBizet%20-%20Carmen%20-%20Karajan%20(1982)%2FCD2%2FCD2.ape&metadata=false Oct 16 11:09:35 volumio volumio[822]: info: URI /mnt/USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD2/CD2.ape Oct 16 11:09:35 volumio volumio[822]: info: ALBUMART /albumart?cacheid=492&web=/Carmen%20CD3/extralarge&path=%2Fmnt%2FUSB%2FBackup%2FMusic%2FBizet%20-%20Carmen%20-%20Karajan%20(1982)%2FCD3%2FCD3.ape&metadata=false Oct 16 11:09:35 volumio volumio[822]: info: URI /mnt/USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD3/CD3.ape Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::saveQueue Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::getTrackBlock Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::volumioPlay Oct 16 11:09:35 volumio volumio[822]: verbose: UNSET VOLATILE: Service: airplay_emulation Oct 16 11:09:35 volumio volumio[822]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::play index 0 Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::stop Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::stPlaybackTimer Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::getTrackBlock Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::serviceStop Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:35 volumio volumio[822]: info: CoreCommandRouter::serviceStop Oct 16 11:09:35 volumio volumio[822]: info: ControllerMpd::stop Oct 16 11:09:35 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 11:09:35 volumio volumio[822]: info: Shairport-Sync paused with USR2 Oct 16 11:09:35 volumio volumio[822]: info: sendMpdCommand stop took 8 milliseconds Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::play index undefined Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:35 volumio volumio[822]: info: CoreStateMachine::startPlaybackTimer Oct 16 11:09:35 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:35 volumio volumio[822]: verbose: ControllerMpd::clearAddPlayTracks USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD1/CD1.ape Oct 16 11:09:35 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 11:09:35 volumio volumio[822]: info: sendMpdCommand stop took 6 milliseconds Oct 16 11:09:35 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 11:09:35 volumio volumio[822]: info: Oct 16 11:09:35 volumio volumio[822]: ---------------------------- MPD announces system playlist update Oct 16 11:09:35 volumio volumio[822]: info: Ignoring MPD Status Update Oct 16 11:09:35 volumio volumio[822]: info: sendMpdCommand clear took 4 milliseconds Oct 16 11:09:35 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand add "USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD1/CD1.ape" Oct 16 11:09:35 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1002 Oct 16 11:09:35 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 11:09:35 volumio volumio[822]: error: updateQueue error: null Oct 16 11:09:35 volumio volumio[822]: info: Oct 16 11:09:35 volumio volumio[822]: ---------------------------- MPD announces system playlist update Oct 16 11:09:35 volumio volumio[822]: info: Ignoring MPD Status Update Oct 16 11:09:35 volumio volumio[822]: info: ------------------------------ 9ms Oct 16 11:09:35 volumio volumio[822]: info: sendMpdCommand add "USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD1/CD1.ape" took 7 milliseconds Oct 16 11:09:35 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand play Oct 16 11:09:35 volumio volumio[822]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 11:09:35 volumio volumio[822]: info: ------------------------------ 4ms Oct 16 11:09:35 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Oct 16 11:09:35 volumio volumio[822]: info: sendMpdCommand play took 7 milliseconds Oct 16 11:09:35 volumio volumio[822]: info: Shairport-Sync paused with USR2 Oct 16 11:09:36 volumio volumio[822]: info: Oct 16 11:09:36 volumio volumio[822]: ---------------------------- MPD announces state update: player Oct 16 11:09:36 volumio volumio[822]: info: ControllerMpd::getState Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 16 11:09:36 volumio volumio[822]: info: Oct 16 11:09:36 volumio volumio[822]: ---------------------------- MPD announces state update: player Oct 16 11:09:36 volumio volumio[822]: info: sendMpdCommand status took 4 milliseconds Oct 16 11:09:36 volumio volumio[822]: info: ControllerMpd::getState Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 11:09:36 volumio volumio[822]: info: sendMpdCommand status took 3 milliseconds Oct 16 11:09:36 volumio volumio[822]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::parseState Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo Oct 16 11:09:36 volumio volumio[822]: info: ControllerMpd::pushState Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:36 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:36 volumio volumio[822]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3455,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"D:\\Karajan, Baltsa, Carreras, van Dam, Ricciarelli - Bizet - Carmen, Berliner Philharmoniker, Ltg. CD1","artist":null,"album":"Bizet - Carmen, Berliner Philharmoniker, Ltg. CD1","uri":"USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD1/CD1.ape","trackType":"ape"} Oct 16 11:09:36 volumio volumio[822]: verbose: CURRENT POSITION 0 Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::syncState stateService play Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::syncState currentStatus stop Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:36 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:36 volumio volumio[822]: info: ------------------------------ 21ms Oct 16 11:09:36 volumio volumio[822]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 16 11:09:36 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo Oct 16 11:09:36 volumio volumio[822]: info: ControllerMpd::pushState Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::servicePushState Oct 16 11:09:36 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:36 volumio volumio[822]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3455,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"D:\\Karajan, Baltsa, Carreras, van Dam, Ricciarelli - Bizet - Carmen, Berliner Philharmoniker, Ltg. CD1","artist":null,"album":"Bizet - Carmen, Berliner Philharmoniker, Ltg. CD1","uri":"USB/Backup/Music/Bizet - Carmen - Karajan (1982)/CD1/CD1.ape","trackType":"ape"} Oct 16 11:09:36 volumio volumio[822]: verbose: CURRENT POSITION 0 Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::syncState stateService play Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::syncState currentStatus play Oct 16 11:09:36 volumio volumio[822]: info: Received an update from plugin. extracting info from payload Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:36 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:36 volumio volumio[822]: info: CoreStateMachine::pushState Oct 16 11:09:36 volumio volumio[822]: info: CorePlayQueue::getTrack 0 Oct 16 11:09:36 volumio volumio[822]: info: CoreCommandRouter::volumioPushState Oct 16 11:09:36 volumio volumio[822]: info: ------------------------------ 29ms Oct 16 11:09:36 volumio volumio[822]: info: Restarting Shairport-Sync after stop Oct 16 11:09:36 volumio sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 11:09:36 volumio sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 11:09:36 volumio volumio[822]: info: Restarting Shairport-Sync after stop Oct 16 11:09:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 11:09:36 volumio shairport-sync[1002]: {"time":1729076771678,"response":"startAirplayPlayback Success"} Oct 16 11:09:36 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 16 11:09:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 11:09:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 11:09:36 volumio sudo[1266]: pam_unix(sudo:session): session closed for user root Oct 16 11:09:36 volumio volumio[822]: info: Shairport-Sync retarted Oct 16 11:09:36 volumio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 16 11:09:36 volumio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 11:09:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 16 11:09:36 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 16 11:09:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 16 11:09:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 16 11:09:36 volumio sudo[1269]: pam_unix(sudo:session): session closed for user root Oct 16 11:09:36 volumio volumio[822]: info: Shairport-Sync retarted Oct 16 11:09:37 volumio volumio[822]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 11:09:37 volumio volumio[822]: Error: connect ETIMEDOUT 128.121.243.228:443 Oct 16 11:09:37 volumio volumio[822]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 16 11:09:37 volumio volumio[822]: errno: -110, Oct 16 11:09:37 volumio volumio[822]: code: 'ETIMEDOUT', Oct 16 11:09:37 volumio volumio[822]: syscall: 'connect', Oct 16 11:09:37 volumio volumio[822]: address: '128.121.243.228', Oct 16 11:09:37 volumio volumio[822]: port: 443 Oct 16 11:09:37 volumio volumio[822]: } Oct 16 11:09:37 volumio volumio[822]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 11:09:37 volumio sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-16 11:08 Oct 16 11:09:37 volumio sudo[1286]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"