-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2025-01-11 17:18:09 CET. -- Jan 11 17:17:02 volumio ntpd[792]: Soliciting pool server 156.17.20.254 Jan 11 17:17:03 volumio ntpd[792]: Soliciting pool server 178.215.228.24 Jan 11 17:17:04 volumio ntpd[792]: Soliciting pool server 150.254.65.62 Jan 11 17:17:04 volumio ntpd[792]: Soliciting pool server 193.123.37.231 Jan 11 17:17:05 volumio ntpd[792]: Soliciting pool server 80.68.234.117 Jan 11 17:17:05 volumio ntpd[792]: Soliciting pool server 213.239.154.12 Jan 11 17:17:06 volumio ntpd[792]: Soliciting pool server 178.239.19.58 Jan 11 17:17:06 volumio ntpd[792]: Soliciting pool server 51.77.52.155 Jan 11 17:17:07 volumio ntpd[792]: Soliciting pool server 89.161.47.132 Jan 11 17:17:07 volumio ntpd[792]: Soliciting pool server 193.111.32.123 Jan 11 17:17:08 volumio ntpd[792]: Soliciting pool server 77.237.25.197 Jan 11 17:17:09 volumio ntpd[792]: Soliciting pool server 91.210.128.220 Jan 11 17:17:11 volumio ntpd[792]: receive: Unexpected origin timestamp 0xeb2d1805.d4d6922b does not match aorg 0000000000.00000000 from server@150.254.65.62 xmt 0xeb2d1807.a5f52262 Jan 11 17:17:11 volumio ntpd[792]: receive: Unexpected origin timestamp 0xeb2d1805.d4d3b5bf does not match aorg 0000000000.00000000 from server@193.123.37.231 xmt 0xeb2d1807.a5a72558 Jan 11 17:17:12 volumio ntpd[792]: Soliciting pool server 2606:4700:f1::123 Jan 11 17:17:14 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 17:17:16 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 17:17:24 volumio volumio[945]: info: Checking if install.sh is present Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 17:17:24 volumio volumio[945]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 11 17:17:24 volumio volumio[945]: info: Completed starting MyVolumio Plugin Jan 11 17:17:24 volumio volumio[945]: [Metrics] CommandRouter: 72s 708.94ms Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::volumiosetStartupVolume Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::Close All Modals sent Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::Close All Modals sent Jan 11 17:17:24 volumio volumio[945]: info: Executing install.sh Jan 11 17:17:24 volumio volumio[945]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 11 17:17:24 volumio volumio[945]: aplay: main:828: audio open error: Device or resource busy Jan 11 17:17:24 volumio volumio-remote-updater[583]: No test mode Jan 11 17:17:24 volumio volumio-remote-updater[583]: No alpha test mode Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 11 17:17:24 volumio sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/ytmusic/install.sh Jan 11 17:17:24 volumio sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 17:17:24 volumio sudo[1396]: pam_unix(sudo:session): session closed for user root Jan 11 17:17:24 volumio volumio[945]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

","title":"Update v3.785","updateavailable":true} Jan 11 17:17:24 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 11 17:17:24 volumio volumio[945]: info: Install script completed Jan 11 17:17:25 volumio volumio[945]: info: Adding reference to registry Jan 11 17:17:25 volumio volumio[945]: info: Done installing plugin. Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 11 17:17:25 volumio volumio[945]: info: Folder /tmp/plugins removed Jan 11 17:17:25 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 17:17:25 volumio volumio[945]: info: YouTube Music plugin installed Jan 11 17:17:25 volumio volumio[945]: Plugin install end detected on script Jan 11 17:17:25 volumio volumio[945]: info: Folder /data/temp removed Jan 11 17:17:25 volumio volumio[945]: verbose: New Socket.io Connection to 192.168.0.199 from 192.168.0.31 UA: Mozilla/5.0 (X11; Linux x86_64; rv:134.0) Gecko/20100101 Firefox/134.0 Engine version: 3 Transport: polling Total Clients: 4 Jan 11 17:17:25 volumio volumio[945]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::volumioGetState Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 17:17:25 volumio volumio[945]: info: Listing playlists Jan 11 17:17:25 volumio volumio[945]: info: CoreCommandRouter::volumioGetQueue Jan 11 17:17:25 volumio volumio[945]: info: CoreStateMachine::getQueue Jan 11 17:17:25 volumio volumio[945]: info: CorePlayQueue::getQueue Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:26 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:26 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:26 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand status took 9 milliseconds Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand status took 6 milliseconds Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand status took 4 milliseconds Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:26 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 0 Jan 11 17:17:26 volumio volumio[945]: 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} Jan 11 17:17:26 volumio volumio[945]: verbose: CURRENT POSITION 0 Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::syncState stateService stop Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::play index undefined Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:26 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Jan 11 17:17:26 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current soundcloud Received mpd Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::startPlaybackTimer Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: [soundcloud-play] clearAddPlayTrack: soundcloud/track@trackId=1200086053@fromAlbumId=1382570491 Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 110ms Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 108ms Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 106ms Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:26 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:17:26 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:26 volumio volumio[945]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand stop took 2 milliseconds Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand clear took 4 milliseconds Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ" Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: error: updateQueue error: null Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 14ms Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand addid "https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ" took 11 milliseconds Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 10ms Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 8ms Jan 11 17:17:26 volumio volumio[945]: verbose: MPD COMMAND [object Object] Jan 11 17:17:26 volumio volumio[945]: verbose: MPD COMMAND [object Object] Jan 11 17:17:26 volumio volumio[945]: verbose: MPD COMMAND [object Object] Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 17ms Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 10ms Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 7ms Jan 11 17:17:26 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 17:17:26 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand play Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: Jan 11 17:17:26 volumio volumio[945]: ---------------------------- MPD announces system playlist update Jan 11 17:17:26 volumio volumio[945]: info: Ignoring MPD Status Update Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 20ms Jan 11 17:17:26 volumio volumio[945]: info: sendMpdCommand play took 15 milliseconds Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 14ms Jan 11 17:17:26 volumio volumio[945]: info: ------------------------------ 11ms Jan 11 17:17:27 volumio volumio[945]: info: Jan 11 17:17:27 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:27 volumio volumio[945]: info: Jan 11 17:17:27 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:27 volumio volumio[945]: info: Jan 11 17:17:27 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand status took 15 milliseconds Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:27 volumio volumio[945]: info: Jan 11 17:17:27 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand status took 19 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand status took 17 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:27 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:27 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:27 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus stop Jan 11 17:17:27 volumio volumio[945]: info: ------------------------------ 39ms Jan 11 17:17:27 volumio volumio[945]: info: Jan 11 17:17:27 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:27 volumio volumio[945]: info: Jan 11 17:17:27 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand status took 25 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand status took 11 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand status took 8 milliseconds Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:27 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:27 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:27 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:17:27 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:27 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:27 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:27 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:17:27 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: ------------------------------ 172ms Jan 11 17:17:27 volumio volumio[945]: info: ------------------------------ 170ms Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand playlistinfo took 127 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand playlistinfo took 125 milliseconds Jan 11 17:17:27 volumio volumio[945]: info: sendMpdCommand playlistinfo took 124 milliseconds Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:27 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:27 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:27 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:27 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:17:27 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:27 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:27 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:27 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:17:27 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:27 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:27 volumio volumio[945]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:27 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState stateService play Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus play Jan 11 17:17:27 volumio volumio[945]: info: Received an update from plugin. extracting info from payload Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:27 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:27 volumio volumio[945]: info: ------------------------------ 300ms Jan 11 17:17:27 volumio volumio[945]: info: ------------------------------ 284ms Jan 11 17:17:27 volumio volumio[945]: info: ------------------------------ 282ms Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::volumioPause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::stPlaybackTimer Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::servicePause Jan 11 17:17:30 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::servicePause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::pause Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand pause Jan 11 17:17:30 volumio volumio[945]: info: Jan 11 17:17:30 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand pause took 2 milliseconds Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:30 volumio volumio[945]: info: Jan 11 17:17:30 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:30 volumio volumio[945]: info: Jan 11 17:17:30 volumio volumio[945]: ---------------------------- MPD announces state update: player Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::getState Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand status Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand status took 6 milliseconds Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand status took 4 milliseconds Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand status took 2 milliseconds Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::parseState Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 17:17:30 volumio volumio[945]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:30 volumio volumio[945]: verbose: ControllerMpd::parseTrackInfo Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:30 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:30 volumio volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4102,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:30 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::syncState stateService pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::stPlaybackTimer Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:30 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:30 volumio volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4102,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:30 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::syncState stateService pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::stPlaybackTimer Jan 11 17:17:30 volumio volumio[945]: info: ControllerMpd::pushState Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::servicePushState Jan 11 17:17:30 volumio volumio[945]: info: CorePlayQueue::getTrack 1 Jan 11 17:17:30 volumio volumio[945]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4102,"duration":30,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Supernova","artist":"(Preview) Digital Emotion","album":"Moving to the Top / Supernova","uri":"https://cf-preview-media.sndcdn.com/preview/0/30/Bf3Rv2kSXSl3.128.mp3?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiKjovL2NmLXByZXZpZXctbWVkaWEuc25kY2RuLmNvbS9wcmV2aWV3LzAvMzAvQmYzUnYya1NYU2wzLjEyOC5tcDMqIiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzM2NjEyNzMzfX19XX0_&Signature=UnhqBGqe04QkcXJUhTZFUnior41ksJkozT7QApI7KdS2C2kQ1gJdXsmlPPrfWUZVWsGhGn~WT8EzrpO1HDgXG2XFgN43nSFuY179rQiYbzywQ3bieIMYIl261hBm31io-Rd9NK8yXXHgUbFIGaFtklx1-Xh0RNfvmnoklW41Miy~hlqvB~mpKvA3hiSScd5hWzhPsk2~~fYSiL2EwJHpKFhj4x7Ts3JRZKIkGff9iELQgD7JAH3Skx7uZ~U68lGQej-Gg6p9WIMWUUd2LKAXy~MbdBpP5u3bP5BpyGkGIOElu1xElyKmqierdMv-zK3umrCymP15bi9wsGapx2TsZg__&Key-Pair-Id=APKAI6TU7MMXM5DG6EPQ","trackType":""} Jan 11 17:17:30 volumio volumio[945]: verbose: CURRENT POSITION 1 Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::syncState stateService pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::syncState currentStatus pause Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::pushState Jan 11 17:17:30 volumio volumio[945]: info: CoreCommandRouter::volumioPushState Jan 11 17:17:30 volumio volumio[945]: info: CoreStateMachine::stPlaybackTimer Jan 11 17:17:30 volumio volumio[945]: info: ------------------------------ 48ms Jan 11 17:17:30 volumio volumio[945]: info: ------------------------------ 46ms Jan 11 17:17:30 volumio volumio[945]: info: ------------------------------ 45ms Jan 11 17:17:31 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 17:17:31 volumio volumio[945]: info: BOOT COMPLETED Jan 11 17:17:33 volumio volumio[1104]: .......................................................................................................................................................................................................................................................................................++++ Jan 11 17:17:33 volumio volumio[1104]: e is 65537 (0x010001) Jan 11 17:17:33 volumio volumio[1104]: writing RSA key Jan 11 17:17:37 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 17:17:41 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/buster/armhf Jan 11 17:17:44 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 17:17:44 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytmusic/volumio/buster/armhf Jan 11 17:17:44 volumio volumio[945]: info: Folder /tmp/plugins removed Jan 11 17:17:45 volumio volumio[945]: info: Check plugin dependencies Jan 11 17:17:45 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 17:17:45 volumio volumio[945]: info: Checking if plugin already exists Jan 11 17:17:45 volumio volumio[945]: info: An error occurred installing the plugin. Rolling back config Jan 11 17:17:45 volumio volumio[945]: info: Plugin folders cleanup Jan 11 17:17:45 volumio volumio[945]: info: Scanning into folder /volumio/app/plugins/ Jan 11 17:17:45 volumio volumio[945]: info: Scanning category audio_interface Jan 11 17:17:45 volumio volumio[945]: info: Scanning category miscellanea Jan 11 17:17:45 volumio volumio[945]: info: Scanning category music_service Jan 11 17:17:45 volumio volumio[945]: info: Scanning category plugins.json Jan 11 17:17:45 volumio volumio[945]: info: Scanning category system_controller Jan 11 17:17:45 volumio volumio[945]: info: Scanning category user_interface Jan 11 17:17:45 volumio volumio[945]: info: Scanning into folder /data/plugins/ Jan 11 17:17:45 volumio volumio[945]: info: Scanning category music_service Jan 11 17:17:45 volumio volumio[945]: info: Scanning category system_controller Jan 11 17:17:45 volumio volumio[945]: info: Scanning category system_hardware Jan 11 17:17:45 volumio volumio[945]: info: Plugin folders cleanup completed Jan 11 17:17:45 volumio volumio[945]: info: Error: Error Jan 11 17:17:45 volumio volumio[945]: info: Folder /tmp/plugins removed Jan 11 17:17:45 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 17:17:45 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 17:17:46 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 17:17:46 volumio volumio[945]: info: Folder /data/temp removed Jan 11 17:17:51 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytcr/volumio/buster/armhf Jan 11 17:17:52 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 17:17:54 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 17:17:56 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ytcr/volumio/buster/armhf Jan 11 17:17:56 volumio volumio[945]: info: Folder /tmp/plugins removed Jan 11 17:18:01 volumio volumio[945]: info: Check plugin dependencies Jan 11 17:18:01 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 17:18:01 volumio volumio[945]: info: Checking if plugin already exists Jan 11 17:18:01 volumio volumio[945]: info: An error occurred installing the plugin. Rolling back config Jan 11 17:18:01 volumio volumio[945]: info: Plugin folders cleanup Jan 11 17:18:01 volumio volumio[945]: info: Scanning into folder /volumio/app/plugins/ Jan 11 17:18:01 volumio volumio[945]: info: Scanning category audio_interface Jan 11 17:18:01 volumio volumio[945]: info: Scanning category miscellanea Jan 11 17:18:01 volumio volumio[945]: info: Scanning category music_service Jan 11 17:18:01 volumio volumio[945]: info: Scanning category plugins.json Jan 11 17:18:01 volumio volumio[945]: info: Scanning category system_controller Jan 11 17:18:01 volumio volumio[945]: info: Scanning category user_interface Jan 11 17:18:01 volumio volumio[945]: info: Scanning into folder /data/plugins/ Jan 11 17:18:01 volumio volumio[945]: info: Scanning category music_service Jan 11 17:18:01 volumio volumio[945]: info: Scanning category system_controller Jan 11 17:18:01 volumio volumio[945]: info: Scanning category system_hardware Jan 11 17:18:01 volumio volumio[945]: info: Plugin folders cleanup completed Jan 11 17:18:01 volumio volumio[945]: info: Error: Error Jan 11 17:18:01 volumio volumio[945]: info: Folder /tmp/plugins removed Jan 11 17:18:01 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 17:18:01 volumio volumio[945]: info: Folder /tmp/downloaded_plugin.zip removed Jan 11 17:18:02 volumio kernel: hwmon hwmon1: Voltage normalised Jan 11 17:18:05 volumio volumio[945]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/buster/armhf Jan 11 17:18:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 11 17:18:07 volumio volumio[945]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/buster/armhf Jan 11 17:18:07 volumio volumio[945]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSoundCloud Jan 11 17:18:07 volumio volumio[945]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 17:18:07 volumio volumio[945]: Cannot find translation for source Bandcamp Discover Jan 11 17:18:07 volumio volumio[945]: Cannot find translation for source Mixcloud Jan 11 17:18:08 volumio volumio[945]: info: Folder /data/temp removed Jan 11 17:18:08 volumio volumio[945]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 17:18:08 volumio volumio[945]: Error: Unable to resolve or reject the same promise twice Jan 11 17:18:08 volumio volumio[945]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 11 17:18:08 volumio volumio[945]: at /volumio/app/pluginmanager.js:1087:11 Jan 11 17:18:08 volumio volumio[945]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jan 11 17:18:08 volumio volumio[945]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jan 11 17:18:08 volumio volumio[945]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jan 11 17:18:08 volumio volumio[945]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jan 11 17:18:08 volumio volumio[945]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jan 11 17:18:08 volumio volumio[945]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Jan 11 17:18:08 volumio volumio[945]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Jan 11 17:18:08 volumio volumio[945]: at FSReqCallback.oncomplete (fs.js:171:23) Jan 11 17:18:08 volumio volumio[945]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 17:18:09 volumio sudo[1571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 17:17 Jan 11 17:18:09 volumio sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"