-- Logs begin at Fri 2026-04-24 16:18:55 UTC, end at Sun 2026-05-24 22:37:07 UTC. -- May 24 22:36:01 volumio kernel: usb 1-1.2: cannot submit urb 0, error -2: endpoint not enabled May 24 22:36:01 volumio volumio-remote-updater[543]: [2026-05-24 22:36:01] [connect] Successful connection May 24 22:36:06 volumio volumio[929]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 24 22:36:06 volumio volumio[929]: aplay: set_params:1297: Unable to install hw params: May 24 22:36:06 volumio volumio[929]: ACCESS: RW_INTERLEAVED May 24 22:36:06 volumio volumio[929]: FORMAT: S16_LE May 24 22:36:06 volumio volumio[929]: SUBFORMAT: STD May 24 22:36:06 volumio volumio[929]: SAMPLE_BITS: 16 May 24 22:36:06 volumio volumio[929]: FRAME_BITS: 32 May 24 22:36:06 volumio volumio[929]: CHANNELS: 2 May 24 22:36:06 volumio volumio[929]: RATE: 44100 May 24 22:36:06 volumio volumio[929]: PERIOD_TIME: (125011 125012) May 24 22:36:06 volumio volumio[929]: PERIOD_SIZE: 5513 May 24 22:36:06 volumio volumio[929]: PERIOD_BYTES: 22052 May 24 22:36:06 volumio volumio[929]: PERIODS: (3 4) May 24 22:36:06 volumio volumio[929]: BUFFER_TIME: 500000 May 24 22:36:06 volumio volumio[929]: BUFFER_SIZE: 22050 May 24 22:36:06 volumio volumio[929]: BUFFER_BYTES: 88200 May 24 22:36:06 volumio volumio[929]: TICK_TIME: 0 May 24 22:36:06 volumio volumio[929]: Cannot play startup sound May 24 22:36:06 volumio volumio[929]: info: CoreCommandRouter::Close All Modals sent May 24 22:36:06 volumio volumio[929]: info: CoreCommandRouter::Close All Modals sent May 24 22:36:06 volumio volumio[929]: Express server listening on port 3000 May 24 22:36:06 volumio volumio[929]: Volumio Calling Home May 24 22:36:06 volumio volumio[929]: info: MyVolumio not started May 24 22:36:06 volumio volumio[929]: info: CoreStateMachine::resetVolumioState May 24 22:36:06 volumio volumio[929]: info: CoreStateMachine::getcurrentVolume May 24 22:36:06 volumio volumio[929]: info: CoreCommandRouter::volumioRetrievevolume May 24 22:36:06 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:06 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:06 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root May 24 22:36:07 volumio volumio[929]: info: Setting Device type: Raspberry PI May 24 22:36:07 volumio volumio[929]: info: MPD running with PID576 ,establishing connection May 24 22:36:07 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 24 22:36:07 volumio sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 24 22:36:07 volumio sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:07 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 24 22:36:07 volumio sudo[1030]: pam_unix(sudo:session): session closed for user root May 24 22:36:07 volumio volumio[929]: info: VolumeController:: Volume=undefined Mute =false May 24 22:36:07 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:07 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:07 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:07 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:07 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:07 volumio volumio[929]: info: May 24 22:36:07 volumio volumio[929]: ---------------------------- Client requests Volume 10 May 24 22:36:07 volumio volumio[929]: info: VolumeController::SetAlsaVolume10 May 24 22:36:07 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:07 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:07 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:07 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:07 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:08 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:36:08 volumio volumio[929]: info: Reloading queue from file May 24 22:36:08 volumio volumio[929]: info: May 24 22:36:08 volumio volumio[929]: info: Setting volume on startup at 10 May 24 22:36:08 volumio volumio[929]: info: CoreStateMachine::setRepeat null single undefined May 24 22:36:08 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:08 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:08 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:08 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:08 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:08 volumio volumio[929]: info: CoreStateMachine::setRandom null May 24 22:36:08 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:08 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:08 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:08 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:08 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:08 volumio sudo[1043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 24 22:36:08 volumio sudo[1043]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:08 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 24 22:36:08 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:08 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 24 22:36:08 volumio sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 24 22:36:08 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 24 22:36:08 volumio sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:08 volumio sudo[1043]: pam_unix(sudo:session): session closed for user root May 24 22:36:08 volumio shairport-sync[1057]: Successful Startup May 24 22:36:08 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... May 24 22:36:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 24 22:36:08 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 24 22:36:08 volumio sudo[1051]: pam_unix(sudo:session): session closed for user root May 24 22:36:08 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 24 22:36:08 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 24 22:36:08 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root May 24 22:36:09 volumio shairport-sync[1069]: Successful Startup May 24 22:36:09 volumio volumio[929]: Discovery: adding a5768bb4-60d6-4870-a192-414f6056c45c May 24 22:36:09 volumio volumio[929]: info: mDNS: Found device Volumio May 24 22:36:09 volumio sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:09 volumio sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:09 volumio sudo[1077]: pam_unix(sudo:session): session closed for user root May 24 22:36:09 volumio volumio[929]: info: Upmpdcli Daemon Started May 24 22:36:09 volumio volumio[929]: info: Shairport-Sync Started May 24 22:36:09 volumio volumio[929]: Error adding Membership: Error: addMembership EINVAL May 24 22:36:09 volumio volumio[929]: info: Shairport-Sync Started May 24 22:36:09 volumio volumio[929]: info: CoreCommandRouter::volumioGetState May 24 22:36:09 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:09 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:09 volumio sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:09 volumio sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:09 volumio sudo[1086]: pam_unix(sudo:session): session closed for user root May 24 22:36:09 volumio volumio-streaming-daemon[996]: Checking variant in static cache May 24 22:36:09 volumio volumio[929]: info: Fetching Streaming Services browse cache May 24 22:36:09 volumio volumio[929]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 24 22:36:09 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 22:36:10 volumio volumio[929]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL May 24 22:36:10 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 22:36:16 volumio volumio-remote-updater[543]: [2026-05-24 22:36:16] [connect] Successful connection May 24 22:36:16 volumio volumio-remote-updater[543]: [2026-05-24 22:36:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1779662176 101 May 24 22:36:17 volumio volumio[929]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.565&uuid=678e17bca7ac714010eaaeca92b5db39" http://updates.volumio.org:7070/downloader-v1/track-device May 24 22:36:17 volumio volumio[929]: % Total % Received % Xferd Average Speed Time Time Time Current May 24 22:36:17 volumio volumio[929]: Dload Upload Total Spent Left Speed May 24 22:36:17 volumio volumio[929]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 24 22:36:17 volumio volumio[929]: retrying in 5 seconds, trial 0 May 24 22:36:17 volumio volumio[929]: Volumio Calling Home May 24 22:36:18 volumio sudo[1180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 24 22:36:18 volumio sudo[1180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:18 volumio sudo[1180]: pam_unix(sudo:session): session closed for user root May 24 22:36:18 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:18 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:18 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root May 24 22:36:18 volumio volumio[929]: info: CoreCommandRouter::volumioGetVisibleSources May 24 22:36:18 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 22:36:18 volumio volumio[929]: info: CoreCommandRouter::volumioGetState May 24 22:36:18 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:18 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:18 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:18 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 24 22:36:18 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 24 22:36:19 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 24 22:36:19 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:19 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root May 24 22:36:19 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 24 22:36:19 volumio volumio[929]: info: Listing playlists May 24 22:36:19 volumio sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:19 volumio sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:19 volumio sudo[1204]: pam_unix(sudo:session): session closed for user root May 24 22:36:21 volumio volumio[929]: info: CoreCommandRouter::volumioPlay May 24 22:36:21 volumio volumio[929]: UNSET VOLATILE May 24 22:36:21 volumio volumio[929]: info: CoreStateMachine::play index undefined May 24 22:36:21 volumio volumio[929]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 22:36:21 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:21 volumio volumio[929]: info: CoreStateMachine::startPlaybackTimer May 24 22:36:21 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:21 volumio volumio[929]: info: ControllerMpd::sendMpdCommand stop May 24 22:36:21 volumio volumio[929]: info: sending command... May 24 22:36:21 volumio volumio[929]: info: parsing response... May 24 22:36:21 volumio volumio[929]: info: ControllerMpd::sendMpdCommand clear May 24 22:36:21 volumio volumio[929]: info: sending command... May 24 22:36:21 volumio volumio[929]: info: May 24 22:36:21 volumio volumio[929]: ---------------------------- MPD announces system playlist update May 24 22:36:21 volumio volumio[929]: info: Ignoring MPD Status Update May 24 22:36:21 volumio volumio[929]: info: parsing response... May 24 22:36:21 volumio volumio[929]: info: ControllerMpd::sendMpdCommand load "USB/E/(1979) Communique [Vertigo, 800 052-2, France] [R]/Dire Straits - Communique.cue" May 24 22:36:21 volumio volumio[929]: info: sending command... May 24 22:36:21 volumio volumio[929]: info: May 24 22:36:21 volumio volumio[929]: info: ------------------------------ 26ms May 24 22:36:21 volumio volumio[929]: info: parsing response... May 24 22:36:21 volumio volumio[929]: info: ControllerMpd::sendMpdCommand play May 24 22:36:21 volumio volumio[929]: info: sending command... May 24 22:36:21 volumio volumio[929]: info: May 24 22:36:21 volumio volumio[929]: ---------------------------- MPD announces system playlist update May 24 22:36:21 volumio volumio[929]: info: Ignoring MPD Status Update May 24 22:36:21 volumio volumio[929]: info: parsing response... May 24 22:36:21 volumio volumio[929]: info: ------------------------------ 16ms May 24 22:36:21 volumio volumio[929]: info: May 24 22:36:21 volumio volumio[929]: ---------------------------- MPD announces state update: player May 24 22:36:21 volumio volumio[929]: info: ControllerMpd::getState May 24 22:36:21 volumio volumio[929]: info: ControllerMpd::sendMpdCommand status May 24 22:36:21 volumio volumio[929]: info: sending command... May 24 22:36:21 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 24 22:36:21 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:36:26 volumio volumio[929]: info: parsing response... May 24 22:36:26 volumio kernel: usb 1-1.2: 1:2: usb_set_interface failed (-110) May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::sendMpdCommand clearerror May 24 22:36:26 volumio volumio[929]: info: sending command... May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::parseState May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::sendMpdCommand playlistinfo May 24 22:36:26 volumio volumio[929]: info: sending command... May 24 22:36:26 volumio volumio[929]: info: May 24 22:36:26 volumio volumio[929]: ---------------------------- MPD announces state update: player May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::getState May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::sendMpdCommand status May 24 22:36:26 volumio volumio[929]: info: parsing response... May 24 22:36:26 volumio volumio[929]: info: sending command... May 24 22:36:26 volumio volumio[929]: info: parsing response... May 24 22:36:26 volumio volumio[929]: info: parsing response... May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::parseTrackInfo May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::parseState May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::sendMpdCommand playlistinfo May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::pushState May 24 22:36:26 volumio volumio[929]: info: CoreCommandRouter::servicePushState May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::syncState May 24 22:36:26 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:26 volumio volumio[929]: info: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Communique","uri":"USB/E/(1979) Communique [Vertigo, 800 052-2, France] [R]/Dire Straits - Communique.flac","trackType":"flac"} May 24 22:36:26 volumio volumio[929]: info: CURRENT POSITION 0 May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::syncState stateService pause May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::syncState currentStatus stop May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:26 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:26 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:26 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:26 volumio volumio[929]: info: sending command... May 24 22:36:26 volumio volumio[929]: info: ------------------------------ 5191ms May 24 22:36:26 volumio volumio[929]: info: parsing response... May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::parseTrackInfo May 24 22:36:26 volumio volumio[929]: info: ControllerMpd::pushState May 24 22:36:26 volumio volumio[929]: info: CoreCommandRouter::servicePushState May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::syncState May 24 22:36:26 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:26 volumio volumio[929]: info: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":325,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"isStreaming":false,"title":"Once Upon A Time In The West","artist":"Dire Straits","album":"Communique","uri":"USB/E/(1979) Communique [Vertigo, 800 052-2, France] [R]/Dire Straits - Communique.flac","trackType":"flac"} May 24 22:36:26 volumio volumio[929]: info: CURRENT POSITION 0 May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::syncState stateService pause May 24 22:36:26 volumio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::syncState currentStatus stop May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:26 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:26 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:26 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:26 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:26 volumio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:26 volumio sudo[1239]: pam_unix(sudo:session): session closed for user root May 24 22:36:26 volumio volumio[929]: info: ------------------------------ 189ms May 24 22:36:26 volumio sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:26 volumio sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:26 volumio sudo[1245]: pam_unix(sudo:session): session closed for user root May 24 22:36:27 volumio volumio[929]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.565&uuid=678e17bca7ac714010eaaeca92b5db39" http://updates.volumio.org:7070/downloader-v1/track-device May 24 22:36:27 volumio volumio[929]: % Total % Received % Xferd Average Speed Time Time Time Current May 24 22:36:27 volumio volumio[929]: Dload Upload Total Spent Left Speed May 24 22:36:27 volumio volumio[929]: [235B blob data] May 24 22:36:27 volumio volumio[929]: retrying in 5 seconds, trial 1 May 24 22:36:27 volumio volumio[929]: Volumio Calling Home May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 22:36:33 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:37 volumio volumio[929]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.565&uuid=678e17bca7ac714010eaaeca92b5db39" http://updates.volumio.org:7070/downloader-v1/track-device May 24 22:36:37 volumio volumio[929]: % Total % Received % Xferd Average Speed Time Time Time Current May 24 22:36:37 volumio volumio[929]: Dload Upload Total Spent Left Speed May 24 22:36:37 volumio volumio[929]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 24 22:36:37 volumio volumio[929]: retrying in 5 seconds, trial 2 May 24 22:36:37 volumio volumio[929]: Volumio Calling Home May 24 22:36:37 volumio volumio[929]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 24 22:36:37 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 24 22:36:37 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:37 volumio volumio[929]: info: Output device has changed, restarting MPD May 24 22:36:37 volumio volumio[929]: info: Output device has changed, restarting Shairport Sync May 24 22:36:38 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:36:38 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:36:38 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:38 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:38 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root May 24 22:36:38 volumio systemd[1]: Stopping Music Player Daemon... May 24 22:36:38 volumio volumio[929]: info: Setting mixer PCM for card REFLEX 3.0 Config 2.0 May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 22:36:38 volumio volumio[929]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:38 volumio volumio[929]: error: Upnp client error: Error: This socket has been ended by the other party May 24 22:36:38 volumio volumio[929]: info: MPD Permissions set May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:38 volumio volumio[929]: info: VolumeController:: Volume=undefined Mute =false May 24 22:36:38 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:38 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:38 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:38 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:38 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root May 24 22:36:38 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 24 22:36:38 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:38 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 24 22:36:38 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 24 22:36:38 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 24 22:36:38 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root May 24 22:36:38 volumio shairport-sync[1385]: Successful Startup May 24 22:36:39 volumio volumio[929]: info: Shairport-Sync Started May 24 22:36:39 volumio volumio[929]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:39 volumio volumio[929]: info: Output device has changed, restarting MPD May 24 22:36:39 volumio volumio[929]: info: Output device has changed, restarting Shairport Sync May 24 22:36:39 volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:36:39 volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:39 volumio sudo[1392]: pam_unix(sudo:session): session closed for user root May 24 22:36:39 volumio sudo[1394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:36:39 volumio sudo[1394]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:39 volumio volumio[929]: info: Setting mixer PCM for card REFLEX 3.0 Config 2.0 May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 22:36:39 volumio volumio[929]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:39 volumio volumio[929]: info: MPD Permissions set May 24 22:36:39 volumio volumio[929]: info: VolumeController:: Volume=undefined Mute =false May 24 22:36:39 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:39 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:39 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:39 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:39 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:39 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 22:36:39 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:39 volumio sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 24 22:36:39 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:39 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 24 22:36:39 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 24 22:36:39 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 24 22:36:39 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root May 24 22:36:40 volumio shairport-sync[1438]: Successful Startup May 24 22:36:40 volumio volumio[929]: info: Shairport-Sync Started May 24 22:36:40 volumio volumio[929]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 24 22:36:40 volumio volumio[929]: info: CoreStateMachine::getcurrentVolume May 24 22:36:40 volumio volumio[929]: info: CoreCommandRouter::volumioRetrievevolume May 24 22:36:40 volumio volumio[929]: info: VolumeController:: Volume=undefined Mute =false May 24 22:36:40 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:40 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:40 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:40 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:40 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:40 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:40 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:40 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root May 24 22:36:41 volumio volumio[929]: info: CoreCommandRouter::volumioRetrieveVolumeLevels May 24 22:36:41 volumio volumio[929]: info: CoreStateMachine::getcurrentVolume May 24 22:36:41 volumio volumio[929]: info: CoreCommandRouter::volumioRetrievevolume May 24 22:36:42 volumio volumio[929]: info: VolumeController:: Volume=undefined Mute =false May 24 22:36:42 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:42 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:42 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:42 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:42 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:42 volumio sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:42 volumio sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:42 volumio sudo[1458]: pam_unix(sudo:session): session closed for user root May 24 22:36:45 volumio volumio[929]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::volumioGetState May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:45 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:45 volumio volumio[929]: info: Restoring Previous Volume level: 10 false true May 24 22:36:45 volumio volumio[929]: info: VolumeController::SetAlsaVolume100 May 24 22:36:45 volumio volumio[929]: info: Enable softmixer device for audio device number 5 May 24 22:36:45 volumio volumio[929]: info: Enable softmixer device for audio device number 5 May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::volumioStop May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::stop May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 22:36:45 volumio volumio[929]: UNSET VOLATILE May 24 22:36:45 volumio volumio[929]: info: Output device has changed, restarting MPD May 24 22:36:45 volumio volumio[929]: info: Output device has changed, restarting Shairport Sync May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:45 volumio volumio[929]: info: Volume configurations have been set May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:45 volumio sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:45 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:45 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root May 24 22:36:45 volumio sudo[1470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:36:45 volumio sudo[1470]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 22:36:45 volumio volumio[929]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:45 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:45 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:45 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:45 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:45 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:45 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:45 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root May 24 22:36:45 volumio volumio[929]: info: MPD Permissions set May 24 22:36:45 volumio volumio[929]: info: Asound.conf file written May 24 22:36:45 volumio sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:45 volumio sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:45 volumio sudo[1504]: pam_unix(sudo:session): session closed for user root May 24 22:36:45 volumio sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 24 22:36:45 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:45 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root May 24 22:36:45 volumio volumio[929]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 4 Validity:0' : Operation not permitted May 24 22:36:50 volumio volumio[929]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 24 22:36:50 volumio volumio[929]: aplay: set_params:1297: Unable to install hw params: May 24 22:36:50 volumio volumio[929]: ACCESS: RW_INTERLEAVED May 24 22:36:50 volumio volumio[929]: FORMAT: S16_LE May 24 22:36:50 volumio volumio[929]: SUBFORMAT: STD May 24 22:36:50 volumio volumio[929]: SAMPLE_BITS: 16 May 24 22:36:50 volumio volumio[929]: FRAME_BITS: 32 May 24 22:36:50 volumio volumio[929]: CHANNELS: 2 May 24 22:36:50 volumio volumio[929]: RATE: 44100 May 24 22:36:50 volumio volumio[929]: PERIOD_TIME: (125011 125012) May 24 22:36:50 volumio volumio[929]: PERIOD_SIZE: 5513 May 24 22:36:50 volumio volumio[929]: PERIOD_BYTES: 22052 May 24 22:36:50 volumio volumio[929]: PERIODS: (3 4) May 24 22:36:50 volumio volumio[929]: BUFFER_TIME: 500000 May 24 22:36:50 volumio volumio[929]: BUFFER_SIZE: 22050 May 24 22:36:50 volumio volumio[929]: BUFFER_BYTES: 88200 May 24 22:36:50 volumio volumio[929]: TICK_TIME: 0 May 24 22:36:50 volumio kernel: usb 1-1.2: 1:1: usb_set_interface failed (-110) May 24 22:36:50 volumio volumio[929]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Clock Source 4 Validity:0' : Operation not permitted May 24 22:36:50 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:50 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:50 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:50 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:50 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:50 volumio volumio[929]: info: Output device has changed, restarting MPD May 24 22:36:51 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 24 22:36:51 volumio volumio[929]: info: Output device has changed, restarting Shairport Sync May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:51 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root May 24 22:36:51 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:36:51 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 24 22:36:51 volumio volumio[929]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 24 22:36:51 volumio volumio[929]: info: VolumeController::SetAlsaVolume10 May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:51 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:51 volumio sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:51 volumio sudo[1560]: pam_unix(sudo:session): session closed for user root May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::getUIConfigOnPlugin May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:36:51 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:36:51 volumio volumio[929]: info: MPD Permissions set May 24 22:36:51 volumio sudo[1573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio sudo[1573]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 24 22:36:51 volumio sudo[1573]: pam_unix(sudo:session): session closed for user root May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::volumioPlay May 24 22:36:51 volumio volumio[929]: UNSET VOLATILE May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::play index undefined May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 22:36:51 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::startPlaybackTimer May 24 22:36:51 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:51 volumio volumio[929]: info: ControllerMpd::sendMpdCommand stop May 24 22:36:51 volumio volumio[929]: info: sending command... May 24 22:36:51 volumio volumio[929]: error: MPD error: Error: This socket has been ended by the other party May 24 22:36:51 volumio volumio[929]: error: MPD error: Error: This socket has been ended by the other party May 24 22:36:51 volumio volumio[929]: error: MPD error: Error: This socket has been ended by the other party May 24 22:36:51 volumio sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 24 22:36:51 volumio sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:51 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 24 22:36:51 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 24 22:36:51 volumio sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 24 22:36:51 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 24 22:36:51 volumio volumio[929]: info: CoreCommandRouter::volumioGetQueue May 24 22:36:51 volumio volumio[929]: info: CoreStateMachine::getQueue May 24 22:36:51 volumio volumio[929]: info: CorePlayQueue::getQueue May 24 22:36:51 volumio sudo[1580]: pam_unix(sudo:session): session closed for user root May 24 22:36:51 volumio sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:51 volumio shairport-sync[1590]: Successful Startup May 24 22:36:51 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 24 22:36:51 volumio volumio[929]: info: No input data May 24 22:36:51 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 24 22:36:51 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 24 22:36:51 volumio sudo[1588]: pam_unix(sudo:session): session closed for user root May 24 22:36:51 volumio volumio[929]: info: No input data May 24 22:36:51 volumio volumio[929]: info: No input data May 24 22:36:51 volumio volumio[929]: info: No input data May 24 22:36:51 volumio shairport-sync[1600]: Successful Startup May 24 22:36:51 volumio volumio[929]: info: No input data May 24 22:36:51 volumio volumio[929]: info: Shairport-Sync Started May 24 22:36:51 volumio volumio[929]: info: No input data May 24 22:36:52 volumio volumio[929]: info: Shairport-Sync Started May 24 22:36:52 volumio volumio[929]: info: No input data May 24 22:36:52 volumio volumio[929]: info: No input data May 24 22:36:52 volumio volumio[929]: info: No input data May 24 22:36:52 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 24 22:36:52 volumio sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 24 22:36:52 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:36:55 volumio volumio[929]: info: CoreCommandRouter::volumioPlay May 24 22:36:55 volumio volumio[929]: UNSET VOLATILE May 24 22:36:55 volumio volumio[929]: info: CoreStateMachine::play index undefined May 24 22:36:55 volumio volumio[929]: info: CoreStateMachine::setConsumeUpdateService undefined May 24 22:36:55 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:55 volumio volumio[929]: info: CoreStateMachine::startPlaybackTimer May 24 22:36:55 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:36:55 volumio volumio[929]: info: ControllerMpd::sendMpdCommand stop May 24 22:36:55 volumio volumio[929]: info: sending command... May 24 22:36:55 volumio volumio[929]: error: MPD error: Error: This socket has been ended by the other party May 24 22:36:55 volumio volumio[929]: error: MPD error: Error: This socket has been ended by the other party May 24 22:36:55 volumio volumio[929]: error: MPD error: Error: This socket has been ended by the other party May 24 22:37:00 volumio volumio[929]: info: VolumeController::SetAlsaVolume47 May 24 22:37:00 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:37:00 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:37:00 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:37:00 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:37:00 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:37:00 volumio sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:37:00 volumio sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:37:01 volumio sudo[1615]: pam_unix(sudo:session): session closed for user root May 24 22:37:01 volumio volumio[929]: info: VolumeController::SetAlsaVolume52 May 24 22:37:01 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:37:01 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:37:01 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:37:01 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:37:01 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:37:01 volumio sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:37:01 volumio sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:37:01 volumio sudo[1626]: pam_unix(sudo:session): session closed for user root May 24 22:37:02 volumio volumio[929]: info: VolumeController::SetAlsaVolume62 May 24 22:37:02 volumio volumio[929]: info: CoreStateMachine::pushState May 24 22:37:02 volumio volumio[929]: info: CoreStateMachine::getState May 24 22:37:02 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:37:02 volumio volumio[929]: info: CoreCommandRouter::volumioPushState May 24 22:37:02 volumio volumio[929]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 22:37:02 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 24 22:37:02 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 22:37:02 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root May 24 22:37:05 volumio volumio[929]: info: CoreCommandRouter::volumioSeek May 24 22:37:05 volumio volumio[929]: info: CoreStateMachine::seek May 24 22:37:05 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:37:05 volumio volumio[929]: info: TRACKBLOCK {"uri":"cue://USB/E/(1979) Communique [Vertigo, 800 052-2, France] [R]/Dire Straits - Communique.cue@0","type":"cuesong","service":"mpd","name":"Once Upon A Time In The West","artist":"Dire Straits","album":"Communique","number":0,"albumart":"/albumart?cacheid=162&web=Dire%20Straits/Communique/extralarge&path=%2FUSB%2FE%2F(1979)%20Communique%20%5BVertigo%2C%20800%20052-2%2C%20France%5D%20%5BR%5D%2F&metadata=false","samplerate":"","bitdepth":0,"channels":0,"duration":325} May 24 22:37:05 volumio volumio[929]: info: CoreStateMachine::startPlaybackTimer May 24 22:37:05 volumio volumio[929]: info: CorePlayQueue::getTrack 0 May 24 22:37:05 volumio volumio[929]: info: ControllerMpd::seek May 24 22:37:05 volumio volumio[929]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 22:37:05 volumio volumio[929]: { AssertionError [ERR_ASSERTION]: false == true May 24 22:37:05 volumio volumio[929]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) May 24 22:37:05 volumio volumio[929]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2799:24) May 24 22:37:05 volumio volumio[929]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1214:15) May 24 22:37:05 volumio volumio[929]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1342:27) May 24 22:37:05 volumio volumio[929]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) May 24 22:37:05 volumio volumio[929]: at emitTwo (events.js:126:13) May 24 22:37:05 volumio volumio[929]: at Socket.emit (events.js:214:7) May 24 22:37:05 volumio volumio[929]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 24 22:37:05 volumio volumio[929]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 24 22:37:05 volumio volumio[929]: at process._tickCallback (internal/process/next_tick.js:180:9) May 24 22:37:05 volumio volumio[929]: generatedMessage: true, May 24 22:37:05 volumio volumio[929]: name: 'AssertionError [ERR_ASSERTION]', May 24 22:37:05 volumio volumio[929]: code: 'ERR_ASSERTION', May 24 22:37:05 volumio volumio[929]: actual: false, May 24 22:37:05 volumio volumio[929]: expected: true, May 24 22:37:05 volumio volumio[929]: operator: '==' } May 24 22:37:05 volumio volumio[929]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 22:37:07 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-24 22:36 May 24 22:37:07 volumio sudo[1653]: 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="f1b59d96a3893a42f8609371956ecba0438017d7" VOLUMIO_FE_VERSION="95791212f747b95a2cb050c16459a831bfe5c7e7" VOLUMIO_BE_VERSION="1c3c01aeeeb4cff8e6c9a4b6c34e1a558d1b237b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 12 19:57:25 CET 2019" VOLUMIO_VERSION="2.565" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="64b803aae2d00ba86d00d612c4abb38d"