-- Logs begin at Sun 2025-05-11 08:22:19 UTC, end at Tue 2025-05-13 19:32:53 UTC. -- May 13 19:31:21 bplayer volumio[1038]: info: CoreCommandRouter::volumioPlay May 13 19:31:21 bplayer volumio[1038]: verbose: UNSET VOLATILE May 13 19:31:21 bplayer volumio[1038]: info: Stopping Airplay Playback and sending pause command to client via USR2 May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::play index 19 May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::stop May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 19:31:21 bplayer volumio[1038]: verbose: UNSET VOLATILE May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::stPlaybackTimer May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::updateTrackBlock May 13 19:31:21 bplayer volumio[1038]: info: CorePlayQueue::getTrackBlock May 13 19:31:21 bplayer volumio[1038]: info: CoreStateMachine::pushState May 13 19:31:21 bplayer volumio[1038]: info: CorePlayQueue::getTrack 0 May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::volumioPushState May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::serviceStop May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 0 May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::serviceStop May 13 19:31:22 bplayer volumio[1038]: info: ControllerMpd::stop May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop May 13 19:31:22 bplayer sudo[11838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 19247 May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand stop took 14 milliseconds May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::play index undefined May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::setConsumeUpdateService undefined May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::startPlaybackTimer May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::clearAddPlayTracks NAS/AirDisk/Hires Download/01N7-青春照相馆-皇后音乐社/05.一封侨批.flac May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand stop May 13 19:31:22 bplayer sudo[11838]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand stop took 7 milliseconds May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand clear May 13 19:31:22 bplayer volumio[1038]: info: May 13 19:31:22 bplayer volumio[1038]: ---------------------------- MPD announces system playlist update May 13 19:31:22 bplayer volumio[1038]: info: Ignoring MPD Status Update May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand clear took 2 milliseconds May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand add "NAS/AirDisk/Hires Download/01N7-青春照相馆-皇后音乐社/05.一封侨批.flac" May 13 19:31:22 bplayer volumio[1038]: error: updateQueue error: null May 13 19:31:22 bplayer volumio[1038]: info: May 13 19:31:22 bplayer volumio[1038]: ---------------------------- MPD announces system playlist update May 13 19:31:22 bplayer volumio[1038]: info: Ignoring MPD Status Update May 13 19:31:22 bplayer volumio[1038]: info: ------------------------------ 6ms May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand add "NAS/AirDisk/Hires Download/01N7-青春照相馆-皇后音乐社/05.一封侨批.flac" took 5 milliseconds May 13 19:31:22 bplayer sudo[11838]: pam_unix(sudo:session): session closed for user root May 13 19:31:22 bplayer systemd[1]: shairport-sync.service: main process exited, code=killed, status=12/USR2 May 13 19:31:22 bplayer systemd[1]: Unit shairport-sync.service entered failed state. May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand play May 13 19:31:22 bplayer volumio[1038]: info: ------------------------------ 6ms May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand play took 5 milliseconds May 13 19:31:22 bplayer shairport-sync[19247]: {"time":1747149196110,"response":"airplayInactive Success"} May 13 19:31:22 bplayer volumio[1038]: info: Shairport-Sync paused with USR2 May 13 19:31:22 bplayer volumio[1038]: info: May 13 19:31:22 bplayer volumio[1038]: ---------------------------- MPD announces state update: player May 13 19:31:22 bplayer kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 13 19:31:22 bplayer volumio[1038]: info: ControllerMpd::getState May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand status May 13 19:31:22 bplayer volumio[1038]: info: May 13 19:31:22 bplayer volumio[1038]: ---------------------------- MPD announces state update: player May 13 19:31:22 bplayer volumio[1038]: info: ControllerMpd::getState May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand status May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand status took 3 milliseconds May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::parseState May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand status took 3 milliseconds May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand playlistinfo took 2 milliseconds May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::parseState May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::parseTrackInfo May 13 19:31:22 bplayer volumio[1038]: info: ControllerMpd::pushState May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::servicePushState May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"621 Kbps","isStreaming":false,"title":"一封侨批: 一封侨批","artist":"玩具船长","album":"青春照相馆","uri":"NAS/AirDisk/Hires Download/01N7-青春照相馆-皇后音乐社/05.一封侨批.flac","trackType":"flac"} May 13 19:31:22 bplayer volumio[1038]: verbose: CURRENT POSITION 19 May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::syncState stateService play May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::syncState currentStatus stop May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::pushState May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::volumioPushState May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:31:22 bplayer volumio[1038]: info: ------------------------------ 21ms May 13 19:31:22 bplayer volumio[1038]: info: sendMpdCommand playlistinfo took 13 milliseconds May 13 19:31:22 bplayer volumio[1038]: verbose: ControllerMpd::parseTrackInfo May 13 19:31:22 bplayer volumio[1038]: info: ControllerMpd::pushState May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::servicePushState May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":312,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"621 Kbps","isStreaming":false,"title":"一封侨批: 一封侨批","artist":"玩具船长","album":"青春照相馆","uri":"NAS/AirDisk/Hires Download/01N7-青春照相馆-皇后音乐社/05.一封侨批.flac","trackType":"flac"} May 13 19:31:22 bplayer volumio[1038]: verbose: CURRENT POSITION 19 May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::syncState stateService play May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::syncState currentStatus play May 13 19:31:22 bplayer volumio[1038]: info: Received an update from plugin. extracting info from payload May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::pushState May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::volumioPushState May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:31:22 bplayer volumio[1038]: info: CoreStateMachine::pushState May 13 19:31:22 bplayer volumio[1038]: info: CorePlayQueue::getTrack 19 May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::volumioPushState May 13 19:31:22 bplayer volumio[1038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 19:31:22 bplayer volumio[1038]: info: ------------------------------ 36ms May 13 19:31:23 bplayer volumio[1038]: info: Restarting Shairport-Sync after stop May 13 19:31:23 bplayer sudo[11850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 19:31:23 bplayer sudo[11850]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 19:31:23 bplayer systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 13 19:31:23 bplayer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 19:31:23 bplayer sudo[11850]: pam_unix(sudo:session): session closed for user root May 13 19:31:23 bplayer volumio[1038]: info: Shairport-Sync retarted May 13 19:32:01 bplayer systemd[1]: Starting Cleanup of Temporary Directories... May 13 19:32:01 bplayer systemd[1]: Started Cleanup of Temporary Directories. May 13 19:32:52 bplayer volumio[1038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 19:32:52 bplayer volumio[1038]: { Error: socket hang up May 13 19:32:52 bplayer volumio[1038]: at TLSSocket.onHangUp (_tls_wrap.js:1137:19) May 13 19:32:52 bplayer volumio[1038]: at Object.onceWrapper (events.js:313:30) May 13 19:32:52 bplayer volumio[1038]: at emitNone (events.js:111:20) May 13 19:32:52 bplayer volumio[1038]: at TLSSocket.emit (events.js:208:7) May 13 19:32:52 bplayer volumio[1038]: at endReadableNT (_stream_readable.js:1064:12) May 13 19:32:52 bplayer volumio[1038]: at _combinedTickCallback (internal/process/next_tick.js:138:11) May 13 19:32:52 bplayer volumio[1038]: at process._tickCallback (internal/process/next_tick.js:180:9) May 13 19:32:52 bplayer volumio[1038]: code: 'ECONNRESET', May 13 19:32:52 bplayer volumio[1038]: path: null, May 13 19:32:52 bplayer volumio[1038]: host: 'lastfm.freetls.fastly.net', May 13 19:32:52 bplayer volumio[1038]: port: 443, May 13 19:32:52 bplayer volumio[1038]: localAddress: undefined } May 13 19:32:52 bplayer volumio[1038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 19:32:53 bplayer sudo[12098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 19:31 May 13 19:32:53 bplayer sudo[12098]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"