-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Thu 2025-06-19 16:34:24 CDT. -- Jun 19 16:33:00 hifipi volumio[1207]: info: Discovery: adding 7a429e4f-e727-47cd-93bc-589648ef2755 Jun 19 16:33:00 hifipi volumio[1207]: info: Discovery: Found device HiFiPi Jun 19 16:33:00 hifipi volumio[1207]: info: CoreCommandRouter::volumioGetState Jun 19 16:33:00 hifipi volumio[1207]: info: CorePlayQueue::getTrack 0 Jun 19 16:33:02 hifipi volumio[1207]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jun 19 16:33:02 hifipi volumio[1207]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jun 19 16:33:02 hifipi ntpd[700]: Soliciting pool server 137.190.2.4 Jun 19 16:33:03 hifipi ntpd[700]: Soliciting pool server 23.186.168.130 Jun 19 16:33:10 hifipi ntpd[700]: Soliciting pool server 216.66.48.42 Jun 19 16:33:13 hifipi volumio[1207]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Jun 19 16:33:13 hifipi volumio[1207]: info: Preparing to generate the ALSA configuration file Jun 19 16:33:13 hifipi volumio[1207]: info: Asound.conf file unchanged, so no further update is needed Jun 19 16:33:13 hifipi volumio[1207]: info: Output device has changed, restarting MPD Jun 19 16:33:13 hifipi volumio[1207]: info: ___________ START PLUGINS ___________ Jun 19 16:33:13 hifipi sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 16:33:13 hifipi sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 16:33:13 hifipi sudo[1321]: pam_unix(sudo:session): session closed for user root Jun 19 16:33:13 hifipi volumio[1207]: info: ControllerMpd::onStart: Initializing MPD Jun 19 16:33:13 hifipi volumio[1207]: info: Creating MPD Configuration file Jun 19 16:33:13 hifipi sudo[1323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 16:33:13 hifipi sudo[1323]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 16:33:13 hifipi volumio[1207]: info: [1750368793576] CoreMusicLibrary::Adding element Last_100 Jun 19 16:33:13 hifipi sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 16:33:13 hifipi sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 16:33:13 hifipi sudo[1327]: pam_unix(sudo:session): session closed for user root Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 19 16:33:13 hifipi volumio[1207]: info: [1750368793586] CoreMusicLibrary::Adding element Webradio Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 16:33:13 hifipi sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 19 16:33:13 hifipi sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 16:33:13 hifipi volumio[1207]: info: Volumio Calling Home Jun 19 16:33:13 hifipi systemd[1]: Listening on mpd.socket. Jun 19 16:33:13 hifipi systemd[1]: Starting Music Player Daemon... Jun 19 16:33:13 hifipi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 19 16:33:13 hifipi systemd[1]: mpd.service: Succeeded. Jun 19 16:33:13 hifipi systemd[1]: Stopped Music Player Daemon. Jun 19 16:33:13 hifipi systemd[1]: Starting Music Player Daemon... Jun 19 16:33:13 hifipi volumio[1207]: info: MPD Permissions set Jun 19 16:33:13 hifipi volumio[1207]: info: MPD Permissions set Jun 19 16:33:13 hifipi volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 16:33:13 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::volumioGetBrowseSources Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:15 hifipi mpd[1337]: Jun 19 16:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 19 16:33:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 19 16:33:15 hifipi systemd[1]: Started Music Player Daemon. Jun 19 16:33:15 hifipi sudo[1323]: pam_unix(sudo:session): session closed for user root Jun 19 16:33:15 hifipi sudo[1329]: pam_unix(sudo:session): session closed for user root Jun 19 16:33:15 hifipi volumio[1207]: info: Completed starting Core Plugins Jun 19 16:33:15 hifipi volumio[1207]: info: ------------------------------------------- Jun 19 16:33:15 hifipi volumio[1207]: info: ----- MyVolumio plugins startup ---- Jun 19 16:33:15 hifipi volumio[1207]: info: ------------------------------------------- Jun 19 16:33:15 hifipi volumio[1207]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 19 16:33:15 hifipi volumio[1207]: info: MPD running with PID1337 Jun 19 16:33:15 hifipi volumio[1207]: ,establishing connection Jun 19 16:33:16 hifipi volumio[1207]: error: MPD error: The expression evaluated to a falsy value: Jun 19 16:33:16 hifipi volumio[1207]: assert.ok(self.idling) Jun 19 16:33:16 hifipi volumio[1207]: error: The expression evaluated to a falsy value: Jun 19 16:33:16 hifipi volumio[1207]: assert.ok(self.idling) Jun 19 16:33:16 hifipi volumio[1207]: error: MPD error: The expression evaluated to a falsy value: Jun 19 16:33:16 hifipi volumio[1207]: assert.ok(self.idling) Jun 19 16:33:16 hifipi volumio[1207]: error: The expression evaluated to a falsy value: Jun 19 16:33:16 hifipi volumio[1207]: assert.ok(self.idling) Jun 19 16:33:16 hifipi volumio[1207]: error: updateQueue error: null Jun 19 16:33:19 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:21 hifipi volumio[1207]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 19 16:33:21 hifipi volumio[1207]: info: CoreCommandRouter::volumioGetState Jun 19 16:33:21 hifipi volumio[1207]: info: CorePlayQueue::getTrack 0 Jun 19 16:33:21 hifipi volumio[1207]: info: Volumio called home Jun 19 16:33:23 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:27 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:31 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:35 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:35 hifipi volumio[1207]: error: MyVolumio Plugin failed to start in a timely fashion Jun 19 16:33:35 hifipi volumio[1207]: info: BOOT COMPLETED Jun 19 16:33:35 hifipi volumio[1207]: [Metrics] CommandRouter: 55s 635.48ms Jun 19 16:33:35 hifipi volumio[1207]: info: CoreCommandRouter::volumiosetStartupVolume Jun 19 16:33:35 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 19 16:33:35 hifipi volumio[1207]: info: VolumeController:: Setting startup Volume 100 Jun 19 16:33:35 hifipi volumio[1207]: info: VolumeController::SetAlsaVolume100 Jun 19 16:33:36 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 16:33:36 hifipi volumio[1207]: info: CoreCommandRouter::Close All Modals sent Jun 19 16:33:36 hifipi volumio[1207]: info: CoreCommandRouter::Close All Modals sent Jun 19 16:33:36 hifipi volumio[1207]: info: CoreStateMachine::pushState Jun 19 16:33:36 hifipi volumio[1207]: info: CorePlayQueue::getTrack 0 Jun 19 16:33:36 hifipi volumio[1207]: info: CoreCommandRouter::volumioPushState Jun 19 16:33:37 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 19 16:33:37 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 19 16:33:37 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 19 16:33:39 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:40 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jun 19 16:33:40 hifipi sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/Music Jun 19 16:33:40 hifipi sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 16:33:40 hifipi systemd[1]: mnt-NAS-Music.mount: Succeeded. Jun 19 16:33:40 hifipi sudo[1395]: pam_unix(sudo:session): session closed for user root Jun 19 16:33:40 hifipi volumio[1207]: info: Share Music successfully unmounted Jun 19 16:33:40 hifipi sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ssaenz,password=f1twp0se,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.50/Muzak\\music\\CD Rips /mnt/NAS/Music Jun 19 16:33:40 hifipi sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 19 16:33:40 hifipi kernel: CIFS: Attempting to mount \\192.168.1.50\Muzak Jun 19 16:33:40 hifipi kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Jun 19 16:33:40 hifipi sudo[1399]: pam_unix(sudo:session): session closed for user root Jun 19 16:33:40 hifipi volumio[1207]: info: An error occurred mounting the new share. Rolling back configuration Jun 19 16:33:41 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 19 16:33:43 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 19 16:33:43 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 19 16:33:43 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 19 16:33:43 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:47 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:51 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:55 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:33:59 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:34:03 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:34:04 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 19 16:34:04 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jun 19 16:34:07 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:34:08 hifipi volumio[981]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 19 16:34:11 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:34:15 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:34:19 hifipi volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 19 16:34:23 hifipi volumio[1207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 16:34:23 hifipi volumio[1207]: Error: getaddrinfo -3007 Jun 19 16:34:23 hifipi volumio[1207]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jun 19 16:34:23 hifipi volumio[1207]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jun 19 16:34:23 hifipi volumio[1207]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jun 19 16:34:23 hifipi volumio[1207]: code: -3007, Jun 19 16:34:23 hifipi volumio[1207]: errno: -3007, Jun 19 16:34:23 hifipi volumio[1207]: syscall: 'getaddrinfo' Jun 19 16:34:23 hifipi volumio[1207]: } Jun 19 16:34:23 hifipi volumio[1207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 19 16:34:24 hifipi sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-19 16:33 Jun 19 16:34:24 hifipi sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"