speeding up boot up procedure

Help and Support Help
1

hey guys,

i have a little problem, perhaps someone could help me with. i would like to reduce the time my volumio is about to start. below there is the bootup log.

here u can see, that the websocket plugin for instance needs 20 seconds to be loaded. thanks for any advices and tips!

2018-12-16T12:52:13.458Z - info: ------------------------------------------- 2018-12-16T12:52:13.464Z - info: ----- Volumio2 ---- 2018-12-16T12:52:13.465Z - info: ------------------------------------------- 2018-12-16T12:52:13.466Z - info: ----- System startup ---- 2018-12-16T12:52:13.466Z - info: ------------------------------------------- 2018-12-16T12:52:13.739Z - info: Plugin folders cleanup 2018-12-16T12:52:13.740Z - info: Scanning into folder /volumio/app/plugins/ 2018-12-16T12:52:13.741Z - info: Scanning category audio_interface 2018-12-16T12:52:13.743Z - info: Scanning category miscellanea 2018-12-16T12:52:13.745Z - info: Scanning category music_service 2018-12-16T12:52:13.748Z - info: Scanning category plugins.json 2018-12-16T12:52:13.748Z - info: Scanning category system_controller 2018-12-16T12:52:13.751Z - info: Scanning category user_interface 2018-12-16T12:52:13.752Z - info: Scanning into folder /data/plugins/ 2018-12-16T12:52:13.753Z - info: Scanning category music_service 2018-12-16T12:52:13.755Z - info: Plugin folders cleanup completed 2018-12-16T12:52:13.758Z - info: ------------------------------------------- 2018-12-16T12:52:13.758Z - info: ----- Core plugins startup ---- 2018-12-16T12:52:13.759Z - info: ------------------------------------------- 2018-12-16T12:52:13.761Z - info: Loading plugins from folder /volumio/app/plugins/ 2018-12-16T12:52:13.767Z - info: Adding plugin upnp to MyMusic Plugins 2018-12-16T12:52:13.771Z - info: Adding plugin airplay_emulation to MyMusic Plugins 2018-12-16T12:52:13.773Z - info: Adding plugin upnp_browser to MyMusic Plugins 2018-12-16T12:52:13.779Z - info: Loading plugins from folder /data/plugins/ 2018-12-16T12:52:13.785Z - info: Loading plugin "system"... 2018-12-16T12:52:13.837Z - info: Loading plugin "appearance"... 2018-12-16T12:52:15.932Z - info: Loading plugin "network"... 2018-12-16T12:52:16.747Z - info: Loading plugin "services"... 2018-12-16T12:52:16.756Z - info: Loading plugin "alsa_controller"... 2018-12-16T12:52:16.962Z - info: Loading plugin "wizard"... 2018-12-16T12:52:16.990Z - info: Loading plugin "volumio_command_line_client"... 2018-12-16T12:52:17.007Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:17.010Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:17.013Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:17.015Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:17.067Z - info: Plugin upnp is not enabled 2018-12-16T12:52:17.071Z - info: Loading plugin "my_music"... 2018-12-16T12:52:17.089Z - info: Loading plugin "mpd"... 2018-12-16T12:52:17.754Z - info: Plugin upnp_browser is not enabled 2018-12-16T12:52:17.755Z - info: Loading plugin "networkfs"... 2018-12-16T12:52:17.800Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback 2018-12-16T12:52:17.802Z - info: Loading plugin "alarm-clock"... 2018-12-16T12:52:18.217Z - info: Loading plugin "airplay_emulation"... 2018-12-16T12:52:18.263Z - info: Starting Shairport Sync 2018-12-16T12:52:18.265Z - info: Loading plugin "last_100"... 2018-12-16T12:52:18.277Z - info: Loading plugin "webradio"... 2018-12-16T12:52:19.362Z - info: Loading plugin "i2s_dacs"... 2018-12-16T12:52:19.385Z - info: I2S DAC not set, start Auto-detection 2018-12-16T12:52:19.414Z - info: Loading plugin "volumiodiscovery"... 2018-12-16T12:52:19.547Z - info: Applying required configuration parameters for plugin volumiodiscovery 2018-12-16T12:52:19.609Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback 2018-12-16T12:52:19.611Z - info: Loading plugin "albumart"... 2018-12-16T12:52:19.652Z - info: Plugin example_plugin is not enabled 2018-12-16T12:52:19.654Z - info: Loading plugin "updater_comm"... 2018-12-16T12:52:19.918Z - info: Plugin mpdemulation is not enabled 2018-12-16T12:52:19.920Z - info: Loading plugin "rest_api"... 2018-12-16T12:52:19.943Z - info: Loading plugin "websocket"... 2018-12-16T12:52:39.460Z - info: Loading plugin "podcast"... 2018-12-16T12:52:44.855Z - info: ControllerPodcast::constructor 2018-12-16T12:52:44.870Z - info: Loading plugin "spop"... 2018-12-16T12:52:46.105Z - info: Applying required configuration parameters for plugin spop 2018-12-16T12:52:46.118Z - info: Loading plugin "volspotconnect2"... 2018-12-16T12:52:47.344Z - info: ___________ START PLUGINS ___________ 2018-12-16T12:52:47.355Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2018-12-16T12:52:47.357Z - info: [1544964767356] CoreMusicLibrary::Adding element Last_100 2018-12-16T12:52:47.381Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2018-12-16T12:52:47.382Z - info: [1544964767382] CoreMusicLibrary::Adding element Webradio 2018-12-16T12:52:47.386Z - info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 2018-12-16T12:52:47.408Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2018-12-16T12:52:47.409Z - info: [1544964767408] CoreMusicLibrary::Adding element Podcast 2018-12-16T12:52:47.472Z - info: ------------------------------------------- 2018-12-16T12:52:47.473Z - info: ----- MyVolumio plugins startup ---- 2018-12-16T12:52:47.474Z - info: ------------------------------------------- 2018-12-16T12:52:47.477Z - info: Loading plugins from folder /myvolumio/plugins 2018-12-16T12:52:47.491Z - info: Loading plugins from folder /data/myvolumio/plugins 2018-12-16T12:52:47.497Z - info: Loading plugin "my_volumio"... 2018-12-16T12:52:48.506Z - info: Loading plugin "streaming_services"... 2018-12-16T12:52:49.056Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2018-12-16T12:52:49.058Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2018-12-16T12:52:49.111Z - info: Streaming services startup 2018-12-16T12:52:49.147Z - info: Loading i18n strings for locale en 2018-12-16T12:52:49.243Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 2018-12-16T12:52:49.250Z - info: CoreCommandRouter::initPlayerControls 2018-12-16T12:52:49.277Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:49.278Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards 2018-12-16T12:52:49.329Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:49.330Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:49.331Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:49.331Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:49.332Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 2018-12-16T12:52:49.355Z - info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 2018-12-16T12:52:49.362Z - info: BOOT COMPLETED 2018-12-16T12:52:49.364Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2018-12-16T12:52:57.811Z - info: CoreCommandRouter::Close All Modals sent 2018-12-16T12:52:57.813Z - info: CoreCommandRouter::Close All Modals sent 2018-12-16T12:52:57.985Z - info: MyVolumio not started 2018-12-16T12:52:57.987Z - info: CoreStateMachine::resetVolumioState 2018-12-16T12:52:57.989Z - info: CoreStateMachine::getcurrentVolume 2018-12-16T12:52:57.990Z - info: CoreCommandRouter::volumioRetrievevolume 2018-12-16T12:52:58.019Z - info: CoreStateMachine::updateTrackBlock 2018-12-16T12:52:58.021Z - info: CorePlayQueue::getTrackBlock 2018-12-16T12:52:58.496Z - info: Volumio called home 2018-12-16T12:52:58.506Z - info: Setting Device type: Raspberry PI 2018-12-16T12:52:58.518Z - info: Volspotconnect2 Daemon Started 2018-12-16T12:52:58.520Z - info: [SpConDebug] Volspotconnect2 Started 2018-12-16T12:52:58.521Z - info: [SpConDebug] Starting metadata listener 2018-12-16T12:52:58.533Z - info: SpopD Daemon Started 2018-12-16T12:52:58.564Z - info: MPD running with PID506 ,establishing connection 2018-12-16T12:52:58.806Z - info: VolumeController:: Volume=70 Mute =false 2018-12-16T12:52:58.811Z - info: CoreStateMachine::pushState 2018-12-16T12:52:58.816Z - info: CoreStateMachine::getState 2018-12-16T12:52:58.818Z - info: CorePlayQueue::getTrack 0 2018-12-16T12:52:58.820Z - info: CoreCommandRouter::volumioPushState 2018-12-16T12:52:58.821Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2018-12-16T12:52:58.836Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable 2018-12-16T12:52:58.879Z - info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect 2018-12-16T12:52:58.943Z - info: ---------------------------- Client requests Volume 70 2018-12-16T12:52:58.949Z - info: VolumeController::SetAlsaVolume70 2018-12-16T12:52:58.975Z - info: CoreStateMachine::pushState 2018-12-16T12:52:58.976Z - info: CoreStateMachine::getState 2018-12-16T12:52:58.977Z - info: CorePlayQueue::getTrack 0 2018-12-16T12:52:58.979Z - info: CoreCommandRouter::volumioPushState 2018-12-16T12:52:58.979Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2018-12-16T12:52:59.117Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 2018-12-16T12:52:59.139Z - info: Reloading queue from file 2018-12-16T12:52:59.175Z - info: 2018-12-16T12:52:59.188Z - info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) at Socket.<anonymous> (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) at emitOne (events.js:116:13) at Socket.emit (events.js:211:7) at addChunk (_stream_readable.js:263:12) at readableAddChunk (_stream_readable.js:246:13) at Socket.Readable.push (_stream_readable.js:208:10) at Pipe.onread (net.js:607:20) 2018-12-16T12:52:59.336Z - info: Setting volume on startup at 70 2018-12-16T12:52:59.349Z - info: CoreStateMachine::setRepeat null single undefined 2018-12-16T12:52:59.350Z - info: CoreStateMachine::pushState 2018-12-16T12:52:59.350Z - info: CoreStateMachine::getState 2018-12-16T12:52:59.351Z - info: CorePlayQueue::getTrack 0 2018-12-16T12:52:59.352Z - info: CoreCommandRouter::volumioPushState 2018-12-16T12:52:59.353Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2018-12-16T12:52:59.358Z - info: CoreStateMachine::setRandom true 2018-12-16T12:52:59.359Z - info: CoreStateMachine::pushState 2018-12-16T12:52:59.360Z - info: CoreStateMachine::getState 2018-12-16T12:52:59.360Z - info: CorePlayQueue::getTrack 0 2018-12-16T12:52:59.361Z - info: CoreCommandRouter::volumioPushState 2018-12-16T12:52:59.362Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 2018-12-16T12:52:59.604Z - info: mDNS: Found device kitchen 2018-12-16T12:52:59.826Z - info: Shairport-Sync Started 2018-12-16T12:52:59.839Z - info: Shairport-Sync Started 2018-12-16T12:52:59.885Z - info: CoreCommandRouter::volumioGetState 2018-12-16T12:52:59.886Z - info: CoreStateMachine::getState 2018-12-16T12:52:59.887Z - info: CorePlayQueue::getTrack 0 2018-12-16T12:53:03.539Z - info: Connecting to daemon 2018-12-16T12:53:03.590Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object] 2018-12-16T12:53:03.592Z - info: [1544964783591] CoreMusicLibrary::Adding element Spotify 2018-12-16T12:53:03.614Z - info: FIRST BRANCH 2018-12-16T12:53:03.794Z - info: Spotify credentials grant success 2018-12-16T12:53:17.437Z - info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 2018-12-16T12:53:17.440Z - info: CoreCommandRouter::executeOnPlugin: system , getConfigParam

2

hi, couple of questions:

  1. where exactly is this boot log located?
  2. were you able to speed the boot time?

my new Volumio on RPI4 takes nearly a minute or so…

3

@gchip7groundie

Logs are managed by journalctl.

I’ve been interested in speeding up booting as well, and opened up these PRs to figure out where bottlenecks are and generally test for boot speed regressions as plugins evolve.


4

is the Boot Metrics available?
i do not see it on the plugins page.

i use Volumio for squeezelite only, no other plugins and do not even need its UI.
is there a way to basically disable/remove all Volumio web stuff?
my previous Raspbian-based setup boots up in 10-20 seconds.