-- Logs begin at Fri 2020-11-20 19:05:14 UTC, end at Sat 2025-06-21 07:36:52 UTC. -- Jun 21 07:36:15 volumio systemd[1]: Time has been changed Jun 21 07:36:16 volumio volumio[1012]: info: Loading plugin "mpd"... Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "upnp_browser"... Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "networkfs"... Jun 21 07:36:17 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "alarm-clock"... Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "airplay_emulation"... Jun 21 07:36:17 volumio volumio[1012]: info: Starting Shairport Sync Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "last_100"... Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "webradio"... Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "i2s_dacs"... Jun 21 07:36:17 volumio volumio[1012]: info: I2S DAC not set, start Auto-detection Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "volumiodiscovery"... Jun 21 07:36:17 volumio volumio[1012]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 21 07:36:17 volumio node[1012]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 21 07:36:17 volumio node[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 07:36:17 volumio node[1012]: *** WARNING *** For more information see Jun 21 07:36:17 volumio node[1012]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 21 07:36:17 volumio node[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 07:36:17 volumio node[1012]: *** WARNING *** For more information see Jun 21 07:36:17 volumio volumio[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 07:36:17 volumio volumio[1012]: *** WARNING *** For more information see Jun 21 07:36:17 volumio volumio[1012]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 21 07:36:17 volumio volumio[1012]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 21 07:36:17 volumio volumio[1012]: *** WARNING *** For more information see Jun 21 07:36:17 volumio volumio[1012]: Discovery: StartAdv! undefined Jun 21 07:36:17 volumio volumio[1012]: Discovery: Started advertising... Volumio - undefined Jun 21 07:36:17 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "outputs"... Jun 21 07:36:17 volumio volumio[1012]: info: Loading plugin "albumart"... Jun 21 07:36:18 volumio volumio[1012]: info: Plugin example_plugin is not enabled Jun 21 07:36:18 volumio volumio[1012]: info: Loading plugin "inputs"... Jun 21 07:36:18 volumio firststart.sh[1013]: Creating /var/log/samba folder Jun 21 07:36:18 volumio volumio[1012]: info: Loading plugin "updater_comm"... Jun 21 07:36:18 volumio firststart.sh[1013]: Creating /boot/userconfig.txt Jun 21 07:36:18 volumio smbd[999]: [2025/06/21 07:36:18.086839, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jun 21 07:36:18 volumio smbd[999]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 21 07:36:18 volumio volumio[1012]: info: Plugin mpdemulation is not enabled Jun 21 07:36:18 volumio volumio[1012]: info: Loading plugin "rest_api"... Jun 21 07:36:18 volumio volumio[1012]: info: Loading plugin "websocket"... Jun 21 07:36:18 volumio volumio[1012]: info: ___________ START PLUGINS ___________ Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 07:36:18 volumio volumio[1012]: info: [1750491378219] CoreMusicLibrary::Adding element Media Servers Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 07:36:18 volumio firststart.sh[1013]: Removing default SSH host keys Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_dsa_key' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_dsa_key.pub' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_ecdsa_key' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_ed25519_key' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_rsa_key' Jun 21 07:36:18 volumio firststart.sh[1013]: removed '/etc/ssh/ssh_host_rsa_key.pub' Jun 21 07:36:18 volumio firststart.sh[1013]: Generating SSH host keys Jun 21 07:36:18 volumio volumio[1012]: Forking 3 albumart workers Jun 21 07:36:18 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 07:36:18 volumio volumio[1012]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 07:36:18 volumio volumio[1012]: info: [1750491378325] CoreMusicLibrary::Adding element Last_100 Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 21 07:36:18 volumio volumio[1012]: info: [1750491378339] CoreMusicLibrary::Adding element Webradio Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 07:36:18 volumio volumio[1012]: info: ------------------------------------------- Jun 21 07:36:18 volumio volumio[1012]: info: ----- MyVolumio plugins startup ---- Jun 21 07:36:18 volumio volumio[1012]: info: ------------------------------------------- Jun 21 07:36:18 volumio volumio[1012]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 21 07:36:18 volumio volumio[1012]: info: Loading i18n strings for locale en Jun 21 07:36:18 volumio volumio[1012]: Updating browse sources language Jun 21 07:36:18 volumio volumio[1012]: Cannot find translation for sourceMedia Servers Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::initPlayerControls Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: info: BOOT COMPLETED Jun 21 07:36:18 volumio volumio[1012]: [Metrics] CommandRouter: 7s 276.48ms Jun 21 07:36:18 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 07:36:18 volumio volumio[1012]: Starting albumart workers Jun 21 07:36:18 volumio volumio[1012]: Starting albumart workers Jun 21 07:36:19 volumio volumio[1012]: Starting albumart workers Jun 21 07:36:20 volumio firststart.sh[1013]: debconf: unable to initialize frontend: Dialog Jun 21 07:36:20 volumio firststart.sh[1013]: debconf: (TERM is not set, so the dialog frontend is not usable.) Jun 21 07:36:20 volumio firststart.sh[1013]: debconf: falling back to frontend: Readline Jun 21 07:36:20 volumio firststart.sh[1013]: debconf: unable to initialize frontend: Readline Jun 21 07:36:20 volumio firststart.sh[1013]: debconf: (This frontend requires a controlling tty.) Jun 21 07:36:20 volumio firststart.sh[1013]: debconf: falling back to frontend: Teletype Jun 21 07:36:21 volumio firststart.sh[1013]: Creating SSH2 RSA key; this may take some time ... Jun 21 07:36:21 volumio firststart.sh[1013]: 2048 24:c6:6d:92:3f:90:04:43:e9:c1:f1:45:63:73:51:1c /etc/ssh/ssh_host_rsa_key.pub (RSA) Jun 21 07:36:23 volumio volumio-remote-updater[627]: [2025-06-21 07:36:23] [connect] Successful connection Jun 21 07:36:23 volumio volumio[1012]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 21 07:36:23 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent Jun 21 07:36:23 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent Jun 21 07:36:23 volumio volumio[1012]: Express server listening on port 3000 Jun 21 07:36:23 volumio volumio[1012]: [Metrics] WebUI: 14s 310.47ms Jun 21 07:36:23 volumio volumio[1012]: Volumio Calling Home Jun 21 07:36:23 volumio volumio[1012]: info: CoreStateMachine::resetVolumioState Jun 21 07:36:23 volumio volumio[1012]: info: CoreStateMachine::getcurrentVolume Jun 21 07:36:23 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::getcurrentVolume Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 07:36:24 volumio sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 07:36:24 volumio volumio[1012]: info: Cannot read play queue from file Jun 21 07:36:24 volumio sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:24 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 07:36:24 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:24 volumio sudo[1216]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:24 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:24 volumio volumio[1012]: info: Setting Device type: Raspberry PI Jun 21 07:36:24 volumio volumio[1012]: info: MPD running with PID705 Jun 21 07:36:24 volumio volumio[1012]: ,establishing connection Jun 21 07:36:24 volumio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jun 21 07:36:24 volumio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:24 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:24 volumio volumio-remote-updater[627]: [2025-06-21 07:36:24] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1750491383 101 Jun 21 07:36:24 volumio volumio[1012]: info: VolumeController:: Volume=40 Mute =false Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 07:36:24 volumio volumio[1012]: info: VolumeController:: Volume=40 Mute =false Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::updateTrackBlock Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrackBlock Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioRetrievevolume Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 07:36:24 volumio volumio[1012]: error: updateQueue error: null Jun 21 07:36:24 volumio volumio[1012]: info: VolumeController:: Volume=40 Mute =false Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:24 volumio volumio[1012]: info: VolumeController:: Volume=40 Mute =false Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: Starting Shairport Sync Jun 21 07:36:24 volumio volumio[1012]: info: Starting Shairport Sync Jun 21 07:36:24 volumio sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 07:36:24 volumio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 21 07:36:24 volumio sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:24 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:24 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 21 07:36:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 21 07:36:24 volumio sudo[1262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 21 07:36:24 volumio sudo[1251]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:24 volumio sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 21 07:36:24 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 21 07:36:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 21 07:36:24 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:24 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jun 21 07:36:24 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 21 07:36:24 volumio sudo[1262]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:24 volumio volumio[1012]: Discovery: adding 43caece4-d269-4320-be9d-9eaf2c75ef85 Jun 21 07:36:24 volumio volumio[1012]: info: mDNS: Found device Volumio Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 07:36:24 volumio volumio[1012]: info: Listing playlists Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetQueue Jun 21 07:36:24 volumio volumio[1012]: info: CoreStateMachine::getQueue Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getQueue Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 07:36:24 volumio volumio[1012]: Discovery: this is already registered, 43caece4-d269-4320-be9d-9eaf2c75ef85 Jun 21 07:36:24 volumio volumio[1012]: info: mDNS: Found device Volumio Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: Upmpdcli Daemon Started Jun 21 07:36:24 volumio volumio[1012]: info: Shairport-Sync Started Jun 21 07:36:24 volumio volumio[1012]: Error adding Membership: Error: addMembership EINVAL Jun 21 07:36:24 volumio volumio[1012]: info: Shairport-Sync Started Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 21 07:36:24 volumio volumio[1012]: info: Version has changed, forcing UI Reload Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::Reload Ui Jun 21 07:36:24 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState Jun 21 07:36:24 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:25 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 07:36:25 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:25 volumio sudo[1308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 07:36:25 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:25 volumio sudo[1308]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:25 volumio sudo[1308]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::volumioGetState Jun 21 07:36:26 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 07:36:26 volumio volumio[1012]: info: Listing playlists Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 21 07:36:26 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 21 07:36:26 volumio firststart.sh[1013]: Creating SSH2 DSA key; this may take some time ... Jun 21 07:36:26 volumio firststart.sh[1013]: 1024 d2:3b:23:16:8a:cd:4a:17:d3:1c:f2:80:c0:ee:55:98 /etc/ssh/ssh_host_dsa_key.pub (DSA) Jun 21 07:36:26 volumio firststart.sh[1013]: Creating SSH2 ECDSA key; this may take some time ... Jun 21 07:36:26 volumio firststart.sh[1013]: 256 00:d8:2e:96:25:86:4b:c4:ef:47:23:9d:07:d9:c3:1c /etc/ssh/ssh_host_ecdsa_key.pub (ECDSA) Jun 21 07:36:27 volumio firststart.sh[1013]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jun 21 07:36:27 volumio firststart.sh[1013]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Jun 21 07:36:27 volumio systemd[1]: Reloading. Jun 21 07:36:27 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jun 21 07:36:27 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jun 21 07:36:27 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jun 21 07:36:27 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 21 07:36:27 volumio volumio[1012]: info: Adding plugin bluetooth to MyMusic Plugins Jun 21 07:36:27 volumio volumio[1012]: info: Adding plugin cd_controller to MyMusic Plugins Jun 21 07:36:27 volumio volumio[1012]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 21 07:36:27 volumio volumio[1012]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 21 07:36:28 volumio firststart.sh[1013]: Disabling firststart service Jun 21 07:36:28 volumio firststart.sh[1013]: Removed symlink /etc/systemd/system/multi-user.target.wants/firststart.service. Jun 21 07:36:28 volumio systemd[1]: Reloading. Jun 21 07:36:28 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jun 21 07:36:28 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jun 21 07:36:28 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jun 21 07:36:28 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jun 21 07:36:28 volumio firststart.sh[1013]: Finalizing Jun 21 07:36:41 volumio volumio[1012]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 21 07:36:42 volumio volumio[1012]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 21 07:36:42 volumio volumio[1012]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 21 07:36:42 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 07:36:42 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 07:36:42 volumio volumio[1012]: info: Starting MyVolumio Remote Streaming Endpoints Jun 21 07:36:42 volumio volumio[1012]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 21 07:36:42 volumio volumio[1012]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 21 07:36:42 volumio volumio[1012]: info: Streaming services startup Jun 21 07:36:42 volumio volumio[1012]: info: Starting Streaming Daemon Jun 21 07:36:42 volumio volumio[1012]: info: MyVolumio not started Jun 21 07:36:42 volumio volumio[1012]: info: Initializing device activation check Jun 21 07:36:42 volumio sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 21 07:36:42 volumio sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:42 volumio volumio[1012]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 21 07:36:42 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jun 21 07:36:42 volumio systemd[1]: Starting Volumio Streaming Daemon... Jun 21 07:36:42 volumio systemd[1]: Failed to reset devices.list on /system.slice: Invalid argument Jun 21 07:36:42 volumio systemd[1]: Started Volumio Streaming Daemon. Jun 21 07:36:42 volumio sudo[1411]: pam_unix(sudo:session): session closed for user root Jun 21 07:36:42 volumio volumio-streaming-daemon[1417]: ############################ Jun 21 07:36:42 volumio volumio-streaming-daemon[1417]: # Volumio Streaming Daemon # Jun 21 07:36:42 volumio volumio-streaming-daemon[1417]: # Running on port 7777 # Jun 21 07:36:42 volumio volumio-streaming-daemon[1417]: ############################ Jun 21 07:36:42 volumio volumio[1012]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.853&uuid=a0050144a56f5cb6e88c3a77a0c3ff77" http://updates.volumio.org:7070/downloader-v1/track-device Jun 21 07:36:42 volumio volumio[1012]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 21 07:36:42 volumio volumio[1012]: Dload Upload Total Spent Left Speed Jun 21 07:36:42 volumio volumio[1012]: [235B blob data] Jun 21 07:36:42 volumio volumio[1012]: retrying in 5 seconds, trial 0 Jun 21 07:36:42 volumio volumio-streaming-daemon[1417]: Environment: production Jun 21 07:36:42 volumio volumio[1012]: Volumio Calling Home Jun 21 07:36:42 volumio volumio[1012]: info: MPD Database updated - AlbumList cache refreshed Jun 21 07:36:43 volumio volumio[1012]: info: Jun 21 07:36:43 volumio volumio[1012]: ---------------------------- MPD announces state update: database Jun 21 07:36:43 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status Jun 21 07:36:43 volumio volumio[1012]: info: Jun 21 07:36:43 volumio volumio[1012]: ---------------------------- MPD announces state update: update Jun 21 07:36:43 volumio volumio[1012]: info: ControllerMpd::getState Jun 21 07:36:43 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status Jun 21 07:36:43 volumio volumio[1012]: info: ControllerMpd::getState Jun 21 07:36:43 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status Jun 21 07:36:43 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status Jun 21 07:36:43 volumio volumio[1012]: info: Jun 21 07:36:43 volumio volumio[1012]: ---------------------------- MPD announces state update: update Jun 21 07:36:43 volumio volumio[1012]: info: ControllerMpd::getState Jun 21 07:36:43 volumio volumio[1012]: verbose: ControllerMpd::sendMpdCommand status Jun 21 07:36:43 volumio volumio[1012]: verbose: ControllerMpd::parseState Jun 21 07:36:51 volumio volumio[1012]: info: Command Router : Notfying DB Updatefalse Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent Jun 21 07:36:51 volumio volumio[1012]: verbose: ControllerMpd::parseState Jun 21 07:36:51 volumio volumio[1012]: verbose: ControllerMpd::parseState Jun 21 07:36:51 volumio volumio[1012]: verbose: ControllerMpd::parseState Jun 21 07:36:51 volumio volumio[1012]: info: Command Router : Notfying DB Updatefalse Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::Close All Modals sent Jun 21 07:36:51 volumio volumio[1012]: verbose: ControllerMpd::parseState Jun 21 07:36:51 volumio volumio[1012]: info: ControllerMpd::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 21 07:36:51 volumio volumio[1012]: verbose: CURRENT POSITION 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: No code Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: ControllerMpd::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 21 07:36:51 volumio volumio[1012]: verbose: CURRENT POSITION 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: No code Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: ControllerMpd::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::servicePushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 21 07:36:51 volumio volumio[1012]: verbose: CURRENT POSITION 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::syncState stateService stop Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::syncState currentStatus stop Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: No code Jun 21 07:36:51 volumio volumio[1012]: info: CoreStateMachine::pushState Jun 21 07:36:51 volumio volumio[1012]: info: CorePlayQueue::getTrack 0 Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::volumioPushState Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 07:36:51 volumio volumio[1012]: info: ------------------------------ 8421ms Jun 21 07:36:51 volumio volumio[1012]: info: ------------------------------ 8419ms Jun 21 07:36:51 volumio volumio[1012]: info: ------------------------------ 8388ms Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 21 07:36:51 volumio volumio[1012]: info: Loading i18n strings for locale zh Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 21 07:36:51 volumio volumio[1012]: Updating browse sources language Jun 21 07:36:51 volumio volumio[1012]: Cannot find translation for sourceMedia Servers Jun 21 07:36:51 volumio volumio[1012]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 07:36:51 volumio sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 21 07:36:51 volumio sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 07:36:51 volumio volumio[1012]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 07:36:51 volumio volumio[1012]: TypeError: Cannot read property 'name' of null Jun 21 07:36:51 volumio volumio[1012]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1462:42) Jun 21 07:36:51 volumio volumio[1012]: at emitTwo (events.js:126:13) Jun 21 07:36:51 volumio volumio[1012]: at Socket.emit (events.js:214:7) Jun 21 07:36:51 volumio volumio[1012]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jun 21 07:36:51 volumio volumio[1012]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 21 07:36:51 volumio volumio[1012]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 21 07:36:51 volumio volumio[1012]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 07:36:52 volumio sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 07:35 Jun 21 07:36:52 volumio sudo[1456]: 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="2c20ceacdd8365d3bd2fdff070d8c78700ff2903" VOLUMIO_FE_VERSION="fa55af43e5eb642e968aa6de2f8a985962941d63" VOLUMIO_BE_VERSION="c9e4849495e810bd6ebb6833232daba25d86dd4c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Nov 20 20:05:14 CET 2020" VOLUMIO_VERSION="2.853" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="f7f9725bb5ce7d6da77135f468c28964"