-- Logs begin at Sat 2024-11-30 08:56:32 UTC, end at Mon 2024-12-02 17:38:28 UTC. -- Dec 02 17:38:14 volumio systemd[1]: Time has been changed Dec 02 17:38:15 volumio nmbd[882]: [2024/12/02 17:38:15.111864, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 02 17:38:15 volumio nmbd[882]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.64 for name WORKGROUP<1d>. Dec 02 17:38:15 volumio nmbd[882]: This response was from IP 192.168.1.130, reporting an IP address of 192.168.1.130. Dec 02 17:38:15 volumio volumio[854]: info: Plugin folders cleanup Dec 02 17:38:15 volumio volumio[854]: info: Scanning into folder /volumio/app/plugins/ Dec 02 17:38:15 volumio volumio[854]: info: Scanning category audio_interface Dec 02 17:38:15 volumio volumio[854]: info: Scanning category miscellanea Dec 02 17:38:15 volumio volumio[854]: info: Scanning category music_service Dec 02 17:38:15 volumio volumio[854]: info: Scanning category plugins.json Dec 02 17:38:15 volumio volumio[854]: info: Scanning category system_controller Dec 02 17:38:15 volumio volumio[854]: info: Scanning category user_interface Dec 02 17:38:15 volumio volumio[854]: info: Scanning into folder /data/plugins/ Dec 02 17:38:15 volumio volumio[854]: info: Plugin folders cleanup completed Dec 02 17:38:16 volumio volumio[854]: info: ------------------------------------------- Dec 02 17:38:16 volumio volumio[854]: info: ----- Core plugins startup ---- Dec 02 17:38:16 volumio volumio[854]: info: ------------------------------------------- Dec 02 17:38:16 volumio volumio[854]: info: Loading plugins from folder /volumio/app/plugins/ Dec 02 17:38:16 volumio volumio[854]: info: Adding plugin upnp to MyMusic Plugins Dec 02 17:38:16 volumio volumio[854]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 02 17:38:16 volumio volumio[854]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 02 17:38:16 volumio volumio[854]: info: Loading plugins from folder /data/plugins/ Dec 02 17:38:16 volumio volumio[854]: info: Loading plugin "system"... Dec 02 17:38:16 volumio volumio[854]: info: Loading plugin "appearance"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "network"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "services"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "alsa_controller"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "wizard"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "volumio_command_line_client"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "upnp"... Dec 02 17:38:17 volumio volumio[854]: info: [1733161097862] Starting Upmpd Daemon Dec 02 17:38:17 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "my_music"... Dec 02 17:38:17 volumio volumio[854]: info: Loading plugin "mpd"... Dec 02 17:38:18 volumio sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 02 17:38:18 volumio volumio[854]: info: Loading plugin "upnp_browser"... Dec 02 17:38:18 volumio sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:38:18 volumio sudo[920]: pam_unix(sudo:session): session closed for user root Dec 02 17:38:18 volumio sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 02 17:38:18 volumio sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:38:18 volumio systemd[1]: Stopping Music Player Daemon... Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "networkfs"... Dec 02 17:38:19 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "alarm-clock"... Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "airplay_emulation"... Dec 02 17:38:19 volumio volumio[854]: info: Starting Shairport Sync Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "last_100"... Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "webradio"... Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "i2s_dacs"... Dec 02 17:38:19 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "volumiodiscovery"... Dec 02 17:38:19 volumio volumio[854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 17:38:19 volumio node[854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 02 17:38:19 volumio node[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:38:19 volumio node[854]: *** WARNING *** For more information see Dec 02 17:38:19 volumio node[854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 17:38:19 volumio node[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:38:19 volumio node[854]: *** WARNING *** For more information see Dec 02 17:38:19 volumio volumio[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:38:19 volumio volumio[854]: *** WARNING *** For more information see Dec 02 17:38:19 volumio volumio[854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 02 17:38:19 volumio volumio[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 02 17:38:19 volumio volumio[854]: *** WARNING *** For more information see Dec 02 17:38:19 volumio volumio[854]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 02 17:38:19 volumio volumio[854]: Discovery: StartAdv! undefined Dec 02 17:38:19 volumio volumio[854]: Discovery: Started advertising... Volumio - undefined Dec 02 17:38:19 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "outputs"... Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "albumart"... Dec 02 17:38:19 volumio volumio[854]: info: Plugin example_plugin is not enabled Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "inputs"... Dec 02 17:38:19 volumio volumio[854]: info: Loading plugin "updater_comm"... Dec 02 17:38:20 volumio volumio[854]: info: Plugin mpdemulation is not enabled Dec 02 17:38:20 volumio volumio[854]: info: Loading plugin "rest_api"... Dec 02 17:38:20 volumio volumio[854]: info: Loading plugin "websocket"... Dec 02 17:38:20 volumio volumio[854]: info: ___________ START PLUGINS ___________ Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:38:20 volumio volumio[854]: info: [1733161100201] CoreMusicLibrary::Adding element Media Servers Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:38:20 volumio volumio[854]: Forking 3 albumart workers Dec 02 17:38:20 volumio volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:38:20 volumio volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:38:20 volumio volumio[854]: info: [1733161100378] CoreMusicLibrary::Adding element Last_100 Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 02 17:38:20 volumio volumio[854]: info: [1733161100384] CoreMusicLibrary::Adding element Webradio Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 02 17:38:20 volumio volumio[854]: info: ------------------------------------------- Dec 02 17:38:20 volumio volumio[854]: info: ----- MyVolumio plugins startup ---- Dec 02 17:38:20 volumio volumio[854]: info: ------------------------------------------- Dec 02 17:38:20 volumio volumio[854]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 02 17:38:20 volumio volumio[854]: info: Loading i18n strings for locale de Dec 02 17:38:20 volumio volumio[854]: Updating browse sources language Dec 02 17:38:20 volumio volumio[854]: Cannot find translation for sourceMedia Servers Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::initPlayerControls Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 02 17:38:20 volumio volumio[854]: info: BOOT COMPLETED Dec 02 17:38:20 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 02 17:38:20 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Dec 02 17:38:20 volumio volumio-remote-updater[639]: [2024-12-02 17:38:20] [connect] Successful connection Dec 02 17:38:21 volumio volumio[854]: Starting albumart workers Dec 02 17:38:21 volumio volumio[854]: Starting albumart workers Dec 02 17:38:21 volumio volumio[854]: Starting albumart workers Dec 02 17:38:24 volumio udisks-glue[634]: error: Connection reset by peer Dec 02 17:38:24 volumio systemd[1]: Starting Music Player Daemon... Dec 02 17:38:25 volumio volumio[854]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Dec 02 17:38:25 volumio volumio[854]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:38:25 volumio volumio[854]: info: CoreCommandRouter::Close All Modals sent Dec 02 17:38:25 volumio volumio[854]: Express server listening on port 3000 Dec 02 17:38:25 volumio volumio[854]: Volumio Calling Home Dec 02 17:38:25 volumio volumio[854]: info: CoreStateMachine::resetVolumioState Dec 02 17:38:25 volumio volumio[854]: info: CoreStateMachine::getcurrentVolume Dec 02 17:38:25 volumio volumio[854]: info: CoreCommandRouter::volumioRetrievevolume Dec 02 17:38:25 volumio volumio[854]: info: CoreStateMachine::pushState Dec 02 17:38:25 volumio volumio[854]: info: CoreStateMachine::getState Dec 02 17:38:25 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Dec 02 17:38:25 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Dec 02 17:38:25 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:38:26 volumio volumio[854]: info: Setting Device type: Raspberry PI Dec 02 17:38:26 volumio volumio[854]: info: MPD Permissions set Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 02 17:38:26 volumio systemd[1]: Started Music Player Daemon. Dec 02 17:38:26 volumio sudo[923]: pam_unix(sudo:session): session closed for user root Dec 02 17:38:26 volumio volumio[854]: info: CoreStateMachine::setRepeat null single undefined Dec 02 17:38:26 volumio volumio[854]: info: CoreStateMachine::pushState Dec 02 17:38:26 volumio volumio[854]: info: CoreStateMachine::getState Dec 02 17:38:26 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:38:26 volumio volumio[854]: info: CoreStateMachine::setRandom null Dec 02 17:38:26 volumio volumio[854]: info: CoreStateMachine::pushState Dec 02 17:38:26 volumio volumio[854]: info: CoreStateMachine::getState Dec 02 17:38:26 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Dec 02 17:38:26 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 17:38:26 volumio volumio[854]: info: Reloading queue from file Dec 02 17:38:27 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 02 17:38:27 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 02 17:38:27 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 02 17:38:27 volumio volumio[854]: info: MPD running with PID986 ,establishing connection Dec 02 17:38:27 volumio volumio[854]: info: Starting Shairport Sync Dec 02 17:38:27 volumio volumio[854]: info: Starting Shairport Sync Dec 02 17:38:27 volumio sudo[1012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:38:27 volumio sudo[1012]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:38:27 volumio sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 02 17:38:27 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 02 17:38:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:38:27 volumio volumio[854]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:38:27 volumio sudo[1012]: pam_unix(sudo:session): session closed for user root Dec 02 17:38:27 volumio sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 17:38:27 volumio volumio[854]: { AssertionError [ERR_ASSERTION]: false == true Dec 02 17:38:27 volumio volumio[854]: at MpdClient.sendCommand (/volumio/app/plugins/music_service/mpd/lib/mpd.js:92:9) Dec 02 17:38:27 volumio volumio[854]: at /volumio/app/plugins/music_service/mpd/index.js:3118:28 Dec 02 17:38:27 volumio volumio[854]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Dec 02 17:38:27 volumio volumio[854]: at process._tickCallback (internal/process/next_tick.js:180:9) Dec 02 17:38:27 volumio volumio[854]: generatedMessage: true, Dec 02 17:38:27 volumio volumio[854]: name: 'AssertionError [ERR_ASSERTION]', Dec 02 17:38:27 volumio volumio[854]: code: 'ERR_ASSERTION', Dec 02 17:38:27 volumio volumio[854]: actual: false, Dec 02 17:38:27 volumio volumio[854]: expected: true, Dec 02 17:38:27 volumio volumio[854]: operator: '==' } Dec 02 17:38:27 volumio volumio[854]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 17:38:27 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 02 17:38:27 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 02 17:38:27 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 02 17:38:27 volumio sudo[1014]: pam_unix(sudo:session): session closed for user root Dec 02 17:38:28 volumio sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 17:37 Dec 02 17:38:28 volumio sudo[1049]: 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="80e3a7415791a01dac7cd7cc2cc0a3d3fc65ba9d" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="a5d5fb6aa4e4c71be85580a005adf6b2009366a5" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jan 16 20:23:20 CET 2020" VOLUMIO_VERSION="2.698" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="3dcbb012a2043e1c4181cb54ce618cae"