-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Thu 2025-10-23 18:39:54 CST. -- Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:16 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:23 ft1905-febf sshd[3677]: rexec line 6: Deprecated option UsePrivilegeSeparation Oct 23 18:38:23 ft1905-febf sshd[3677]: rexec line 7: Deprecated option KeyRegenerationInterval Oct 23 18:38:23 ft1905-febf sshd[3677]: rexec line 8: Deprecated option ServerKeyBits Oct 23 18:38:23 ft1905-febf sshd[3677]: rexec line 14: Deprecated option RSAAuthentication Oct 23 18:38:23 ft1905-febf sshd[3677]: rexec line 17: Deprecated option RhostsRSAAuthentication Oct 23 18:38:23 ft1905-febf sshd[3677]: reprocess config line 14: Deprecated option RSAAuthentication Oct 23 18:38:23 ft1905-febf sshd[3677]: reprocess config line 17: Deprecated option RhostsRSAAuthentication Oct 23 18:38:24 ft1905-febf sshd[3677]: Accepted password for volumio from 192.168.2.154 port 62239 ssh2 Oct 23 18:38:24 ft1905-febf sshd[3677]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 23 18:38:24 ft1905-febf systemd[1]: Created slice User Slice of UID 1000. Oct 23 18:38:24 ft1905-febf systemd[1]: Starting User Runtime Directory /run/user/1000... Oct 23 18:38:24 ft1905-febf systemd-logind[2222]: New session c1 of user volumio. Oct 23 18:38:24 ft1905-febf systemd[1]: Finished User Runtime Directory /run/user/1000. Oct 23 18:38:24 ft1905-febf systemd[1]: Starting User Manager for UID 1000... Oct 23 18:38:24 ft1905-febf systemd[3683]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Oct 23 18:38:24 ft1905-febf systemd[3689]: /usr/lib/environment.d/99-environment.conf:6: invalid variable name "export no_proxy", ignoring. Oct 23 18:38:25 ft1905-febf systemd[3683]: Reached target Paths. Oct 23 18:38:25 ft1905-febf systemd[3683]: Reached target Timers. Oct 23 18:38:25 ft1905-febf systemd[3683]: Starting D-Bus User Message Bus Socket. Oct 23 18:38:25 ft1905-febf systemd[3683]: Listening on GnuPG network certificate management daemon. Oct 23 18:38:25 ft1905-febf systemd[3683]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 23 18:38:25 ft1905-febf systemd[3683]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Oct 23 18:38:25 ft1905-febf systemd[3683]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Oct 23 18:38:25 ft1905-febf systemd[3683]: Listening on GnuPG cryptographic agent and passphrase cache. Oct 23 18:38:25 ft1905-febf systemd[3683]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Oct 23 18:38:25 ft1905-febf systemd[3683]: mpd.socket: Failed to listen on sockets: Address already in use Oct 23 18:38:25 ft1905-febf systemd[3683]: mpd.socket: Failed with result 'resources'. Oct 23 18:38:25 ft1905-febf systemd[3683]: Failed to listen on mpd.socket. Oct 23 18:38:25 ft1905-febf systemd[3683]: Listening on D-Bus User Message Bus Socket. Oct 23 18:38:25 ft1905-febf systemd[3683]: Reached target Sockets. Oct 23 18:38:25 ft1905-febf systemd[3683]: Reached target Basic System. Oct 23 18:38:25 ft1905-febf systemd[1]: Started User Manager for UID 1000. Oct 23 18:38:25 ft1905-febf systemd[3683]: Starting Music Player Daemon... Oct 23 18:38:25 ft1905-febf systemd[1]: Started Session c1 of user volumio. Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:26 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:27 ft1905-febf sshd[3703]: error: Failed to allocate internet-domain X11 display socket. Oct 23 18:38:27 ft1905-febf mpd[3692]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Oct 23 18:38:27 ft1905-febf systemd[3683]: mpd.service: Main process exited, code=exited, status=1/FAILURE Oct 23 18:38:27 ft1905-febf systemd[3683]: mpd.service: Failed with result 'exit-code'. Oct 23 18:38:27 ft1905-febf systemd[3683]: Failed to start Music Player Daemon. Oct 23 18:38:27 ft1905-febf systemd[3683]: Reached target Main User Target. Oct 23 18:38:27 ft1905-febf systemd[3683]: Startup finished in 2.737s. Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:37 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:38:58 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:11 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:11 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:12 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: acre ssnc 1403239189 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: daid ssnc CDB96DDC8591B0F2 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: conn ssnc 192.168.2.117 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: clip ssnc 192.168.2.117 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: svip ssnc 192.168.2.218 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: snam ssnc iPhone 6s Plus Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: cdid ssnc C0:CC:F8:72:1E:76 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: cmod ssnc iPhone8,2 Oct 23 18:39:17 ft1905-febf volumio[2732]: AIRPLAY useData: cmac ssnc 1E:89:38:5C:7A:A6 Oct 23 18:39:17 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:17 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: AIRPLAY useData: acre ssnc 1403239189 Oct 23 18:39:18 ft1905-febf volumio[2732]: AIRPLAY useData: daid ssnc CDB96DDC8591B0F2 Oct 23 18:39:18 ft1905-febf volumio[2732]: AIRPLAY useData: abeg ssnc Oct 23 18:39:18 ft1905-febf volumio[2732]: AIRPLAY useData: pbeg ssnc Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Airplay started streaming, receiving metadatas Oct 23 18:39:18 ft1905-febf volumio[2732]: warn: [AirPlay] Error handling coverart change: ENOENT: no such file or directory, scandir '/tmp/shairport-sync/.cache/coverart/' {"errno":-2,"syscall":"scandir","code":"ENOENT","path":"/tmp/shairport-sync/.cache/coverart/","stack":"Error: ENOENT: no such file or directory, scandir '/tmp/shairport-sync/.cache/coverart/'\n at Object.readdirSync (fs.js:790:3)\n at AirPlayInterface.handleCoverartChange (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/index.js?:135:22)\n at ShairportSyncReaderUDP.eval (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/index.js?:330:10)\n at ShairportSyncReaderUDP.emit (events.js:198:13)\n at ShairportSyncReaderUDP.useData (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js?:87:16)\n at Socket.ShairportSyncReaderUDP._source.on.msg (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js?:21:12)\n at Socket.emit (events.js:198:13)\n at UDP.onMessage [as onmessage] (dgram.js:628:8)"} Oct 23 18:39:18 ft1905-febf volumio[2732]: info: [AirPlay] Preparing playback. Current service: mpd Oct 23 18:39:18 ft1905-febf volumio[2732]: info: [AirPlay] Stopping previous service: Oct 23 18:39:18 ft1905-febf volumio[2732]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Oct 23 18:39:18 ft1905-febf volumio[2732]: !! !! Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 23 18:39:18 ft1905-febf volumio[2732]: !! !! Oct 23 18:39:18 ft1905-febf volumio[2732]: !!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!!! Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: UNSET VOLATILE Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::volumioStop Oct 23 18:39:18 ft1905-febf shairport-sync[3916]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 23 18:39:18 ft1905-febf shairport-sync[3916]: Dload Upload Total Spent Left Speed Oct 23 18:39:18 ft1905-febf shairport-sync[3916]: [79B blob data] Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::stop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::serviceStop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::serviceStop trackBlock undefined Oct 23 18:39:18 ft1905-febf shairport-sync[3917]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 23 18:39:18 ft1905-febf volumio[2732]: info: ControllerMpd::stop Oct 23 18:39:18 ft1905-febf shairport-sync[3917]: Dload Upload Total Spent Left Speed Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: ControllerMpd::sendMpdCommand stop Oct 23 18:39:18 ft1905-febf volumio[2732]: sendCommand: Command { name: 'stop', args: [] } Oct 23 18:39:18 ft1905-febf volumio[2732]: AIRPLAY useData: pvol ssnc -24.00,0.00,0.00,0.00 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: VolumeController::SetAlsaVolume20 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Volume script /bin/sh /tmp/setvolume 20 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: this.stateMachine.currentVolume: 0, vol: 20 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Aligning Spotify Volume to Volumio Volume Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Setting Spotify Volume from Volumio: 20 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: sendMpdCommand stop took 209 milliseconds Oct 23 18:39:18 ft1905-febf volumio[2732]: info: ControllerMpd::getState Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: ControllerMpd::sendMpdCommand status Oct 23 18:39:18 ft1905-febf volumio[2732]: sendCommand: Command { name: 'status', args: [] } Oct 23 18:39:18 ft1905-febf volumio[2732]: info: sendMpdCommand status took 6 milliseconds Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: ControllerMpd::parseState Oct 23 18:39:18 ft1905-febf volumio[2732]: info: ControllerMpd::pushState Oct 23 18:39:18 ft1905-febf volumio[2732]: this.isVolatile: false, stateService.status: stop Oct 23 18:39:18 ft1905-febf volumio[2732]: this.volatileState: {} Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"service":"mpd"} Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: CURRENT POSITION 0 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState stateService stop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState currentStatus stop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:18 ft1905-febf volumio[2732]: info: No code Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: SET VOLATILE Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::stop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::serviceStop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::serviceStop trackBlock undefined Oct 23 18:39:18 ft1905-febf volumio[2732]: info: ControllerMpd::stop Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: ControllerMpd::sendMpdCommand stop Oct 23 18:39:18 ft1905-febf volumio[2732]: this.isVolatile: false, stateService.status: play Oct 23 18:39:18 ft1905-febf volumio[2732]: this.volatileState: {} Oct 23 18:39:18 ft1905-febf volumio[2732]: set volatileService undefined Oct 23 18:39:18 ft1905-febf volumio[2732]: info: volatileService: undefined Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: CURRENT POSITION 0 Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState stateService play Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState currentStatus stop Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:18 ft1905-febf volumio[2732]: sendCommand: Command { name: 'stop', args: [] } Oct 23 18:39:18 ft1905-febf volumio[2732]: info: sendMpdCommand stop took 125 milliseconds Oct 23 18:39:18 ft1905-febf volumio[2732]: info: ControllerMpd::getState Oct 23 18:39:18 ft1905-febf volumio[2732]: verbose: ControllerMpd::sendMpdCommand status Oct 23 18:39:18 ft1905-febf volumio[2732]: sendCommand: Command { name: 'status', args: [] } Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Oct 23 18:39:18 ft1905-febf volumio[2732]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Received startAirplayPlayback: Oct 23 18:39:18 ft1905-febf shairport-sync[3917]: [79B blob data] Oct 23 18:39:18 ft1905-febf shairport-sync[3916]: [79B blob data] Oct 23 18:39:18 ft1905-febf volumio[2732]: info: Oct 23 18:39:18 ft1905-febf volumio[2732]: ---------------------------- Client requests AirplayActive Oct 23 18:39:18 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Oct 23 18:39:18 ft1905-febf shairport-sync[3916]: {"time":1761215958934,"response":"startAirplayPlayback Success"} Oct 23 18:39:18 ft1905-febf shairport-sync[3917]: [79B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: pres ssnc Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: pcst ssnc 2568309818 Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: PICT ssnc Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: pcen ssnc 2568309818 Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: prgr ssnc 2567988541/2568310313/2573694397 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: [Airplay] prgr: {"start":2567988541,"current":2568310313,"end":2573694397} Oct 23 18:39:19 ft1905-febf volumio[2732]: info: [Airplay] prgr: Saved progress info - start: 2567988541 current: 2568310313 end: 2573694397 Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: mdst ssnc 2568310590 Oct 23 18:39:19 ft1905-febf volumio[2732]: [40B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asal core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asar core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: ascp core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asgn core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: minm core 001. Five Little Monkeys Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: astn core Oct 23 18:39:19 ft1905-febf volumio[2732]: [1B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: astc core Oct 23 18:39:19 ft1905-febf volumio[2732]: [1B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asdk core Oct 23 18:39:19 ft1905-febf volumio[2732]: [29B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: astm core Oct 23 18:39:19 ft1905-febf volumio[2732]: [5B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: mden ssnc 2568310590 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: [Airplay] meta: {"mper":"0x693a137769a85986","asal":"","asar":"","ascp":"","asgn":"","minm":"001. Five Little Monkeys","astn":1,"astc":1,"asdk":0,"caps":2,"astm":129384} Oct 23 18:39:19 ft1905-febf volumio[2732]: this.isVolatile: false, stateService.status: pause Oct 23 18:39:19 ft1905-febf volumio[2732]: this.volatileState: {} Oct 23 18:39:19 ft1905-febf volumio[2732]: set volatileService undefined Oct 23 18:39:19 ft1905-febf volumio[2732]: info: volatileService: undefined Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: STATE SERVICE {"status":"pause","service":"airplay_emulation","title":"001. Five Little Monkeys","artist":"","album":"","albumart":"/albumart?cacheid=859&web=//extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":7000,"duration":129,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: CURRENT POSITION 0 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState stateService pause Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState currentStatus play Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:19 ft1905-febf volumio[2732]: this.isVolatile: false, stateService.status: pause Oct 23 18:39:19 ft1905-febf volumio[2732]: this.volatileState: {} Oct 23 18:39:19 ft1905-febf volumio[2732]: set volatileService undefined Oct 23 18:39:19 ft1905-febf volumio[2732]: info: volatileService: undefined Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: STATE SERVICE {"status":"pause","service":"airplay_emulation","title":"001. Five Little Monkeys","artist":"","album":"","albumart":"/albumart?cacheid=859&web=//extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":7000,"duration":129,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: CURRENT POSITION 0 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState stateService pause Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState currentStatus play Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: pffr ssnc Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: prsm ssnc Oct 23 18:39:19 ft1905-febf volumio[2732]: info: sendMpdCommand status took 181 milliseconds Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: ControllerMpd::parseState Oct 23 18:39:19 ft1905-febf volumio[2732]: info: ControllerMpd::pushState Oct 23 18:39:19 ft1905-febf volumio[2732]: this.isVolatile: false, stateService.status: stop Oct 23 18:39:19 ft1905-febf volumio[2732]: this.volatileState: {} Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null,"service":"mpd"} Oct 23 18:39:19 ft1905-febf volumio[2732]: verbose: CURRENT POSITION 0 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState stateService stop Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::syncState currentStatus play Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::stPlaybackTimer Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: mdst ssnc 2568323179 Oct 23 18:39:19 ft1905-febf volumio[2732]: [40B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asal core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asar core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: ascp core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: asgn core Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: minm core 001. Five Little Monkeys Oct 23 18:39:19 ft1905-febf volumio[2732]: [29B blob data] Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: mden ssnc 2568323179 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: [Airplay] meta: {"mper":"0x693a137769a85986","asal":"","asar":"","ascp":"","asgn":"","minm":"001. Five Little Monkeys","caps":1} Oct 23 18:39:19 ft1905-febf volumio[2732]: this.isVolatile: true, stateService.status: play Oct 23 18:39:19 ft1905-febf volumio[2732]: this.volatileState: {} Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: styp ssnc Buffered Oct 23 18:39:19 ft1905-febf lvgljs[2740]: setImageSrc: this=0x38b4d90, str=/home/volumio/saui-lvgljs/assets/service-icon/airplay.png Oct 23 18:39:19 ft1905-febf lvgljs[2740]: read file /home/volumio/saui-lvgljs/assets/service-icon/airplay.png, size=691, buffer[0]=89 Oct 23 18:39:19 ft1905-febf lvgljs[2740]: setImageSrc: this=0x38bd428, str=http://127.0.0.1:3000/albumart?cacheid=859&web=//extralarge&path=&metadata=false Oct 23 18:39:19 ft1905-febf volumio[2732]: AIRPLAY useData: prgr ssnc 2568051912/2568336154/2573757768 Oct 23 18:39:19 ft1905-febf volumio[2732]: info: [Airplay] prgr: {"start":2568051912,"current":2568336154,"end":2573757768} Oct 23 18:39:19 ft1905-febf volumio[2732]: this.isVolatile: true, stateService.status: play Oct 23 18:39:19 ft1905-febf volumio[2732]: this.volatileState: {"status":"play","service":"airplay_emulation","title":"001. Five Little Monkeys","artist":"","album":"","albumart":"/albumart?cacheid=859&web=//extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":6000,"duration":129,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true,"stream":false,"repeatMode":"all"} Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreStateMachine::pushState Oct 23 18:39:19 ft1905-febf volumio[2732]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:39:20 ft1905-febf volumio[2732]: info: Sending Spotify command with payload to local API: /player/volume Oct 23 18:39:20 ft1905-febf volumio[2732]: AIRPLAY useData: dapo ssnc 52831 Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:38 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:51 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:51 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:51 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:51 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:51 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:51 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: error: Could not retrieve plugin audio_interface multiroom Oct 23 18:39:52 ft1905-febf volumio[2732]: AIRPLAY useData: paus ssnc Oct 23 18:39:52 ft1905-febf volumio[2732]: AIRPLAY useData: pend ssnc Oct 23 18:39:52 ft1905-febf volumio[2732]: info: [Airplay] ended Oct 23 18:39:52 ft1905-febf volumio[2732]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 18:39:52 ft1905-febf volumio[2732]: TypeError: Cannot read property 'pushAirplayMeta' of undefined Oct 23 18:39:52 ft1905-febf volumio[2732]: at ShairportSyncReaderUDP.eval (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/index.js?:437:10) Oct 23 18:39:52 ft1905-febf volumio[2732]: at ShairportSyncReaderUDP.emit (events.js:198:13) Oct 23 18:39:52 ft1905-febf volumio[2732]: at ShairportSyncReaderUDP.useData (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js?:87:16) Oct 23 18:39:52 ft1905-febf volumio[2732]: at Socket.ShairportSyncReaderUDP._source.on.msg (webpack://Volumio2/./app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js?:21:12) Oct 23 18:39:52 ft1905-febf volumio[2732]: at Socket.emit (events.js:198:13) Oct 23 18:39:52 ft1905-febf volumio[2732]: at UDP.onMessage [as onmessage] (dgram.js:628:8) Oct 23 18:39:52 ft1905-febf volumio[2732]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 18:39:52 ft1905-febf kernel: sunxi-spdif 2036000.spdif: active: 1 Oct 23 18:39:52 ft1905-febf shairport-sync[3917]: {"time":1761215958963,"response":"airplayActive Success"} Oct 23 18:39:52 ft1905-febf shairport-sync[3956]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 23 18:39:52 ft1905-febf shairport-sync[3956]: Dload Upload Total Spent Left Speed Oct 23 18:39:52 ft1905-febf shairport-sync[3956]: [79B blob data] Oct 23 18:39:52 ft1905-febf shairport-sync[3958]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 23 18:39:52 ft1905-febf shairport-sync[3958]: Dload Upload Total Spent Left Speed Oct 23 18:39:54 ft1905-febf shairport-sync[3958]: [79B blob data] Oct 23 18:39:54 ft1905-febf shairport-sync[3956]: [79B blob data] Oct 23 18:39:54 ft1905-febf sudo[3995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-23 18:38 Oct 23 18:39:54 ft1905-febf sudo[3995]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VERSION=1.5.1.161 VOLUMIO_BUILD_DATE=2025-09-05 18:56:59 VOLUMIO_VARIANT=volumio VOLUMIO_HARDWARE=Generic DT based system