-- Logs begin at Thu 2019-02-14 18:12:05 CST, end at Thu 2026-05-28 10:17:23 CST. -- May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 28 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume28 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:00 volumio volumio[2256]: [158B blob data] May 28 10:16:00 volumio volumio[2256]: {"time":1779934560068,"response":"volume Success"}28 May 28 10:16:00 volumio volumio[2256]: 24 May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 24 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume24 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:00 volumio volumio[2256]: [158B blob data] May 28 10:16:00 volumio volumio[2256]: {"time":1779934560214,"response":"volume Success"}24 May 28 10:16:00 volumio volumio[2256]: 19 May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 19 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume19 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:00 volumio volumio[2256]: [237B blob data] May 28 10:16:00 volumio volumio[2256]: {"time":1779934560358,"response":"volume Success"}19 May 28 10:16:00 volumio volumio[2256]: 6 May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 6 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume6 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:00 volumio volumio[2256]: [158B blob data] May 28 10:16:00 volumio volumio[2256]: {"time":1779934560497,"response":"volume Success"}6 May 28 10:16:00 volumio volumio[2256]: 14 May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 14 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume14 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:00 volumio volumio[2256]: [158B blob data] May 28 10:16:00 volumio volumio[2256]: {"time":1779934560635,"response":"volume Success"}14 May 28 10:16:00 volumio volumio[2256]: 3 May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 3 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume3 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:00 volumio volumio[2256]: [158B blob data] May 28 10:16:00 volumio volumio[2256]: {"time":1779934560775,"response":"volume Success"}3 May 28 10:16:00 volumio volumio[2256]: 0 May 28 10:16:00 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:00 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:00 volumio volumio[1941]: info: May 28 10:16:00 volumio volumio[1941]: ---------------------------- Client requests Volume 1 May 28 10:16:00 volumio volumio[1941]: info: VolumeController::SetAlsaVolume1 May 28 10:16:00 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:00 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:01 volumio volumio[2256]: [158B blob data] May 28 10:16:01 volumio volumio[2256]: {"time":1779934560962,"response":"volume Success"}1 May 28 10:16:01 volumio volumio[1941]: info: May 28 10:16:01 volumio volumio[1941]: ---------------------------- MPD announces state update: player May 28 10:16:01 volumio volumio[1941]: info: ControllerMpd::getState May 28 10:16:01 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status May 28 10:16:01 volumio volumio[1941]: info: sendMpdCommand status took 4 milliseconds May 28 10:16:01 volumio volumio[1941]: verbose: ControllerMpd::parseState May 28 10:16:01 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 10:16:01 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 10:16:01 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo May 28 10:16:01 volumio volumio[1941]: info: ControllerMpd::pushState May 28 10:16:01 volumio volumio[1941]: info: CoreCommandRouter::servicePushState May 28 10:16:01 volumio volumio[2256]: 10 May 28 10:16:01 volumio volumio[1941]: verbose: In UPNP mode May 28 10:16:01 volumio volumio[1941]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":638742,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"756 Kbps","isStreaming":false,"title":"bridge-41.flac","artist":null,"album":null,"uri":"http://192.168.5.117:45403/bridge-41.flac","trackType":"flac"} May 28 10:16:01 volumio volumio[1941]: verbose: CURRENT POSITION 0 May 28 10:16:01 volumio volumio[1941]: info: CoreStateMachine::syncState stateService pause May 28 10:16:01 volumio volumio[1941]: info: CoreStateMachine::syncState currentStatus play May 28 10:16:01 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:01 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:01 volumio volumio[1941]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 28 10:16:01 volumio volumio[1941]: info: ------------------------------ 28ms May 28 10:16:01 volumio volumio[2256]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:16:01 volumio volumio[2256]: Dload Upload Total Spent Left Speed May 28 10:16:01 volumio volumio[1941]: info: May 28 10:16:01 volumio volumio[1941]: ---------------------------- Client requests Volume 10 May 28 10:16:01 volumio volumio[1941]: info: VolumeController::SetAlsaVolume10 May 28 10:16:01 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:01 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:01 volumio volumio[2256]: [158B blob data] May 28 10:16:01 volumio volumio[2256]: {"time":1779934561219,"response":"volume Success"}10 May 28 10:16:28 volumio volumio[1941]: info: May 28 10:16:28 volumio volumio[1941]: ---------------------------- MPD announces state update: player May 28 10:16:28 volumio volumio[1941]: info: ControllerMpd::getState May 28 10:16:28 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand status May 28 10:16:28 volumio volumio[1941]: info: sendMpdCommand status took 3 milliseconds May 28 10:16:28 volumio volumio[1941]: verbose: ControllerMpd::parseState May 28 10:16:28 volumio volumio[1941]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 10:16:28 volumio volumio[1941]: info: sendMpdCommand playlistinfo took 3 milliseconds May 28 10:16:28 volumio volumio[1941]: verbose: ControllerMpd::parseTrackInfo May 28 10:16:28 volumio volumio[1941]: info: ControllerMpd::pushState May 28 10:16:28 volumio volumio[1941]: info: CoreCommandRouter::servicePushState May 28 10:16:28 volumio volumio[1941]: info: CoreStateMachine::pushState May 28 10:16:28 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:16:28 volumio volumio[1941]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined May 28 10:16:28 volumio volumio[1941]: info: ------------------------------ 23ms May 28 10:17:22 volumio volumio[1941]: info: Airplay started streaming, receiving metadatas May 28 10:17:22 volumio volumio[1941]: info: CorePlayQueue::getTrack 0 May 28 10:17:22 volumio volumio[1941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 10:17:22 volumio volumio[1941]: TypeError: Cannot read property 'name' of undefined May 28 10:17:22 volumio volumio[1941]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) May 28 10:17:22 volumio volumio[1941]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:534:47) May 28 10:17:22 volumio volumio[1941]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:253:12) May 28 10:17:22 volumio volumio[1941]: at ShairportSyncReaderUDP.emit (events.js:412:35) May 28 10:17:22 volumio volumio[1941]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) May 28 10:17:22 volumio volumio[1941]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) May 28 10:17:22 volumio volumio[1941]: at Socket.emit (events.js:400:28) May 28 10:17:22 volumio volumio[1941]: at UDP.onMessage [as onmessage] (dgram.js:931:8) May 28 10:17:22 volumio volumio[1941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 10:17:22 volumio shairport-sync[2242]: % Total % Received % Xferd Average Speed Time Time Time Current May 28 10:17:22 volumio shairport-sync[2242]: Dload Upload Total Spent Left Speed May 28 10:17:23 volumio sudo[28431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-28 10:16 May 28 10:17:23 volumio sudo[28431]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 21 Oct 2023 12:20:14 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="rockpie" VOLUMIO_DEVICENAME="RockPi E" VOLUMIO_HASH="9324f16779958d03cb8ac5fdddcb0054"