-- Logs begin at Sat 2024-03-30 12:56:10 UTC, end at Sat 2024-03-30 13:03:54 UTC. -- Mar 30 13:03:31 volumio systemd[1]: Time has been changed Mar 30 13:03:31 volumio volumio[956]: info: Loading plugin "network"... Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "services"... Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "alsa_controller"... Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "wizard"... Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "volumio_command_line_client"... Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "upnp"... Mar 30 13:03:32 volumio volumio[956]: info: [1711803812718] Starting Upmpd Daemon Mar 30 13:03:32 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "my_music"... Mar 30 13:03:32 volumio volumio[956]: info: Loading plugin "mpd"... Mar 30 13:03:33 volumio volumio-remote-updater[617]: [2024-03-30 13:03:33] [connect] Successful connection Mar 30 13:03:35 volumio sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 30 13:03:35 volumio sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 13:03:35 volumio sudo[1093]: pam_unix(sudo:session): session closed for user root Mar 30 13:03:35 volumio volumio[956]: info: Loading plugin "upnp_browser"... Mar 30 13:03:35 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 30 13:03:35 volumio sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 13:03:35 volumio systemd[1]: Stopping Music Player Daemon... Mar 30 13:03:37 volumio dhcpcd[528]: timed out Mar 30 13:03:37 volumio dhcpcd[528]: exited Mar 30 13:03:37 volumio ifup[496]: dhcpcd[528]: timed out Mar 30 13:03:37 volumio ifup[496]: dhcpcd[528]: exited Mar 30 13:03:37 volumio ifup[496]: Failed to bring up eth0. Mar 30 13:03:37 volumio systemd[1]: Starting Music Player Daemon... Mar 30 13:03:38 volumio systemd[1]: Started Music Player Daemon. Mar 30 13:03:38 volumio sudo[1096]: pam_unix(sudo:session): session closed for user root Mar 30 13:03:39 volumio volumio[956]: info: Loading plugin "networkfs"... Mar 30 13:03:40 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 13:03:40 volumio volumio[956]: info: Loading plugin "alarm-clock"... Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "airplay_emulation"... Mar 30 13:03:42 volumio volumio[956]: info: Starting Shairport Sync Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "last_100"... Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "webradio"... Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "i2s_dacs"... Mar 30 13:03:42 volumio volumio[956]: info: I2S DAC not set, start Auto-detection Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "volumiodiscovery"... Mar 30 13:03:42 volumio volumio[956]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 13:03:42 volumio node[956]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 30 13:03:42 volumio node[956]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 13:03:42 volumio node[956]: *** WARNING *** For more information see Mar 30 13:03:42 volumio node[956]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 13:03:42 volumio node[956]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 13:03:42 volumio node[956]: *** WARNING *** For more information see Mar 30 13:03:42 volumio volumio[956]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 13:03:42 volumio volumio[956]: *** WARNING *** For more information see Mar 30 13:03:42 volumio volumio[956]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 30 13:03:42 volumio volumio[956]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 30 13:03:42 volumio volumio[956]: *** WARNING *** For more information see Mar 30 13:03:42 volumio volumio[956]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 30 13:03:42 volumio volumio[956]: Discovery: StartAdv! undefined Mar 30 13:03:42 volumio volumio[956]: Discovery: Started advertising... Volumio - undefined Mar 30 13:03:42 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "outputs"... Mar 30 13:03:42 volumio volumio[956]: info: Loading plugin "albumart"... Mar 30 13:03:43 volumio volumio[956]: info: Plugin example_plugin is not enabled Mar 30 13:03:43 volumio volumio[956]: info: Loading plugin "inputs"... Mar 30 13:03:43 volumio volumio[956]: info: Loading plugin "updater_comm"... Mar 30 13:03:43 volumio volumio[956]: Forking 1 albumart workers Mar 30 13:03:44 volumio volumio[956]: info: Plugin mpdemulation is not enabled Mar 30 13:03:44 volumio volumio[956]: info: Loading plugin "rest_api"... Mar 30 13:03:44 volumio volumio[956]: info: Loading plugin "websocket"... Mar 30 13:03:44 volumio volumio[956]: info: ___________ START PLUGINS ___________ Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 13:03:44 volumio volumio[956]: info: [1711803824525] CoreMusicLibrary::Adding element Media Servers Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 13:03:44 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 13:03:44 volumio volumio[956]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 13:03:44 volumio volumio[956]: info: [1711803824726] CoreMusicLibrary::Adding element Last_100 Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 30 13:03:44 volumio volumio[956]: info: [1711803824736] CoreMusicLibrary::Adding element Webradio Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 30 13:03:44 volumio volumio[956]: info: ------------------------------------------- Mar 30 13:03:44 volumio volumio[956]: info: ----- MyVolumio plugins startup ---- Mar 30 13:03:44 volumio volumio[956]: info: ------------------------------------------- Mar 30 13:03:44 volumio volumio[956]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 30 13:03:44 volumio volumio[956]: info: Loading i18n strings for locale ru Mar 30 13:03:44 volumio volumio[956]: Updating browse sources language Mar 30 13:03:44 volumio volumio[956]: Cannot find translation for sourceMedia Servers Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 13:03:44 volumio volumio[956]: info: CoreCommandRouter::initPlayerControls Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 30 13:03:45 volumio volumio[956]: info: BOOT COMPLETED Mar 30 13:03:45 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 30 13:03:45 volumio volumio[956]: Starting albumart workers Mar 30 13:03:48 volumio volumio-remote-updater[617]: [2024-03-30 13:03:48] [connect] Successful connection Mar 30 13:03:52 volumio volumio[956]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 30 13:03:52 volumio volumio[956]: underrun!!! (at least 333.810 ms long) Mar 30 13:03:52 volumio volumio[956]: underrun!!! (at least 316.466 ms long) Mar 30 13:03:52 volumio volumio[956]: underrun!!! (at least 113.924 ms long) Mar 30 13:03:52 volumio volumio[956]: info: CoreCommandRouter::Close All Modals sent Mar 30 13:03:52 volumio volumio[956]: info: CoreCommandRouter::Close All Modals sent Mar 30 13:03:52 volumio volumio[956]: Express server listening on port 3000 Mar 30 13:03:52 volumio volumio[956]: info: Setting Device type: x86 Mar 30 13:03:52 volumio volumio[956]: Volumio Calling Home Mar 30 13:03:52 volumio volumio[956]: info: CoreStateMachine::resetVolumioState Mar 30 13:03:52 volumio volumio[956]: info: CoreStateMachine::getcurrentVolume Mar 30 13:03:52 volumio volumio[956]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 13:03:52 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 30 13:03:52 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 30 13:03:52 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Mar 30 13:03:52 volumio volumio[956]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 30 13:03:52 volumio volumio[956]: info: MPD Permissions set Mar 30 13:03:52 volumio sudo[1195]: pam_unix(sudo:auth): conversation failed Mar 30 13:03:52 volumio sudo[1195]: pam_unix(sudo:auth): auth could not identify password for [volumio] Mar 30 13:03:52 volumio volumio-remote-updater[617]: [2024-03-30 13:03:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1711803828 101 Mar 30 13:03:52 volumio volumio[956]: info: VolumeController:: Volume=100 Mute =false Mar 30 13:03:52 volumio volumio[956]: info: CoreStateMachine::pushState Mar 30 13:03:52 volumio volumio[956]: info: CoreStateMachine::getState Mar 30 13:03:52 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Mar 30 13:03:52 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Mar 30 13:03:52 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 13:03:52 volumio volumio[956]: info: CoreStateMachine::updateTrackBlock Mar 30 13:03:52 volumio volumio[956]: info: CorePlayQueue::getTrackBlock Mar 30 13:03:52 volumio volumio[956]: info: CoreCommandRouter::volumioRetrievevolume Mar 30 13:03:53 volumio volumio[956]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Mar 30 13:03:53 volumio volumio[956]: We trust you have received the usual lecture from the local System Mar 30 13:03:53 volumio volumio[956]: Administrator. It usually boils down to these three things: Mar 30 13:03:53 volumio volumio[956]: #1) Respect the privacy of others. Mar 30 13:03:53 volumio volumio[956]: #2) Think before you type. Mar 30 13:03:53 volumio volumio[956]: #3) With great power comes great responsibility. Mar 30 13:03:53 volumio volumio[956]: sudo: no tty present and no askpass program specified Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 30 13:03:53 volumio volumio[956]: info: Mar 30 13:03:53 volumio volumio[956]: info: ControllerMpd::sendMpdCommand update Mar 30 13:03:53 volumio volumio[956]: info: sending command... Mar 30 13:03:53 volumio volumio[956]: info: MPD running with PID1101 ,establishing connection Mar 30 13:03:53 volumio volumio[956]: info: ControllerMpd::sendMpdCommand status Mar 30 13:03:53 volumio volumio[956]: info: Mar 30 13:03:53 volumio volumio[956]: ---------------------------- MPD announces state update: update Mar 30 13:03:53 volumio volumio[956]: info: parsing response... Mar 30 13:03:53 volumio volumio[956]: info: ControllerMpd::getState Mar 30 13:03:53 volumio volumio[956]: info: ControllerMpd::sendMpdCommand status Mar 30 13:03:53 volumio volumio[956]: info: VolumeController:: Volume=100 Mute =false Mar 30 13:03:53 volumio volumio[956]: info: CoreStateMachine::pushState Mar 30 13:03:53 volumio volumio[956]: info: CoreStateMachine::getState Mar 30 13:03:53 volumio volumio[956]: info: CorePlayQueue::getTrack 0 Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::volumioPushState Mar 30 13:03:53 volumio volumio[956]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 30 13:03:53 volumio volumio[956]: info: Reloading queue from file Mar 30 13:03:53 volumio volumio[956]: info: MPD Database updated - AlbumList cache refreshed Mar 30 13:03:53 volumio volumio[956]: info: Mar 30 13:03:53 volumio volumio[956]: ---------------------------- MPD announces state update: database Mar 30 13:03:53 volumio volumio[956]: info: ControllerMpd::sendMpdCommand status Mar 30 13:03:53 volumio volumio[956]: info: Mar 30 13:03:53 volumio volumio[956]: ---------------------------- MPD announces state update: update Mar 30 13:03:53 volumio volumio[956]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 13:03:53 volumio volumio[956]: { AssertionError [ERR_ASSERTION]: false == true Mar 30 13:03:53 volumio volumio[956]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Mar 30 13:03:53 volumio volumio[956]: at /volumio/app/plugins/music_service/mpd/index.js:3118:28 Mar 30 13:03:53 volumio volumio[956]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Mar 30 13:03:53 volumio volumio[956]: at process._tickCallback (internal/process/next_tick.js:180:9) Mar 30 13:03:53 volumio volumio[956]: generatedMessage: true, Mar 30 13:03:53 volumio volumio[956]: name: 'AssertionError [ERR_ASSERTION]', Mar 30 13:03:53 volumio volumio[956]: code: 'ERR_ASSERTION', Mar 30 13:03:53 volumio volumio[956]: actual: false, Mar 30 13:03:53 volumio volumio[956]: expected: true, Mar 30 13:03:53 volumio volumio[956]: operator: '==' } Mar 30 13:03:53 volumio volumio[956]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 30 13:03:54 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-30 13:02 Mar 30 13:03:54 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="80e3a7415791a01dac7cd7cc2cc0a3d3fc65ba9d" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="a5d5fb6aa4e4c71be85580a005adf6b2009366a5" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jan 16 20:39:24 CET 2020" VOLUMIO_VERSION="2.698" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="248eba14dbaa0e62252bbd3ba3c90535"