-- Logs begin at Thu 2020-09-24 20:42:43 UTC, end at Thu 2024-05-09 15:13:32 UTC. -- May 09 15:12:04 xxxxx volumio[28759]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 09 15:12:04 xxxxx volumio[28759]: info: CoreCommandRouter::Close All Modals sent May 09 15:12:04 xxxxx volumio[28759]: info: CoreCommandRouter::Close All Modals sent May 09 15:12:04 xxxxx volumio[28759]: Express server listening on port 3000 May 09 15:12:04 xxxxx volumio[28759]: [Metrics] WebUI: 8s 169.82ms May 09 15:12:04 xxxxx volumio[28759]: Volumio Calling Home May 09 15:12:04 xxxxx volumio[28759]: info: CoreStateMachine::resetVolumioState May 09 15:12:04 xxxxx volumio[28759]: info: CoreStateMachine::getcurrentVolume May 09 15:12:04 xxxxx volumio[28759]: info: CoreCommandRouter::volumioRetrievevolume May 09 15:12:04 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:04 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:04 xxxxx volumio[28759]: info: Setting Device type: Raspberry PI May 09 15:12:04 xxxxx volumio[28759]: info: MPD running with PID27876 May 09 15:12:04 xxxxx volumio[28759]: ,establishing connection May 09 15:12:04 xxxxx sudo[28882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 09 15:12:04 xxxxx sudo[28882]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:04 xxxxx sudo[28882]: pam_unix(sudo:session): session closed for user root May 09 15:12:05 xxxxx volumio[28759]: info: VolumeController:: Volume=35 Mute =false May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::updateTrackBlock May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrackBlock May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioRetrievevolume May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:05 xxxxx volumio[28759]: info: Reloading queue from file May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 15:12:05 xxxxx volumio[28759]: error: Failed LSINFO: Error: [50@0] {} No such directory May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::setRepeat false single undefined May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::setRandom true May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 15:12:05 xxxxx volumio[28759]: info: Listing playlists May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetQueue May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::getQueue May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getQueue May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetVisibleSources May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:05 xxxxx volumio[28759]: info: VolumeController:: Volume=35 Mute =false May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: Starting Shairport Sync May 09 15:12:05 xxxxx volumio[28759]: info: Starting Shairport Sync May 09 15:12:05 xxxxx sudo[28895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 15:12:05 xxxxx sudo[28895]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:05 xxxxx sudo[28898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 15:12:05 xxxxx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 15:12:05 xxxxx sudo[28898]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:05 xxxxx systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 09 15:12:05 xxxxx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 15:12:05 xxxxx sudo[28895]: pam_unix(sudo:session): session closed for user root May 09 15:12:05 xxxxx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 15:12:05 xxxxx systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 09 15:12:05 xxxxx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 15:12:05 xxxxx sudo[28898]: pam_unix(sudo:session): session closed for user root May 09 15:12:05 xxxxx volumio[28759]: info: Shairport-Sync Started May 09 15:12:05 xxxxx volumio[28759]: Error adding Membership: Error: addMembership EINVAL May 09 15:12:05 xxxxx volumio[28759]: info: Shairport-Sync Started May 09 15:12:05 xxxxx volumio[28759]: Discovery: adding a5ee7c44-89eb-4bf4-9ce8-e49e72683375 May 09 15:12:05 xxxxx volumio[28759]: info: mDNS: Found device Volumio May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: May 09 15:12:05 xxxxx volumio[28759]: ---------------------------- MPD announces state update: player May 09 15:12:05 xxxxx volumio[28759]: info: ControllerMpd::getState May 09 15:12:05 xxxxx volumio[28759]: verbose: ControllerMpd::sendMpdCommand status May 09 15:12:05 xxxxx volumio[28759]: verbose: ControllerMpd::parseState May 09 15:12:05 xxxxx volumio[28759]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:12:05 xxxxx volumio[28759]: verbose: ControllerMpd::parseTrackInfo May 09 15:12:05 xxxxx volumio[28759]: info: ControllerMpd::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::servicePushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"渡口","artist":"蔡琴","album":"民歌蔡琴","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F0000041aqiP3k8MYA.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=8067B0E62B8F7526D567282E066A2066C8B776623A567759D6F7FA01380D8D4BD6F0642902CCEA4CAEB70870197D5989484956D4F415832E&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:12:05 xxxxx volumio[28759]: verbose: CURRENT POSITION 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::syncState stateService stop May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::syncState currentStatus stop May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: No code May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:05 xxxxx volumio[28759]: info: ------------------------------ 41ms May 09 15:12:05 xxxxx volumio[28759]: Discovery: this is already registered, a5ee7c44-89eb-4bf4-9ce8-e49e72683375 May 09 15:12:05 xxxxx volumio[28759]: info: mDNS: Found device Volumio May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetState May 09 15:12:05 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:05 xxxxx volumio[28759]: info: Clearing queue after UPNP request May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 15:12:05 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::ClearQueue May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::stop May 09 15:12:05 xxxxx volumio[28759]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:12:06 xxxxx volumio[28759]: UNSET VOLATILE May 09 15:12:06 xxxxx volumio[28759]: info: CorePlayQueue::clearPlayQueue May 09 15:12:06 xxxxx volumio[28759]: info: CorePlayQueue::saveQueue May 09 15:12:06 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:06 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:06 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushQueue May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: error: updateQueue error: null May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 2ms May 09 15:12:06 xxxxx volumio[28759]: info: Starting UPNP Playback May 09 15:12:06 xxxxx volumio[28759]: info: Preparing playback through UPNP May 09 15:12:06 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetState May 09 15:12:06 xxxxx volumio[28759]: info: CorePlayQueue::getTrack 0 May 09 15:12:06 xxxxx volumio[28759]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:12:06 xxxxx volumio[28759]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 09 15:12:06 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 3ms May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 6ms May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 8ms May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 1ms May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 1ms May 09 15:12:06 xxxxx volumio[28759]: info: May 09 15:12:06 xxxxx volumio[28759]: ---------------------------- MPD announces system playlist update May 09 15:12:06 xxxxx volumio[28759]: info: Ignoring MPD Status Update May 09 15:12:06 xxxxx volumio[28759]: info: ------------------------------ 1ms May 09 15:12:07 xxxxx volumio[28759]: info: May 09 15:12:07 xxxxx volumio[28759]: ---------------------------- MPD announces state update: player May 09 15:12:07 xxxxx volumio[28759]: info: ControllerMpd::getState May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::sendMpdCommand status May 09 15:12:07 xxxxx volumio[28759]: info: May 09 15:12:07 xxxxx volumio[28759]: ---------------------------- MPD announces state update: player May 09 15:12:07 xxxxx volumio[28759]: info: ControllerMpd::getState May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::sendMpdCommand status May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::parseState May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::parseState May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::parseTrackInfo May 09 15:12:07 xxxxx volumio[28759]: info: ControllerMpd::pushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::servicePushState May 09 15:12:07 xxxxx volumio[28759]: In UPNP mode May 09 15:12:07 xxxxx volumio[28759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":216,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"G.O.A.T.","artist":"Polyphia","album":"New Levels New Devils","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002zLgon1O7vuG.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=0B1886765889A885EA66639A75F2361C63F9A1FC56F80770A4AF9086582AA5A8A6BD27B436C7F0B31516027B9B8B8A6D39D1333CDD63DFF7&uin=317524287&src=F000001eINgI1nzx2v.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:12:07 xxxxx volumio[28759]: verbose: CURRENT POSITION 0 May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::syncState stateService play May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::syncState currentStatus stop May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:07 xxxxx volumio[28759]: info: ------------------------------ 18ms May 09 15:12:07 xxxxx volumio[28759]: verbose: ControllerMpd::parseTrackInfo May 09 15:12:07 xxxxx volumio[28759]: info: ControllerMpd::pushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::servicePushState May 09 15:12:07 xxxxx volumio[28759]: In UPNP mode May 09 15:12:07 xxxxx volumio[28759]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":216,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"G.O.A.T.","artist":"Polyphia","album":"New Levels New Devils","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002zLgon1O7vuG.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=0B1886765889A885EA66639A75F2361C63F9A1FC56F80770A4AF9086582AA5A8A6BD27B436C7F0B31516027B9B8B8A6D39D1333CDD63DFF7&uin=317524287&src=F000001eINgI1nzx2v.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:12:07 xxxxx volumio[28759]: verbose: CURRENT POSITION 0 May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::syncState stateService play May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::syncState currentStatus play May 09 15:12:07 xxxxx volumio[28759]: info: Received an update from plugin. extracting info from payload May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::pushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::volumioPushState May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:07 xxxxx volumio[28759]: info: ------------------------------ 32ms May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetQueue May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::getQueue May 09 15:12:07 xxxxx volumio[28759]: info: CorePlayQueue::getQueue May 09 15:12:07 xxxxx volumio[28759]: info: CoreCommandRouter::volumioGetQueue May 09 15:12:07 xxxxx volumio[28759]: info: CoreStateMachine::getQueue May 09 15:12:07 xxxxx volumio[28759]: info: CorePlayQueue::getQueue May 09 15:12:08 xxxxx sudo[28955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 15:12:08 xxxxx sudo[28955]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:08 xxxxx sudo[28955]: pam_unix(sudo:session): session closed for user root May 09 15:12:08 xxxxx sudo[28958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 15:12:08 xxxxx sudo[28958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:08 xxxxx sudo[28965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 15:12:08 xxxxx sudo[28958]: pam_unix(sudo:session): session closed for user root May 09 15:12:08 xxxxx sudo[28965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:08 xxxxx systemd[1]: Started UPnP Renderer front-end to MPD. May 09 15:12:08 xxxxx sudo[28965]: pam_unix(sudo:session): session closed for user root May 09 15:12:08 xxxxx volumio[28759]: info: Upmpdcli Daemon Started May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 09 15:12:09 xxxxx volumio[28759]: info: Adding plugin bluetooth to MyMusic Plugins May 09 15:12:09 xxxxx volumio[28759]: info: Adding plugin cd_controller to MyMusic Plugins May 09 15:12:09 xxxxx volumio[28759]: info: Adding plugin smart_inputs to MyMusic Plugins May 09 15:12:09 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 09 15:12:10 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 09 15:12:10 xxxxx volumio[28759]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 09 15:12:10 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 09 15:12:10 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:10 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:10 xxxxx volumio[28759]: info: Starting MyVolumio Remote Streaming Endpoints May 09 15:12:10 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 09 15:12:10 xxxxx volumio[28759]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 09 15:12:10 xxxxx volumio[28759]: info: Streaming services startup May 09 15:12:10 xxxxx volumio[28759]: info: Starting Streaming Daemon May 09 15:12:10 xxxxx volumio[28759]: info: MyVolumio not started May 09 15:12:10 xxxxx volumio[28759]: info: Initializing device activation check May 09 15:12:10 xxxxx sudo[28984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 15:12:10 xxxxx sudo[28984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:10 xxxxx volumio[28759]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 09 15:12:10 xxxxx systemd[1]: Stopping Volumio Streaming Daemon... May 09 15:12:10 xxxxx systemd[1]: Starting Volumio Streaming Daemon... May 09 15:12:10 xxxxx systemd[1]: Started Volumio Streaming Daemon. May 09 15:12:10 xxxxx sudo[28984]: pam_unix(sudo:session): session closed for user root May 09 15:12:10 xxxxx volumio-streaming-daemon[28990]: ############################ May 09 15:12:10 xxxxx volumio-streaming-daemon[28990]: # Volumio Streaming Daemon # May 09 15:12:10 xxxxx volumio-streaming-daemon[28990]: # Running on port 7777 # May 09 15:12:10 xxxxx volumio-streaming-daemon[28990]: ############################ May 09 15:12:10 xxxxx volumio-streaming-daemon[28990]: Environment: production May 09 15:12:13 xxxxx volumio-streaming-daemon[28990]: Environment: production May 09 15:12:13 xxxxx volumio[28759]: info: Fetching Streaming Services browse cache May 09 15:12:13 xxxxx volumio[28759]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL May 09 15:12:13 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:13 xxxxx volumio[28759]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 09 15:12:13 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:15 xxxxx volumio[28759]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:12:15 xxxxx volumio[28759]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:12:15 xxxxx volumio[28759]: Dload Upload Total Spent Left Speed May 09 15:12:15 xxxxx volumio[28759]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 09 15:12:15 xxxxx volumio[28759]: retrying in 5 seconds, trial 0 May 09 15:12:15 xxxxx volumio[28759]: Volumio Calling Home May 09 15:12:26 xxxxx volumio[28759]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:12:26 xxxxx volumio[28759]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:12:26 xxxxx volumio[28759]: Dload Upload Total Spent Left Speed May 09 15:12:26 xxxxx volumio[28759]: [235B blob data] May 09 15:12:26 xxxxx volumio[28759]: retrying in 5 seconds, trial 1 May 09 15:12:26 xxxxx volumio[28759]: Volumio Calling Home May 09 15:12:29 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 15:12:29 xxxxx volumio[28759]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:37 xxxxx volumio[28759]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:12:37 xxxxx volumio[28759]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:12:37 xxxxx volumio[28759]: Dload Upload Total Spent Left Speed May 09 15:12:37 xxxxx volumio[28759]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 09 15:12:37 xxxxx volumio[28759]: retrying in 5 seconds, trial 2 May 09 15:12:37 xxxxx volumio[28759]: Volumio Calling Home May 09 15:12:39 xxxxx volumio[28759]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 15:12:39 xxxxx volumio[28759]: { Error: connect ETIMEDOUT 162.125.32.9:443 May 09 15:12:39 xxxxx volumio[28759]: at Object._errnoException (util.js:1022:11) May 09 15:12:39 xxxxx volumio[28759]: at _exceptionWithHostPort (util.js:1044:20) May 09 15:12:39 xxxxx volumio[28759]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 09 15:12:39 xxxxx volumio[28759]: code: 'ETIMEDOUT', May 09 15:12:39 xxxxx volumio[28759]: errno: 'ETIMEDOUT', May 09 15:12:39 xxxxx volumio[28759]: syscall: 'connect', May 09 15:12:39 xxxxx volumio[28759]: address: '162.125.32.9', May 09 15:12:39 xxxxx volumio[28759]: port: 443 } May 09 15:12:39 xxxxx volumio[28759]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 15:12:39 xxxxx sudo[29113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 15:11 May 09 15:12:39 xxxxx sudo[29113]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:40 xxxxx sudo[29113]: pam_unix(sudo:session): session closed for user root May 09 15:12:40 xxxxx systemd[1]: volumio.service: main process exited, code=exited, status=1/FAILURE May 09 15:12:40 xxxxx systemd[1]: Unit volumio.service entered failed state. May 09 15:12:40 xxxxx systemd[1]: Starting dynamicswap service... May 09 15:12:40 xxxxx systemd[1]: Started dynamicswap service. May 09 15:12:40 xxxxx systemd[1]: volumio.service holdoff time over, scheduling restart. May 09 15:12:40 xxxxx systemd[1]: Starting dynamicswap service... May 09 15:12:40 xxxxx systemd[1]: Started dynamicswap service. May 09 15:12:40 xxxxx systemd[1]: Stopping Volumio Backend Module... May 09 15:12:40 xxxxx systemd[1]: Starting Volumio Backend Module... May 09 15:12:40 xxxxx systemd[1]: Started Volumio Backend Module. May 09 15:12:41 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:41 xxxxx volumio[29134]: info: ----- Volumio2 ---- May 09 15:12:41 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:41 xxxxx volumio[29134]: info: ----- System startup ---- May 09 15:12:41 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:41 xxxxx volumio[29134]: info: MYVOLUMIO Environment detected May 09 15:12:41 xxxxx volumio[29134]: info: Plugin folders cleanup May 09 15:12:41 xxxxx volumio[29134]: info: Scanning into folder /volumio/app/plugins/ May 09 15:12:41 xxxxx volumio[29134]: info: Scanning category audio_interface May 09 15:12:41 xxxxx volumio[29134]: info: Scanning category miscellanea May 09 15:12:41 xxxxx volumio[29134]: info: Scanning category music_service May 09 15:12:41 xxxxx volumio[29134]: info: Scanning category plugins.json May 09 15:12:41 xxxxx volumio[29134]: info: Scanning category system_controller May 09 15:12:41 xxxxx volumio[29134]: info: Scanning category user_interface May 09 15:12:41 xxxxx volumio[29134]: info: Scanning into folder /data/plugins/ May 09 15:12:41 xxxxx volumio[29134]: info: Plugin folders cleanup completed May 09 15:12:41 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:41 xxxxx volumio[29134]: info: ----- Core plugins startup ---- May 09 15:12:41 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:41 xxxxx volumio[29134]: info: Loading plugins from folder /volumio/app/plugins/ May 09 15:12:41 xxxxx volumio[29134]: info: Adding plugin upnp to MyMusic Plugins May 09 15:12:41 xxxxx volumio[29134]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 15:12:41 xxxxx volumio[29134]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 15:12:41 xxxxx volumio[29134]: info: Loading plugins from folder /data/plugins/ May 09 15:12:41 xxxxx volumio[29134]: info: Loading plugin "system"... May 09 15:12:41 xxxxx volumio[29134]: info: Loading plugin "appearance"... May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "network"... May 09 15:12:42 xxxxx volumio[29134]: info: Refreshing Cached IP Addresses May 09 15:12:42 xxxxx sudo[29159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "services"... May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "alsa_controller"... May 09 15:12:42 xxxxx sudo[29159]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:42 xxxxx sudo[29159]: pam_unix(sudo:session): session closed for user root May 09 15:12:42 xxxxx sudo[29162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 15:12:42 xxxxx sudo[29162]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:42 xxxxx sudo[29162]: pam_unix(sudo:session): session closed for user root May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "wizard"... May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "volumio_command_line_client"... May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "upnp"... May 09 15:12:42 xxxxx volumio[29134]: info: [1715267562725] Starting Upmpd Daemon May 09 15:12:42 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "my_music"... May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "mpd"... May 09 15:12:42 xxxxx volumio[29134]: info: Loading plugin "upnp_browser"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "networkfs"... May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "alarm-clock"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "airplay_emulation"... May 09 15:12:43 xxxxx volumio[29134]: info: Starting Shairport Sync May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "last_100"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "webradio"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "i2s_dacs"... May 09 15:12:43 xxxxx volumio[29134]: info: I2S DAC not set, start Auto-detection May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "volumiodiscovery"... May 09 15:12:43 xxxxx volumio[29134]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 15:12:43 xxxxx node[29134]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 15:12:43 xxxxx node[29134]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 15:12:43 xxxxx node[29134]: *** WARNING *** For more information see May 09 15:12:43 xxxxx node[29134]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 15:12:43 xxxxx node[29134]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 15:12:43 xxxxx node[29134]: *** WARNING *** For more information see May 09 15:12:43 xxxxx volumio[29134]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 15:12:43 xxxxx volumio[29134]: *** WARNING *** For more information see May 09 15:12:43 xxxxx volumio[29134]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 15:12:43 xxxxx volumio[29134]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 15:12:43 xxxxx volumio[29134]: *** WARNING *** For more information see May 09 15:12:43 xxxxx volumio[29134]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 15:12:43 xxxxx volumio[29134]: Discovery: StartAdv! undefined May 09 15:12:43 xxxxx volumio[29134]: Discovery: Started advertising... Volumio - undefined May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "outputs"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "albumart"... May 09 15:12:43 xxxxx volumio[29134]: info: Plugin example_plugin is not enabled May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "inputs"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "updater_comm"... May 09 15:12:43 xxxxx volumio[29134]: info: Plugin mpdemulation is not enabled May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "rest_api"... May 09 15:12:43 xxxxx volumio[29134]: info: Loading plugin "websocket"... May 09 15:12:43 xxxxx volumio[29134]: info: ___________ START PLUGINS ___________ May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 15:12:43 xxxxx volumio[29134]: info: [1715267563688] CoreMusicLibrary::Adding element Media Servers May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:43 xxxxx volumio[29134]: Forking 3 albumart workers May 09 15:12:43 xxxxx volumio[29134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 15:12:43 xxxxx volumio[29134]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 15:12:43 xxxxx volumio[29134]: info: [1715267563784] CoreMusicLibrary::Adding element Last_100 May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 15:12:43 xxxxx volumio[29134]: info: [1715267563788] CoreMusicLibrary::Adding element Webradio May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 15:12:43 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:43 xxxxx volumio[29134]: info: ----- MyVolumio plugins startup ---- May 09 15:12:43 xxxxx volumio[29134]: info: ------------------------------------------- May 09 15:12:43 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Fetching plans data.... May 09 15:12:43 xxxxx volumio[29134]: info: Loading i18n strings for locale zh May 09 15:12:43 xxxxx volumio[29134]: Updating browse sources language May 09 15:12:43 xxxxx volumio[29134]: Cannot find translation for sourceMedia Servers May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::initPlayerControls May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 15:12:43 xxxxx volumio[29134]: info: BOOT COMPLETED May 09 15:12:43 xxxxx volumio[29134]: [Metrics] CommandRouter: 2s 469.39ms May 09 15:12:43 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:44 xxxxx volumio[29134]: Starting albumart workers May 09 15:12:44 xxxxx volumio[29134]: Starting albumart workers May 09 15:12:44 xxxxx volumio[29134]: Starting albumart workers May 09 15:12:49 xxxxx volumio[29134]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::Close All Modals sent May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::Close All Modals sent May 09 15:12:49 xxxxx volumio[29134]: Express server listening on port 3000 May 09 15:12:49 xxxxx volumio[29134]: [Metrics] WebUI: 8s 108.76ms May 09 15:12:49 xxxxx volumio[29134]: Volumio Calling Home May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::resetVolumioState May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::getcurrentVolume May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioRetrievevolume May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 15:12:49 xxxxx volumio[29134]: info: Setting Device type: Raspberry PI May 09 15:12:49 xxxxx volumio[29134]: info: MPD running with PID27876 May 09 15:12:49 xxxxx volumio[29134]: ,establishing connection May 09 15:12:49 xxxxx sudo[29256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 09 15:12:49 xxxxx sudo[29256]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:49 xxxxx sudo[29256]: pam_unix(sudo:session): session closed for user root May 09 15:12:49 xxxxx volumio[29134]: info: VolumeController:: Volume=35 Mute =false May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::updateTrackBlock May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrackBlock May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioRetrievevolume May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:49 xxxxx volumio[29134]: info: Reloading queue from file May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 09 15:12:49 xxxxx volumio[29134]: error: Failed LSINFO: Error: [50@0] {} No such directory May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::setRepeat false single undefined May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::setRandom true May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:49 xxxxx volumio[29134]: info: VolumeController:: Volume=35 Mute =false May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:49 xxxxx volumio[29134]: info: Starting Shairport Sync May 09 15:12:49 xxxxx volumio[29134]: info: Starting Shairport Sync May 09 15:12:49 xxxxx sudo[29269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 15:12:49 xxxxx sudo[29269]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:49 xxxxx sudo[29271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 15:12:49 xxxxx sudo[29271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:49 xxxxx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 15:12:49 xxxxx systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 09 15:12:49 xxxxx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx sudo[29269]: pam_unix(sudo:session): session closed for user root May 09 15:12:49 xxxxx systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 15:12:49 xxxxx systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 09 15:12:49 xxxxx systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 15:12:49 xxxxx sudo[29271]: pam_unix(sudo:session): session closed for user root May 09 15:12:49 xxxxx volumio[29134]: Discovery: adding a5ee7c44-89eb-4bf4-9ce8-e49e72683375 May 09 15:12:49 xxxxx volumio[29134]: info: mDNS: Found device Volumio May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: Discovery: this is already registered, a5ee7c44-89eb-4bf4-9ce8-e49e72683375 May 09 15:12:49 xxxxx volumio[29134]: info: mDNS: Found device Volumio May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 15:12:49 xxxxx volumio[29134]: info: Listing playlists May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:12:49 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:12:49 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetVisibleSources May 09 15:12:49 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:49 xxxxx volumio[29134]: info: Shairport-Sync Started May 09 15:12:49 xxxxx volumio[29134]: Error adding Membership: Error: addMembership EINVAL May 09 15:12:49 xxxxx volumio[29134]: info: Shairport-Sync Started May 09 15:12:50 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:12:50 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:50 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 15:12:50 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 15:12:50 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 15:12:50 xxxxx volumio[29134]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 09 15:12:50 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 09 15:12:52 xxxxx sudo[29307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 15:12:52 xxxxx sudo[29307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:52 xxxxx sudo[29307]: pam_unix(sudo:session): session closed for user root May 09 15:12:52 xxxxx sudo[29309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 15:12:52 xxxxx sudo[29309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:52 xxxxx sudo[29319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 15:12:52 xxxxx sudo[29309]: pam_unix(sudo:session): session closed for user root May 09 15:12:52 xxxxx sudo[29319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:52 xxxxx systemd[1]: Started UPnP Renderer front-end to MPD. May 09 15:12:52 xxxxx sudo[29319]: pam_unix(sudo:session): session closed for user root May 09 15:12:52 xxxxx volumio[29134]: info: Upmpdcli Daemon Started May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 09 15:12:54 xxxxx volumio[29134]: info: Adding plugin bluetooth to MyMusic Plugins May 09 15:12:54 xxxxx volumio[29134]: info: Adding plugin cd_controller to MyMusic Plugins May 09 15:12:54 xxxxx volumio[29134]: info: Adding plugin smart_inputs to MyMusic Plugins May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 09 15:12:54 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 09 15:12:55 xxxxx volumio[29134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 09 15:12:55 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 09 15:12:55 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:55 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:12:55 xxxxx volumio[29134]: info: Starting MyVolumio Remote Streaming Endpoints May 09 15:12:55 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 09 15:12:55 xxxxx volumio[29134]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 09 15:12:55 xxxxx volumio[29134]: info: Streaming services startup May 09 15:12:55 xxxxx volumio[29134]: info: Starting Streaming Daemon May 09 15:12:55 xxxxx volumio[29134]: info: MyVolumio not started May 09 15:12:55 xxxxx volumio[29134]: info: Initializing device activation check May 09 15:12:55 xxxxx sudo[29338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 15:12:55 xxxxx sudo[29338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 15:12:55 xxxxx volumio[29134]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 09 15:12:55 xxxxx systemd[1]: Stopping Volumio Streaming Daemon... May 09 15:12:55 xxxxx systemd[1]: Starting Volumio Streaming Daemon... May 09 15:12:55 xxxxx systemd[1]: Started Volumio Streaming Daemon. May 09 15:12:55 xxxxx sudo[29338]: pam_unix(sudo:session): session closed for user root May 09 15:12:55 xxxxx volumio-streaming-daemon[29344]: ############################ May 09 15:12:55 xxxxx volumio-streaming-daemon[29344]: # Volumio Streaming Daemon # May 09 15:12:55 xxxxx volumio-streaming-daemon[29344]: # Running on port 7777 # May 09 15:12:55 xxxxx volumio-streaming-daemon[29344]: ############################ May 09 15:12:55 xxxxx volumio-streaming-daemon[29344]: Environment: production May 09 15:12:56 xxxxx volumio[29134]: info: May 09 15:12:56 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:12:56 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:12:56 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:12:56 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:12:56 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:12:56 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:12:56 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:12:56 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:56 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:56 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:56 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"G.O.A.T.","artist":"Polyphia","album":"New Levels New Devils","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002zLgon1O7vuG.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=0B1886765889A885EA66639A75F2361C63F9A1FC56F80770A4AF9086582AA5A8A6BD27B436C7F0B31516027B9B8B8A6D39D1333CDD63DFF7&uin=317524287&src=F000001eINgI1nzx2v.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:12:56 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:12:56 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService stop May 09 15:12:56 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus stop May 09 15:12:56 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:56 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:56 xxxxx volumio[29134]: info: No code May 09 15:12:56 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:56 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:56 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:56 xxxxx volumio[29134]: info: ------------------------------ 31ms May 09 15:12:57 xxxxx volumio[29134]: info: Clearing queue after UPNP request May 09 15:12:57 xxxxx volumio[29134]: info: CoreStateMachine::ClearQueue May 09 15:12:57 xxxxx volumio[29134]: info: CoreStateMachine::stop May 09 15:12:57 xxxxx volumio[29134]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:12:57 xxxxx volumio[29134]: UNSET VOLATILE May 09 15:12:57 xxxxx volumio[29134]: info: CorePlayQueue::clearPlayQueue May 09 15:12:57 xxxxx volumio[29134]: info: CorePlayQueue::saveQueue May 09 15:12:57 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:57 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:57 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushQueue May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: error: updateQueue error: null May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:12:57 xxxxx volumio[29134]: info: Starting UPNP Playback May 09 15:12:57 xxxxx volumio[29134]: info: Preparing playback through UPNP May 09 15:12:57 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:12:57 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:12:57 xxxxx volumio[29134]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:12:57 xxxxx volumio[29134]: info: May 09 15:12:57 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:12:57 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:12:57 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:12:58 xxxxx volumio-streaming-daemon[29344]: Environment: production May 09 15:12:58 xxxxx volumio[29134]: info: Fetching Streaming Services browse cache May 09 15:12:58 xxxxx volumio[29134]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL May 09 15:12:58 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:58 xxxxx volumio[29134]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 09 15:12:58 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 15:12:59 xxxxx volumio[29134]: info: May 09 15:12:59 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:12:59 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:12:59 xxxxx volumio[29134]: info: May 09 15:12:59 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:12:59 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:12:59 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:12:59 xxxxx volumio[29134]: In UPNP mode May 09 15:12:59 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Flying Octopus","artist":"Rezz","album":"Flying Octopus","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002s3L951hJyVl.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=9407B15B3A8F127C29737EE5461E99B2F28B86F52D43E33ECE389A7F31656E08F7E4F7A66EE1C7333A2D3E5118F399BC2652445F8198E0B9&uin=317524287&src=F000004AYCDo309UAJ.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:12:59 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService play May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus stop May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:59 xxxxx volumio[29134]: info: ------------------------------ 17ms May 09 15:12:59 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:12:59 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:12:59 xxxxx volumio[29134]: In UPNP mode May 09 15:12:59 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":440,"duration":180,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Flying Octopus","artist":"Rezz","album":"Flying Octopus","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002s3L951hJyVl.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=9407B15B3A8F127C29737EE5461E99B2F28B86F52D43E33ECE389A7F31656E08F7E4F7A66EE1C7333A2D3E5118F399BC2652445F8198E0B9&uin=317524287&src=F000004AYCDo309UAJ.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:12:59 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService play May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus play May 09 15:12:59 xxxxx volumio[29134]: info: Received an update from plugin. extracting info from payload May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:12:59 xxxxx volumio[29134]: info: ------------------------------ 28ms May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:12:59 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:12:59 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:12:59 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:12:59 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:12:59 xxxxx volumio[29134]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:12:59 xxxxx volumio[29134]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:12:59 xxxxx volumio[29134]: Dload Upload Total Spent Left Speed May 09 15:12:59 xxxxx volumio[29134]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 09 15:12:59 xxxxx volumio[29134]: retrying in 5 seconds, trial 0 May 09 15:12:59 xxxxx volumio[29134]: Volumio Calling Home May 09 15:13:01 xxxxx volumio[29134]: info: May 09 15:13:01 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:13:01 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:13:01 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:13:01 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:13:01 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:13:01 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:13:01 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:13:01 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:13:01 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:13:01 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:01 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:01 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:13:01 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Flying Octopus","artist":"Rezz","album":"Flying Octopus","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002s3L951hJyVl.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=9407B15B3A8F127C29737EE5461E99B2F28B86F52D43E33ECE389A7F31656E08F7E4F7A66EE1C7333A2D3E5118F399BC2652445F8198E0B9&uin=317524287&src=F000004AYCDo309UAJ.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:13:01 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:13:01 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService stop May 09 15:13:01 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus play May 09 15:13:01 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:01 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:01 xxxxx volumio[29134]: info: CoreStateMachine::stPlaybackTimer May 09 15:13:01 xxxxx volumio[29134]: info: ------------------------------ 28ms May 09 15:13:01 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:13:01 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:13:01 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:13:01 xxxxx volumio[29134]: info: Clearing queue after UPNP request May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::ClearQueue May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::stop May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:13:02 xxxxx volumio[29134]: UNSET VOLATILE May 09 15:13:02 xxxxx volumio[29134]: info: CorePlayQueue::clearPlayQueue May 09 15:13:02 xxxxx volumio[29134]: info: CorePlayQueue::saveQueue May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushQueue May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: error: updateQueue error: null May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:02 xxxxx volumio[29134]: info: Starting UPNP Playback May 09 15:13:02 xxxxx volumio[29134]: info: Preparing playback through UPNP May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:13:02 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:13:02 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:02 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:02 xxxxx volumio[29134]: info: May 09 15:13:02 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:13:02 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 8ms May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:13:02 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:13:02 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:13:02 xxxxx volumio[29134]: In UPNP mode May 09 15:13:02 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":148,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"922 Kbps","isStreaming":false,"title":"相许","artist":"百万调音师","album":"相许（交响乐版）","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002PgfMU1wQgXI.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=3BDB18D3DA9B7A437B96976DBD01F45679F7616ABE7B414506B6141AEC3BC874F1AD3FF1F06C3145F84908266036821CD702B7A90FE61958&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:13:02 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService play May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus stop May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:02 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:13:02 xxxxx volumio[29134]: In UPNP mode May 09 15:13:02 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":148,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"922 Kbps","isStreaming":false,"title":"相许","artist":"百万调音师","album":"相许（交响乐版）","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002PgfMU1wQgXI.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=3BDB18D3DA9B7A437B96976DBD01F45679F7616ABE7B414506B6141AEC3BC874F1AD3FF1F06C3145F84908266036821CD702B7A90FE61958&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:13:02 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService play May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus play May 09 15:13:02 xxxxx volumio[29134]: info: Received an update from plugin. extracting info from payload May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 38ms May 09 15:13:02 xxxxx volumio[29134]: info: ------------------------------ 36ms May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:13:02 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:13:02 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:13:02 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:13:02 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:13:10 xxxxx volumio[29134]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:13:10 xxxxx volumio[29134]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:13:10 xxxxx volumio[29134]: Dload Upload Total Spent Left Speed May 09 15:13:10 xxxxx volumio[29134]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 09 15:13:10 xxxxx volumio[29134]: retrying in 5 seconds, trial 1 May 09 15:13:10 xxxxx volumio[29134]: Volumio Calling Home May 09 15:13:13 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 15:13:13 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 15:13:16 xxxxx volumio[29134]: info: May 09 15:13:16 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:13:16 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:13:16 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:13:16 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:13:16 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:13:16 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:13:16 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:16 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:13:16 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"相许","artist":"百万调音师","album":"相许（交响乐版）","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000002PgfMU1wQgXI.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=3BDB18D3DA9B7A437B96976DBD01F45679F7616ABE7B414506B6141AEC3BC874F1AD3FF1F06C3145F84908266036821CD702B7A90FE61958&uin=317524287&redirect=1&fromtag=111114","trackType":""} May 09 15:13:16 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService stop May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus play May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::stPlaybackTimer May 09 15:13:16 xxxxx volumio[29134]: info: ------------------------------ 17ms May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetQueue May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::getQueue May 09 15:13:16 xxxxx volumio[29134]: info: CorePlayQueue::getQueue May 09 15:13:16 xxxxx volumio[29134]: info: Clearing queue after UPNP request May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::ClearQueue May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::stop May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 15:13:16 xxxxx volumio[29134]: UNSET VOLATILE May 09 15:13:16 xxxxx volumio[29134]: info: CorePlayQueue::clearPlayQueue May 09 15:13:16 xxxxx volumio[29134]: info: CorePlayQueue::saveQueue May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushQueue May 09 15:13:16 xxxxx volumio[29134]: info: May 09 15:13:16 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:16 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:16 xxxxx volumio[29134]: error: updateQueue error: null May 09 15:13:16 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:16 xxxxx volumio[29134]: info: Starting UPNP Playback May 09 15:13:16 xxxxx volumio[29134]: info: Preparing playback through UPNP May 09 15:13:16 xxxxx volumio[29134]: info: CoreCommandRouter::volumioGetState May 09 15:13:16 xxxxx volumio[29134]: info: CorePlayQueue::getTrack 0 May 09 15:13:16 xxxxx volumio[29134]: info: CoreStateMachine::setConsumeUpdateService mpd May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:17 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:17 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 2ms May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:17 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:17 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:17 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces system playlist update May 09 15:13:17 xxxxx volumio[29134]: info: Ignoring MPD Status Update May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 1ms May 09 15:13:17 xxxxx volumio[29134]: info: May 09 15:13:17 xxxxx volumio[29134]: ---------------------------- MPD announces state update: player May 09 15:13:17 xxxxx volumio[29134]: info: ControllerMpd::getState May 09 15:13:17 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand status May 09 15:13:17 xxxxx volumio[29134]: verbose: ControllerMpd::parseState May 09 15:13:17 xxxxx volumio[29134]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 09 15:13:17 xxxxx volumio[29134]: verbose: ControllerMpd::parseTrackInfo May 09 15:13:17 xxxxx volumio[29134]: info: ControllerMpd::pushState May 09 15:13:17 xxxxx volumio[29134]: info: CoreCommandRouter::servicePushState May 09 15:13:17 xxxxx volumio[29134]: In UPNP mode May 09 15:13:17 xxxxx volumio[29134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":313,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Merry-Go-Round of Life (人生旋转木马)","artist":"久石让 (ひさいし じょう)","album":"Freedom Piano Stories 4","uri":"http://118.123.167.189/amobile.music.tc.qq.com/F000000BH1Ng2HFfvC.flac?guid=0000000052c67b170000017b9ba5b73a&vkey=31BDB02B34651A1AE4BDBBB7B8124045C71FED2B1B569D154C877B69BB9824DD3F595B540EBD02A7B296A8AF325CE4B1D69725843C548E2A&uin=317524287&src=F000001MbJCw1H6g0A.flac&redirect=1&fromtag=111114","trackType":""} May 09 15:13:17 xxxxx volumio[29134]: verbose: CURRENT POSITION 0 May 09 15:13:17 xxxxx volumio[29134]: info: CoreStateMachine::syncState stateService play May 09 15:13:17 xxxxx volumio[29134]: info: CoreStateMachine::syncState currentStatus stop May 09 15:13:17 xxxxx volumio[29134]: info: CoreStateMachine::pushState May 09 15:13:17 xxxxx volumio[29134]: info: CoreCommandRouter::volumioPushState May 09 15:13:17 xxxxx volumio[29134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 15:13:17 xxxxx volumio[29134]: info: ------------------------------ 15ms May 09 15:13:22 xxxxx volumio[29134]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=abe27a8023b376fd579f8eb3175888e0" http://updates.volumio.org:7070/downloader-v1/track-device May 09 15:13:22 xxxxx volumio[29134]: % Total % Received % Xferd Average Speed Time Time Time Current May 09 15:13:22 xxxxx volumio[29134]: Dload Upload Total Spent Left Speed May 09 15:13:22 xxxxx volumio[29134]: [314B blob data] May 09 15:13:22 xxxxx volumio[29134]: retrying in 5 seconds, trial 2 May 09 15:13:22 xxxxx volumio[29134]: Volumio Calling Home May 09 15:13:32 xxxxx volumio[29134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 15:13:32 xxxxx volumio[29134]: { Error: connect ETIMEDOUT 199.59.149.235:443 May 09 15:13:32 xxxxx volumio[29134]: at Object._errnoException (util.js:1022:11) May 09 15:13:32 xxxxx volumio[29134]: at _exceptionWithHostPort (util.js:1044:20) May 09 15:13:32 xxxxx volumio[29134]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 09 15:13:32 xxxxx volumio[29134]: code: 'ETIMEDOUT', May 09 15:13:32 xxxxx volumio[29134]: errno: 'ETIMEDOUT', May 09 15:13:32 xxxxx volumio[29134]: syscall: 'connect', May 09 15:13:32 xxxxx volumio[29134]: address: '199.59.149.235', May 09 15:13:32 xxxxx volumio[29134]: port: 443 } May 09 15:13:32 xxxxx volumio[29134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 15:13:32 xxxxx sudo[29511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-09 15:12 May 09 15:13:32 xxxxx sudo[29511]: 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"