-- Logs begin at Mon 2018-04-09 10:10:35 UTC, end at Sat 2025-03-01 07:26:37 UTC. -- Mar 01 07:25:31 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , goto Mar 01 07:25:31 opera volumio[1016]: info: Mar 01 07:25:34 opera volumio[1016]: info: CoreCommandRouter::volumioSeek Mar 01 07:25:34 opera volumio[1016]: info: CoreStateMachine::seek Mar 01 07:25:34 opera volumio[1016]: info: CorePlayQueue::getTrack 8 Mar 01 07:25:34 opera volumio[1016]: info: TRACKBLOCK {"uri":"mnt/USB/music/music/Adele - 25 (Vinyl-Rip 24-96) 2015 FLAC/01 Hello.flac","service":"mpd","name":"Hello","artist":"Adele","album":"25","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=161&web=Adele/25/extralarge&path=%2FUSB%2Fmusic%2Fmusic%2FAdele%20-%2025%20(Vinyl-Rip%2024-96)%202015%20FLAC&metadata=false","duration":295,"samplerate":"44.1 KHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 01 07:25:34 opera volumio[1016]: info: CoreStateMachine::startPlaybackTimer Mar 01 07:25:34 opera volumio[1016]: info: CorePlayQueue::getTrack 8 Mar 01 07:25:34 opera volumio[1016]: info: ControllerMpd::seek Mar 01 07:25:34 opera volumio[1016]: info: CoreStateMachine::pushState Mar 01 07:25:34 opera volumio[1016]: info: CoreStateMachine::getState Mar 01 07:25:34 opera volumio[1016]: info: CorePlayQueue::getTrack 8 Mar 01 07:25:34 opera volumio[1016]: info: CoreCommandRouter::volumioPushState Mar 01 07:25:34 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 07:25:34 opera volumio[1016]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/music/music/Adele - 25 (Vinyl-Rip 24-96) 2015 FLAC/01 Hello.flac","favourite":false} Mar 01 07:25:34 opera volumio[1016]: info: Error: [2@0] {} Bad song index Mar 01 07:25:34 opera volumio[1016]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Mar 01 07:25:34 opera volumio[1016]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Mar 01 07:25:34 opera volumio[1016]: at emitOne (events.js:96:13) Mar 01 07:25:34 opera volumio[1016]: at Socket.emit (events.js:188:7) Mar 01 07:25:34 opera volumio[1016]: at readableAddChunk (_stream_readable.js:176:18) Mar 01 07:25:34 opera volumio[1016]: at Socket.Readable.push (_stream_readable.js:134:10) Mar 01 07:25:34 opera volumio[1016]: at Pipe.onread (net.js:547:20) Mar 01 07:25:57 opera volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 07:25:57 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 01 07:25:57 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 01 07:25:57 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 01 07:25:57 opera volumio[1016]: info: Received Get System Version Mar 01 07:25:57 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:06 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:22 opera volumio[1016]: info: CorePlayQueue::getTrack 8 Mar 01 07:26:22 opera volumio[1016]: info: CorePlayQueue::getTrack 9 Mar 01 07:26:24 opera volumio[1016]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 07:26:24 opera volumio[1016]: info: Output device has changed, restarting MPD Mar 01 07:26:24 opera volumio[1016]: info: Output device has changed, restarting Shairport Sync Mar 01 07:26:24 opera sudo[11047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 07:26:24 opera sudo[11047]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 07:26:24 opera sudo[11049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 07:26:24 opera sudo[11047]: pam_unix(sudo:session): session closed for user root Mar 01 07:26:24 opera sudo[11049]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 07:26:24 opera volumio[1016]: info: Found match in Cards Database: setting mixer Digital for card DAC Mar 01 07:26:24 opera systemd[1]: Stopping Music Player Daemon... Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 07:26:24 opera volumio[1016]: info: Updating Volume Controller Parameters: Device: 0 Name: DAC Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 07:26:24 opera volumio[1016]: info: MPD Permissions set Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera systemd[1]: Starting Music Player Daemon... Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 07:26:24 opera sudo[11049]: pam_unix(sudo:session): session closed for user root Mar 01 07:26:24 opera systemd[1]: Started Music Player Daemon. Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera volumio[1016]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 07:26:24 opera sudo[11078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 01 07:26:24 opera sudo[11078]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 07:26:24 opera systemd[1]: Stopping ShairportSync AirTunes receiver... Mar 01 07:26:24 opera systemd[1]: Starting ShairportSync AirTunes receiver... Mar 01 07:26:24 opera systemd[1]: Started ShairportSync AirTunes receiver. Mar 01 07:26:24 opera sudo[11078]: pam_unix(sudo:session): session closed for user root Mar 01 07:26:24 opera shairport-sync[11084]: Successful Startup Mar 01 07:26:24 opera volumio[1016]: info: [1740813984895] Shairport-Sync Started Mar 01 07:26:25 opera volumio[1016]: error: Upnp client error: Error: This socket has been ended by the other party Mar 01 07:26:36 opera volumio[1016]: info: CoreCommandRouter::volumioSeek Mar 01 07:26:36 opera volumio[1016]: info: CoreStateMachine::seek Mar 01 07:26:36 opera volumio[1016]: info: CorePlayQueue::getTrack 8 Mar 01 07:26:36 opera volumio[1016]: info: TRACKBLOCK {"uri":"mnt/USB/music/music/Adele - 25 (Vinyl-Rip 24-96) 2015 FLAC/01 Hello.flac","service":"mpd","name":"Hello","artist":"Adele","album":"25","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=161&web=Adele/25/extralarge&path=%2FUSB%2Fmusic%2Fmusic%2FAdele%20-%2025%20(Vinyl-Rip%2024-96)%202015%20FLAC&metadata=false","duration":295,"samplerate":"44.1 KHz","bitdepth":"16 bit","trackType":"flac","channels":2} Mar 01 07:26:36 opera volumio[1016]: info: CoreStateMachine::startPlaybackTimer Mar 01 07:26:36 opera volumio[1016]: info: CorePlayQueue::getTrack 8 Mar 01 07:26:36 opera volumio[1016]: info: ControllerMpd::seek Mar 01 07:26:36 opera volumio[1016]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 07:26:36 opera volumio[1016]: { AssertionError: false == true Mar 01 07:26:36 opera volumio[1016]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Mar 01 07:26:36 opera volumio[1016]: at ControllerMpd.seek (/volumio/app/plugins/music_service/mpd/index.js:2722:24) Mar 01 07:26:36 opera volumio[1016]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1179:15) Mar 01 07:26:36 opera volumio[1016]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1303:27) Mar 01 07:26:36 opera volumio[1016]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:151:31) Mar 01 07:26:36 opera volumio[1016]: at emitTwo (events.js:106:13) Mar 01 07:26:36 opera volumio[1016]: at Socket.emit (events.js:191:7) Mar 01 07:26:36 opera volumio[1016]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 01 07:26:36 opera volumio[1016]: at _combinedTickCallback (internal/process/next_tick.js:73:7) Mar 01 07:26:36 opera volumio[1016]: at process._tickCallback (internal/process/next_tick.js:104:9) Mar 01 07:26:36 opera volumio[1016]: name: 'AssertionError', Mar 01 07:26:36 opera volumio[1016]: actual: false, Mar 01 07:26:36 opera volumio[1016]: expected: true, Mar 01 07:26:36 opera volumio[1016]: operator: '==', Mar 01 07:26:36 opera volumio[1016]: message: 'false == true', Mar 01 07:26:36 opera volumio[1016]: generatedMessage: true } Mar 01 07:26:36 opera volumio[1016]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 07:26:37 opera sudo[11103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 07:25 Mar 01 07:26:37 opera sudo[11103]: 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"