gistfile1.txt

volumio@volumio:~$ cat /var/log/volumio.log
2019-12-21T18:45:50.678Z - info: -------------------------------------------
2019-12-21T18:45:50.684Z - info: -----            Volumio2              ----
2019-12-21T18:45:50.685Z - info: -------------------------------------------
2019-12-21T18:45:50.685Z - info: -----          System startup          ----
2019-12-21T18:45:50.685Z - info: -------------------------------------------
2019-12-21T18:45:50.926Z - info: MYVOLUMIO Environment detected
2019-12-21T18:45:53.072Z - info: Plugin folders cleanup
2019-12-21T18:45:53.073Z - info: Scanning into folder /volumio/app/plugins/
2019-12-21T18:45:53.074Z - info: Scanning category audio_interface
2019-12-21T18:45:53.077Z - info: Scanning category miscellanea
2019-12-21T18:45:53.080Z - info: Scanning category music_service
2019-12-21T18:45:53.082Z - info: Scanning category plugins.json
2019-12-21T18:45:53.082Z - info: Scanning category system_controller
2019-12-21T18:45:53.085Z - info: Scanning category user_interface
2019-12-21T18:45:53.086Z - info: Scanning into folder /data/plugins/
2019-12-21T18:45:53.087Z - info: Scanning category system_controller
2019-12-21T18:45:53.090Z - info: Plugin folders cleanup completed
2019-12-21T18:45:53.093Z - info: -------------------------------------------
2019-12-21T18:45:53.093Z - info: -----      Core plugins startup        ----
2019-12-21T18:45:53.094Z - info: -------------------------------------------
2019-12-21T18:45:53.096Z - info: Loading plugins from folder /volumio/app/plugins/
2019-12-21T18:45:53.102Z - info: Adding plugin upnp to MyMusic Plugins
2019-12-21T18:45:53.106Z - info: Adding plugin airplay_emulation to MyMusic Plugins
2019-12-21T18:45:53.108Z - info: Adding plugin upnp_browser to MyMusic Plugins
2019-12-21T18:45:53.115Z - info: Loading plugins from folder /data/plugins/
2019-12-21T18:45:53.120Z - info: Loading plugin "system"...
2019-12-21T18:45:53.171Z - info: Loading plugin "appearance"...
2019-12-21T18:45:55.085Z - info: Loading plugin "network"...
2019-12-21T18:45:55.874Z - info: Loading plugin "services"...
2019-12-21T18:45:55.883Z - info: Loading plugin "alsa_controller"...
2019-12-21T18:45:56.124Z - info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer
2019-12-21T18:45:56.211Z - info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
2019-12-21T18:45:56.213Z - info: Found match in Cards Database: setting mixer Digital for card Allo BOSS
2019-12-21T18:45:56.292Z - info: CoreCommandRouter::volumioUpdateVolumeSettings
2019-12-21T18:45:56.294Z - info: Loading plugin "wizard"...
2019-12-21T18:45:56.316Z - info: Loading plugin "volumio_command_line_client"...
2019-12-21T18:45:56.385Z - info: Loading plugin "upnp"...
2019-12-21T18:45:56.400Z - info: [1576953956399] Starting Upmpd Daemon
2019-12-21T18:45:56.402Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2019-12-21T18:45:56.406Z - info: Loading plugin "my_music"...
2019-12-21T18:45:56.420Z - info: Loading plugin "mpd"...
2019-12-21T18:45:57.254Z - info: Loading plugin "upnp_browser"...
2019-12-21T18:45:58.228Z - info: Loading plugin "networkfs"...
2019-12-21T18:45:58.531Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2019-12-21T18:45:58.532Z - info: Loading plugin "alarm-clock"...
2019-12-21T18:45:58.906Z - info: Loading plugin "airplay_emulation"...
2019-12-21T18:45:58.933Z - info: Starting Shairport Sync
2019-12-21T18:45:58.935Z - info: Loading plugin "last_100"...
2019-12-21T18:45:58.939Z - info: Loading plugin "webradio"...
2019-12-21T18:45:59.155Z - info: Loading plugin "i2s_dacs"...
2019-12-21T18:45:59.182Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-21T18:45:59.187Z - info: Loading plugin "volumiodiscovery"...
2019-12-21T18:45:59.306Z - info: Applying required configuration parameters for plugin volumiodiscovery
2019-12-21T18:45:59.363Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2019-12-21T18:45:59.365Z - info: Loading plugin "outputs"...
2019-12-21T18:45:59.381Z - info: Loading plugin "albumart"...
2019-12-21T18:45:59.425Z - info: Plugin example_plugin is not enabled
2019-12-21T18:45:59.427Z - info: Loading plugin "inputs"...
2019-12-21T18:45:59.448Z - info: Loading plugin "updater_comm"...
2019-12-21T18:45:59.819Z - info: Plugin mpdemulation is not enabled
2019-12-21T18:45:59.871Z - info: Loading plugin "rest_api"...
2019-12-21T18:45:59.944Z - info: Loading plugin "websocket"...
2019-12-21T18:46:00.007Z - info: Loading plugin "backup_restore"...
2019-12-21T18:46:00.782Z - info: ___________ START PLUGINS ___________
2019-12-21T18:46:00.791Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
2019-12-21T18:46:00.794Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2019-12-21T18:46:00.796Z - info: [1576953960795] CoreMusicLibrary::Adding element Media Servers
2019-12-21T18:46:00.798Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-21T18:46:00.953Z - info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
2019-12-21T18:46:01.067Z - info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
2019-12-21T18:46:01.071Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2019-12-21T18:46:01.072Z - info: [1576953961071] CoreMusicLibrary::Adding element Last_100
2019-12-21T18:46:01.073Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-21T18:46:01.079Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2019-12-21T18:46:01.080Z - info: [1576953961079] CoreMusicLibrary::Adding element Webradio
2019-12-21T18:46:01.081Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-21T18:46:01.090Z - info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
2019-12-21T18:46:01.114Z - info: -------------------------------------------
2019-12-21T18:46:01.115Z - info: -----    MyVolumio plugins startup     ----
2019-12-21T18:46:01.116Z - info: -------------------------------------------
2019-12-21T18:46:01.117Z - info: [MyVolumio PluginManager] Fetching plans data....
2019-12-21T18:46:01.146Z - info: Loading i18n strings for locale en
2019-12-21T18:46:01.159Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-21T18:46:01.204Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
2019-12-21T18:46:01.209Z - info: CoreCommandRouter::initPlayerControls
2019-12-21T18:46:01.233Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2019-12-21T18:46:01.235Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
2019-12-21T18:46:01.321Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2019-12-21T18:46:01.323Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2019-12-21T18:46:01.323Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2019-12-21T18:46:01.324Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2019-12-21T18:46:01.326Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2019-12-21T18:46:01.349Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
2019-12-21T18:46:01.356Z - info: BOOT COMPLETED
2019-12-21T18:46:01.358Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-21T18:46:06.758Z - info: CoreCommandRouter::Close All Modals sent
2019-12-21T18:46:06.760Z - info: CoreCommandRouter::Close All Modals sent
2019-12-21T18:46:06.891Z - info: CoreStateMachine::resetVolumioState
2019-12-21T18:46:06.893Z - info: CoreStateMachine::getcurrentVolume
2019-12-21T18:46:06.894Z - info: CoreCommandRouter::volumioRetrievevolume
2019-12-21T18:46:07.090Z - info: CoreCommandRouter::volumioRetrieveVolumeLevels
2019-12-21T18:46:07.091Z - info: CoreStateMachine::getcurrentVolume
2019-12-21T18:46:07.092Z - info: CoreCommandRouter::volumioRetrievevolume
2019-12-21T18:46:07.390Z - info: Setting Device type: Raspberry PI
2019-12-21T18:46:07.404Z - info: MPD running with PID610 ,establishing connection
2019-12-21T18:46:07.588Z - info: VolumeController:: Volume=32 Mute =false
2019-12-21T18:46:07.592Z - info: CoreStateMachine::pushState
2019-12-21T18:46:07.596Z - info: CoreStateMachine::getState
2019-12-21T18:46:07.597Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:07.600Z - info: CoreCommandRouter::volumioPushState
2019-12-21T18:46:07.600Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2019-12-21T18:46:07.612Z - info: CoreStateMachine::updateTrackBlock
2019-12-21T18:46:07.614Z - info: CorePlayQueue::getTrackBlock
2019-12-21T18:46:07.615Z - info: CoreCommandRouter::volumioRetrievevolume
2019-12-21T18:46:07.750Z - info: VolumeController:: Volume=32 Mute =false
2019-12-21T18:46:07.751Z - info: CoreStateMachine::pushState
2019-12-21T18:46:07.752Z - info: CoreStateMachine::getState
2019-12-21T18:46:07.753Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:07.753Z - info: CoreCommandRouter::volumioPushState
2019-12-21T18:46:07.754Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2019-12-21T18:46:07.759Z - info: CoreStateMachine::updateTrackBlock
2019-12-21T18:46:07.760Z - info: CorePlayQueue::getTrackBlock
2019-12-21T18:46:07.761Z - info: CoreCommandRouter::volumioRetrievevolume
2019-12-21T18:46:07.787Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
2019-12-21T18:46:07.867Z - info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
2019-12-21T18:46:07.892Z - info: Reloading queue from file
2019-12-21T18:46:07.900Z - info:
2019-12-21T18:46:08.088Z - info: CoreStateMachine::setRepeat null single undefined
2019-12-21T18:46:08.089Z - info: CoreStateMachine::pushState
2019-12-21T18:46:08.090Z - info: CoreStateMachine::getState
2019-12-21T18:46:08.090Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:08.092Z - info: CoreCommandRouter::volumioPushState
2019-12-21T18:46:08.093Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2019-12-21T18:46:08.101Z - info: CoreStateMachine::setRandom null
2019-12-21T18:46:08.102Z - info: CoreStateMachine::pushState
2019-12-21T18:46:08.103Z - info: CoreStateMachine::getState
2019-12-21T18:46:08.103Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:08.105Z - info: CoreCommandRouter::volumioPushState
2019-12-21T18:46:08.105Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2019-12-21T18:46:08.172Z - info: VolumeController:: Volume=32 Mute =false
2019-12-21T18:46:08.173Z - info: CoreStateMachine::pushState
2019-12-21T18:46:08.174Z - info: CoreStateMachine::getState
2019-12-21T18:46:08.174Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:08.175Z - info: CoreCommandRouter::volumioPushState
2019-12-21T18:46:08.175Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2019-12-21T18:46:08.182Z - info: VolumeController:: Volume=32 Mute =false
2019-12-21T18:46:08.183Z - info: CoreStateMachine::pushState
2019-12-21T18:46:08.184Z - info: CoreStateMachine::getState
2019-12-21T18:46:08.185Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:08.185Z - info: CoreCommandRouter::volumioPushState
2019-12-21T18:46:08.186Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2019-12-21T18:46:08.197Z - info: Starting Shairport Sync
2019-12-21T18:46:08.221Z - info: Starting Shairport Sync
2019-12-21T18:46:08.727Z - info: CoreCommandRouter::volumioGetState
2019-12-21T18:46:08.729Z - info: CoreStateMachine::getState
2019-12-21T18:46:08.730Z - info: CorePlayQueue::getTrack 0
2019-12-21T18:46:08.746Z - info: Shairport-Sync Started
2019-12-21T18:46:08.794Z - info: Shairport-Sync Started
2019-12-21T18:46:08.796Z - info: Upmpdcli Daemon Started
2019-12-21T18:46:17.383Z - info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.692&uuid=b597100256db6fd8ec85a5bb93ebcb45" http://updates.volumio.org:7070/downloader-v1/track-device
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0curl: (6) Could not resolve host: updates.volumio.org
 retrying in 5 seconds, trial 0
2019-12-21T18:46:24.765Z - info: mDNS: Found device Volumio
2019-12-21T18:46:25.947Z - info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
2019-12-21T18:46:25.948Z - info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
2019-12-21T18:46:25.949Z - info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
2019-12-21T18:46:25.950Z - info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
2019-12-21T18:46:25.951Z - info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
2019-12-21T18:46:25.951Z - info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
2019-12-21T18:46:25.961Z - info: Adding plugin bluetooth to MyMusic Plugins
2019-12-21T18:46:25.971Z - info: Adding plugin cd_controller to MyMusic Plugins
2019-12-21T18:46:25.978Z - info: Adding plugin smart_inputs to MyMusic Plugins
2019-12-21T18:46:25.986Z - info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
2019-12-21T18:46:30.536Z - info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
2019-12-21T18:46:33.545Z - info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
2019-12-21T18:46:33.548Z - info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
2019-12-21T18:46:33.550Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-21T18:46:33.551Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-21T18:46:33.555Z - info: Starting MyVolumio Remote Streaming Endpoints
2019-12-21T18:46:33.606Z - info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
2019-12-21T18:46:33.608Z - info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
2019-12-21T18:46:33.611Z - info: Streaming services startup
2019-12-21T18:46:33.622Z - info: Starting Streaming Daemon
2019-12-21T18:46:33.668Z - info: [MyVolumio PluginManager] MyVolumio plugin successfully started
2019-12-31T15:12:08.129Z - info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.692&uuid=b597100256db6fd8ec85a5bb93ebcb45" http://updates.volumio.org:7070/downloader-v1/track-device
  % Total    % Received % Xferd  Average Speed   Time    Time     Time  Current
                                 Dload  Upload   Total   Spent    Left  Speed
  0     0    0     0    0     0      0      0 --:--:-- --:--:-- --:--:--     0curl: (6) Could not resolve host: updates.volumio.org
 retrying in 5 seconds, trial 1
2019-12-31T15:12:08.208Z - info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
2019-12-31T15:12:08.211Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:08.364Z - info: Volumio called home
2019-12-31T15:12:08.596Z - error: MyVolumio Custom Token format not valid, refreshing it
2019-12-31T15:12:09.343Z - info: mDNS: A device disapperared from network
2019-12-31T15:12:09.345Z - info: mDNS: Device volumio disapperared from network
2019-12-31T15:12:09.660Z - info: mDNS: Found device Volumio
2019-12-31T15:12:10.507Z - info: MYVOLUMIO SUCCESSFULLY LOGGED IN
2019-12-31T15:12:10.509Z - info: MYVOLUMIO: Adding device
2019-12-31T15:12:10.510Z - info: MYVOLUMIO: Evaluating Server
2019-12-31T15:12:10.926Z - info: Fetching Streaming Services browse cache
2019-12-31T15:12:11.123Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ
2019-12-31T15:12:11.127Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-31T15:12:11.154Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL
2019-12-31T15:12:11.155Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-31T15:12:12.213Z - info: Setting Geolocation for MyVolumio to eu2
2019-12-31T15:12:12.216Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:12.218Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:12.219Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:12.526Z - info: MyVolumio status changed
2019-12-31T15:12:12.528Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ
2019-12-31T15:12:12.529Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-31T15:12:12.535Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL
2019-12-31T15:12:12.535Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-31T15:12:12.539Z - info: Streaming services startup
2019-12-31T15:12:12.543Z - info: Re-Starting Streaming Daemon
2019-12-31T15:12:12.551Z - info: Removing browser output: myVolumio user plan is not superstar
2019-12-31T15:12:12.554Z - info: Removing audio output:
2019-12-31T15:12:12.556Z - info: Stoppping Tunnel 1
2019-12-31T15:12:12.698Z - info: Remote SSH Stopped
2019-12-31T15:12:13.165Z - info: Fetching Streaming Services browse cache
2019-12-31T15:12:13.183Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL
2019-12-31T15:12:13.184Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-31T15:12:13.193Z - info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ
2019-12-31T15:12:13.194Z - info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
2019-12-31T15:12:13.369Z - info: Updating MyVolumio device info
2019-12-31T15:12:13.370Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:13.371Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:13.372Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
2019-12-31T15:12:29.676Z - info: CoreCommandRouter::volumioGetState
2019-12-31T15:12:29.677Z - info: CoreStateMachine::getState
2019-12-31T15:12:29.678Z - info: CorePlayQueue::getTrack 0
2019-12-31T15:12:29.741Z - info: CoreCommandRouter::volumioGetQueue
2019-12-31T15:12:29.742Z - info: CoreStateMachine::getQueue
2019-12-31T15:12:29.744Z - info: CorePlayQueue::getQueue
2019-12-31T15:12:29.746Z - info: CoreCommandRouter::executeOnPlugin: system , getHwuuid