-- Logs begin at Wed 2026-02-25 09:16:53 UTC, end at Wed 2026-02-25 09:44:32 UTC. -- Feb 25 09:43:10 opera hostapd[1399]: wlan0: STA ba:10:29:b4:12:f9 IEEE 802.11: associated Feb 25 09:43:10 opera hostapd[1399]: wlan0: STA ba:10:29:b4:12:f9 RADIUS: starting accounting session 699EC381-00000001 Feb 25 09:43:10 opera hostapd[1399]: wlan0: STA ba:10:29:b4:12:f9 WPA: pairwise key handshake completed (RSN) Feb 25 09:43:10 opera dhcpd[1391]: DHCPREQUEST for 192.168.211.4 from ba:10:29:b4:12:f9 via wlan0 Feb 25 09:43:10 opera dhcpd[1391]: DHCPACK on 192.168.211.4 to ba:10:29:b4:12:f9 via wlan0 Feb 25 09:43:45 opera hostapd[1399]: wlan0: STA ba:10:29:b4:12:f9 IEEE 802.11: disassociated Feb 25 09:43:57 opera volumio[1378]: info: CoreCommandRouter::volumioGetState Feb 25 09:43:57 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:43:57 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:43:57 opera volumio[1378]: info: No input data Feb 25 09:43:57 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 09:43:57 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 09:43:57 opera volumio[1378]: Plugin multiroom or method getMultiroom not found Feb 25 09:43:57 opera volumio[1378]: info: Listing playlists Feb 25 09:44:01 opera dhcpd[1391]: DHCPDISCOVER from 74:da:38:db:1d:ab (opera) via wlan0 Feb 25 09:44:02 opera dhcpd[1391]: DHCPOFFER on 192.168.211.7 to 74:da:38:db:1d:ab (opera) via wlan0 Feb 25 09:44:06 opera volumio[1378]: info: VolumeController::SetAlsaVolume25 Feb 25 09:44:06 opera volumio[1378]: info: VolumeController::Volume 25 Feb 25 09:44:06 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:06 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:06 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:06 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:06 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:06 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume75 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume75 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 75 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 75 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume85 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume85 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 85 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 85 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume90 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume90 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 90 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 90 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume100 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::SetAlsaVolume100 Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 100 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: VolumeController::Volume 100 Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:07 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:07 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:07 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:07 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:08 opera volumio[1378]: info: VolumeController::SetAlsaVolume100 Feb 25 09:44:08 opera volumio[1378]: info: VolumeController::Volume 100 Feb 25 09:44:08 opera volumio[1378]: info: CoreStateMachine::pushState Feb 25 09:44:08 opera volumio[1378]: info: CoreStateMachine::getState Feb 25 09:44:08 opera volumio[1378]: info: CorePlayQueue::getTrack 0 Feb 25 09:44:08 opera volumio[1378]: info: CoreCommandRouter::volumioPushState Feb 25 09:44:08 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 09:44:08 opera volumio[1378]: info: Pushing Favourites {"service":"mpd","uri":"cdda:///1","favourite":false} Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:11 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 09:44:21 opera volumio[1378]: info: Output device has changed, restarting MPD Feb 25 09:44:21 opera volumio[1378]: info: Output device has changed, restarting Shairport Sync Feb 25 09:44:21 opera sudo[1731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 09:44:21 opera sudo[1733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 09:44:21 opera sudo[1731]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 09:44:21 opera sudo[1733]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 09:44:21 opera sudo[1731]: pam_unix(sudo:session): session closed for user root Feb 25 09:44:21 opera systemd[1]: Stopping Music Player Daemon... Feb 25 09:44:21 opera volumio[1378]: info: Setting mixer PCM for card USB Audio Input Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 09:44:21 opera volumio[1378]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: PCM Max Vol: 100 Vol Curve; linear Vol Steps: 10 Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 09:44:21 opera volumio[1378]: info: MPD Permissions set Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 09:44:21 opera sudo[1756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Feb 25 09:44:21 opera sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 09:44:21 opera systemd[1]: Stopping ShairportSync AirTunes receiver... Feb 25 09:44:21 opera systemd[1]: Starting ShairportSync AirTunes receiver... Feb 25 09:44:21 opera sudo[1756]: pam_unix(sudo:session): session closed for user root Feb 25 09:44:21 opera systemd[1]: Started ShairportSync AirTunes receiver. Feb 25 09:44:21 opera volumio[1378]: info: [1772012661584] Shairport-Sync Started Feb 25 09:44:21 opera volumio[1378]: error: Upnp client error: Error: This socket has been ended by the other party Feb 25 09:44:21 opera shairport-sync[1762]: Successful Startup Feb 25 09:44:21 opera systemd[1]: Starting Music Player Daemon... Feb 25 09:44:21 opera systemd[1]: Started Music Player Daemon. Feb 25 09:44:21 opera sudo[1733]: pam_unix(sudo:session): session closed for user root Feb 25 09:44:28 opera volumio[1378]: info: CoreCommandRouter::volumioGetBrowseSources Feb 25 09:44:28 opera volumio[1378]: info: CoreCommandRouter::volumioGetQueue Feb 25 09:44:28 opera volumio[1378]: info: CoreStateMachine::getQueue Feb 25 09:44:28 opera volumio[1378]: info: CorePlayQueue::getQueue Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:28 opera volumio[1378]: info: No input data Feb 25 09:44:30 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 09:44:30 opera volumio[1378]: info: CURURI: albums:// Feb 25 09:44:30 opera volumio[1378]: listAlbums - loading Albums from cache Feb 25 09:44:31 opera volumio[1378]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 25 09:44:31 opera volumio[1378]: info: CURURI: albums://Nat%20King%20Cole/%E8%8A%B1%E6%A8%A3%E5%B9%B4%E8%8F%AF%E9%9B%BB%E5%BD%B1%E5%8E%9F%E8%81%B2%E5%A4%A7%E7%A2%9F%20SACD%20DSD Feb 25 09:44:31 opera volumio[1378]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 09:44:31 opera volumio[1378]: { AssertionError: false == true Feb 25 09:44:31 opera volumio[1378]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Feb 25 09:44:31 opera volumio[1378]: at ControllerMpd.listAlbumSongs (/volumio/app/plugins/music_service/mpd/index.js:3123:17) Feb 25 09:44:31 opera volumio[1378]: at ControllerMpd.handleBrowseUri (/volumio/app/plugins/music_service/mpd/index.js:2899:22) Feb 25 09:44:31 opera volumio[1378]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1004:29) Feb 25 09:44:31 opera volumio[1378]: at CoreMusicLibrary.executeBrowseSource (/volumio/app/musiclibrary.js:319:39) Feb 25 09:44:31 opera volumio[1378]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:359:32) Feb 25 09:44:31 opera volumio[1378]: at emitTwo (events.js:106:13) Feb 25 09:44:31 opera volumio[1378]: at Socket.emit (events.js:191:7) Feb 25 09:44:31 opera volumio[1378]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 25 09:44:31 opera volumio[1378]: at _combinedTickCallback (internal/process/next_tick.js:73:7) Feb 25 09:44:31 opera volumio[1378]: at process._tickCallback (internal/process/next_tick.js:104:9) Feb 25 09:44:31 opera volumio[1378]: name: 'AssertionError', Feb 25 09:44:31 opera volumio[1378]: actual: false, Feb 25 09:44:31 opera volumio[1378]: expected: true, Feb 25 09:44:31 opera volumio[1378]: operator: '==', Feb 25 09:44:31 opera volumio[1378]: message: 'false == true', Feb 25 09:44:31 opera volumio[1378]: generatedMessage: true } Feb 25 09:44:31 opera volumio[1378]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 09:44:32 opera sudo[1788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-25 09:43 Feb 25 09:44:32 opera sudo[1788]: 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="7bac1c7b678b8c4c610bba4d70a5c16ee62b7e19" VOLUMIO_FE_VERSION="2d80816ad99ca4dfec2998adad5179fd25e874d5" VOLUMIO_BE_VERSION="9ac13199e84bd2c5cf8082565cccc6b80b02df97" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Apr 9 12:10:35 CEST 2018" VOLUMIO_VERSION="2.114" VOLUMIO_HARDWARE="pi"