-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2024-11-25 10:38:41 CET. -- Nov 25 10:37:00 volumiotop volumio[989]: info: Executing endpoint metavolumio Nov 25 10:37:00 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 25 10:37:00 volumiotop volumio[989]: info: Executing endpoint metavolumio Nov 25 10:37:00 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 25 10:37:07 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:07 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:08 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Nov 25 10:37:09 volumiotop volumio[989]: info: handleBrowseUri took 430 milliseconds Nov 25 10:37:09 volumiotop volumio[989]: info: Preload queue cleared Nov 25 10:37:09 volumiotop volumio[989]: info: Preload queue cleared Nov 25 10:37:12 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 10:37:12 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 10:37:12 volumiotop volumio[989]: info: Discovery: Getting this device information Nov 25 10:37:12 volumiotop volumio[989]: info: CoreCommandRouter::volumioGetState Nov 25 10:37:12 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 10:37:15 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:15 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:18 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Nov 25 10:37:20 volumiotop volumio[989]: info: handleBrowseUri took 2253 milliseconds Nov 25 10:37:20 volumiotop volumio[989]: info: Preload queue cleared Nov 25 10:37:20 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Nov 25 10:37:22 volumiotop volumio[989]: info: handleBrowseUri took 1981 milliseconds Nov 25 10:37:22 volumiotop volumio[989]: info: Preload queue cleared Nov 25 10:37:23 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:23 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:31 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:31 volumiotop volumio[989]: info: Discovery: Remote connection error: 192.168.1.15 Nov 25 10:37:38 volumiotop volumio[989]: Searching all installed plugins Nov 25 10:37:38 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:38 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 10:37:38 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 10:37:38 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 10:37:38 volumiotop volumio[989]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 10:37:38 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 10:37:38 volumiotop volumio[989]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 10:37:38 volumiotop volumio[989]: info: [1732527458330] ControllerCalmRadio::searchCategories Nov 25 10:37:38 volumiotop volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:37:38 volumiotop volumio[989]: TypeError: Cannot read property 'cats' of undefined Nov 25 10:37:38 volumiotop volumio[989]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 10:37:38 volumiotop volumio[989]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 10:37:38 volumiotop volumio[989]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 10:37:38 volumiotop volumio[989]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 10:37:38 volumiotop volumio[989]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 10:37:38 volumiotop volumio[989]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 10:37:38 volumiotop volumio[989]: at Socket.emit (events.js:315:20) Nov 25 10:37:38 volumiotop volumio[989]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 10:37:38 volumiotop volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 10:37:38 volumiotop volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:37:39 volumiotop sudo[6646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 10:36 Nov 25 10:37:39 volumiotop sudo[6646]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:39 volumiotop sudo[6646]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:39 volumiotop volumio-remote-updater[675]: [2024-11-25 10:37:39] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 10:37:39 volumiotop volumio-remote-updater[675]: [2024-11-25 10:37:39] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 10:37:39 volumiotop vtcs[1465]: [2024-11-25 10:37:39.413] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 25 10:37:39 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 10:37:39 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 10:37:39 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:37:39 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:37:39 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 10:37:39 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 25 10:37:39 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:37:39 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 10:37:39 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 10:37:39 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:37:41 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:41 volumiotop volumio[6662]: info: ----- Volumio3 ---- Nov 25 10:37:41 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:41 volumiotop volumio[6662]: info: ----- System startup ---- Nov 25 10:37:41 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:42 volumiotop volumio[6662]: info: MYVOLUMIO Environment detected Nov 25 10:37:42 volumiotop volumio[6662]: info: Plugin folders cleanup Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning into folder /volumio/app/plugins/ Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category audio_interface Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category miscellanea Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category music_service Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category plugins.json Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category system_controller Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category user_interface Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning into folder /data/plugins/ Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category music_service Nov 25 10:37:42 volumiotop volumio[6662]: info: Scanning category system_hardware Nov 25 10:37:42 volumiotop volumio[6662]: info: Plugin folders cleanup completed Nov 25 10:37:42 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:42 volumiotop volumio[6662]: info: ----- Core plugins startup ---- Nov 25 10:37:42 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:42 volumiotop volumio[6662]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 10:37:42 volumiotop volumio[6662]: info: Adding plugin upnp to MyMusic Plugins Nov 25 10:37:42 volumiotop volumio[6662]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 10:37:42 volumiotop volumio[6662]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 10:37:42 volumiotop volumio[6662]: info: Loading plugins from folder /data/plugins/ Nov 25 10:37:42 volumiotop volumio[6662]: info: Loading plugin "system"... Nov 25 10:37:42 volumiotop volumio[6662]: info: Loading plugin "appearance"... Nov 25 10:37:43 volumiotop volumio[6662]: info: Loading plugin "network"... Nov 25 10:37:43 volumiotop volumio[6662]: info: Refreshing Cached IP Addresses Nov 25 10:37:43 volumiotop sudo[6689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 10:37:43 volumiotop sudo[6689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:43 volumiotop sudo[6691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 10:37:43 volumiotop volumio[6662]: info: Loading plugin "services"... Nov 25 10:37:43 volumiotop sudo[6691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:43 volumiotop volumio[6662]: info: Loading plugin "alsa_controller"... Nov 25 10:37:43 volumiotop sudo[6691]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:43 volumiotop sudo[6689]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:43 volumiotop sudo[6694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 10:37:43 volumiotop sudo[6694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:43 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:37:43 volumiotop volumio[6662]: info: Loading plugin "wizard"... Nov 25 10:37:43 volumiotop volumio[6662]: info: Loading plugin "networkfs"... Nov 25 10:37:44 volumiotop volumio[6662]: info: Starting Udev Watcher for removable devices Nov 25 10:37:44 volumiotop volumio[6662]: info: Ignoring mount for partition: boot Nov 25 10:37:44 volumiotop volumio[6662]: info: Ignoring mount for partition: volumio Nov 25 10:37:44 volumiotop volumio[6662]: info: Ignoring mount for partition: volumio_data Nov 25 10:37:44 volumiotop volumio[6662]: info: Mounting Device 8B0D-6686 Nov 25 10:37:44 volumiotop sudo[6705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/8B0D-6686 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:37:44 volumiotop sudo[6705]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:44 volumiotop sudo[6705]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:44 volumiotop volumio[6662]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:37:44 volumiotop volumio[6662]: error: Failed to mount 8B0D-6686: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/8B0D-6686" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:37:44 volumiotop volumio[6662]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:37:44 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "volumio_command_line_client"... Nov 25 10:37:44 volumiotop volumio[6662]: info: Plugin upnp is not enabled Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "my_music"... Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "mpd"... Nov 25 10:37:44 volumiotop volumio-remote-updater[675]: [2024-11-25 10:37:44] [connect] Successful connection Nov 25 10:37:44 volumiotop volumio[6662]: info: Plugin upnp_browser is not enabled Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "alarm-clock"... Nov 25 10:37:44 volumiotop sudo[6694]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "airplay_emulation"... Nov 25 10:37:44 volumiotop volumio[6662]: info: Starting Shairport Sync Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "last_100"... Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "webradio"... Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "i2s_dacs"... Nov 25 10:37:44 volumiotop volumio[6662]: info: I2S DAC not set, start Auto-detection Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "volumiodiscovery"... Nov 25 10:37:44 volumiotop volumio[6662]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:37:44 volumiotop volumio[6662]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:37:44 volumiotop volumio[6662]: *** WARNING *** For more information see Nov 25 10:37:44 volumiotop node[6662]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:37:44 volumiotop volumio[6662]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:37:44 volumiotop volumio[6662]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:37:44 volumiotop volumio[6662]: *** WARNING *** For more information see Nov 25 10:37:44 volumiotop node[6662]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:37:44 volumiotop node[6662]: *** WARNING *** For more information see Nov 25 10:37:44 volumiotop node[6662]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:37:44 volumiotop node[6662]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:37:44 volumiotop node[6662]: *** WARNING *** For more information see Nov 25 10:37:44 volumiotop volumio[6662]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 10:37:44 volumiotop volumio[6662]: info: Discovery: Started advertising with name: Volumiotop Nov 25 10:37:44 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:37:44 volumiotop volumio[6662]: info: Loading plugin "calmradio"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "outputs"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "albumart"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Plugin example_plugin is not enabled Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "inputs"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "updater_comm"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Plugin mpdemulation is not enabled Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "rest_api"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "websocket"... Nov 25 10:37:46 volumiotop volumio[6662]: info: Starting Socket.io Server version 2.3.0 Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading plugin "motherearthradio"... Nov 25 10:37:46 volumiotop volumio[6662]: Forking 3 albumart workers Nov 25 10:37:46 volumiotop volumio[6662]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 10:37:46 volumiotop volumio[6662]: info: [1732527466591] [MotherEarth] API delay: 5 Nov 25 10:37:46 volumiotop volumio[6662]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 10:37:46 volumiotop volumio[6662]: info: Loading i18n strings for locale fr Nov 25 10:37:46 volumiotop volumio[6662]: Updating browse sources language Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::initPlayerControls Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:37:46 volumiotop volumio[6662]: Express server listening on port 3000 Nov 25 10:37:46 volumiotop volumio[6662]: [Metrics] WebUI: 5s 718.34ms Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreStateMachine::resetVolumioState Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreStateMachine::getcurrentVolume Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:37:46 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:47 volumiotop volumio[6662]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 25 10:37:47 volumiotop volumio-remote-updater[675]: [2024-11-25 10:37:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732527464 101 Nov 25 10:37:47 volumiotop volumio[6662]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 25 10:37:47 volumiotop volumio[6662]: info: Reloading queue from file Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreStateMachine::setRepeat null single undefined Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreStateMachine::pushState Nov 25 10:37:47 volumiotop volumio[6662]: info: CorePlayQueue::getTrack 0 Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::volumioPushState Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreStateMachine::setRandom true Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreStateMachine::pushState Nov 25 10:37:47 volumiotop volumio[6662]: info: CorePlayQueue::getTrack 0 Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::volumioPushState Nov 25 10:37:47 volumiotop volumio[6662]: info: Setting Device type: Raspberry PI Nov 25 10:37:47 volumiotop volumio[6662]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreStateMachine::pushState Nov 25 10:37:47 volumiotop volumio[6662]: info: CorePlayQueue::getTrack 0 Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::volumioPushState Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreStateMachine::updateTrackBlock Nov 25 10:37:47 volumiotop volumio[6662]: info: CorePlayQueue::getTrackBlock Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:37:47 volumiotop volumio[6662]: info: Completed loading Core Plugins Nov 25 10:37:47 volumiotop volumio[6662]: info: Preparing to generate the ALSA configuration file Nov 25 10:37:47 volumiotop volumio[6662]: Starting albumart workers Nov 25 10:37:47 volumiotop volumio[6662]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 10:37:47 volumiotop volumio[6662]: info: Discovery: Found device Volumiotop Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::volumioGetState Nov 25 10:37:47 volumiotop volumio[6662]: info: CorePlayQueue::getTrack 0 Nov 25 10:37:47 volumiotop volumio[6662]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 10:37:47 volumiotop volumio[6662]: info: Reading ALSA contributions from plugins. Nov 25 10:37:47 volumiotop volumio[6662]: info: Asound.conf file unchanged, so no further update is needed Nov 25 10:37:47 volumiotop volumio[6662]: info: Output device has changed, restarting MPD Nov 25 10:37:47 volumiotop volumio[6662]: Starting albumart workers Nov 25 10:37:47 volumiotop volumio[6662]: Starting albumart workers Nov 25 10:37:47 volumiotop volumio[6662]: info: Output device has changed, restarting Shairport Sync Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:47 volumiotop sudo[6751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:37:47 volumiotop sudo[6751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:47 volumiotop sudo[6751]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:47 volumiotop sudo[6753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:37:47 volumiotop sudo[6753]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:47 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 10:37:47 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:37:47 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:37:47 volumiotop volumio[6662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:37:47 volumiotop volumio[6662]: info: ___________ START PLUGINS ___________ Nov 25 10:37:47 volumiotop volumio[6662]: info: ControllerMpd::onStart: Initializing MPD Nov 25 10:37:47 volumiotop volumio[6662]: info: Creating MPD Configuration file Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:47 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:47 volumiotop sudo[6760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:37:47 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:37:47 volumiotop sudo[6760]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:47 volumiotop sudo[6760]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:47 volumiotop sudo[6762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:37:47 volumiotop sudo[6762]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:48 volumiotop volumio[6662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:48 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 10:37:48 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:37:48 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:37:48 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:37:48 volumiotop volumio[6662]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:37:48 volumiotop volumio[6662]: info: [1732527468121] CoreMusicLibrary::Adding element Last_100 Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:37:48 volumiotop volumio[6662]: info: [1732527468128] CoreMusicLibrary::Adding element Webradio Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:37:48 volumiotop volumio[6662]: info: Initializing BBC Radios Nov 25 10:37:48 volumiotop sudo[6768]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 10:37:48 volumiotop sudo[6768]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:48 volumiotop sudo[6768]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: Adding Calm Radio to Browse Sources Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:37:48 volumiotop volumio[6662]: info: [1732527468284] CoreMusicLibrary::Adding element Calm Radio Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:48 volumiotop volumio[6662]: Cannot find translation for source Calm Radio Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:37:48 volumiotop volumio[6662]: info: [1732527468311] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:48 volumiotop volumio[6662]: Cannot find translation for source Calm Radio Nov 25 10:37:48 volumiotop volumio[6662]: Cannot find translation for source Mother Earth Radio Nov 25 10:37:48 volumiotop volumio[6662]: info: Volumio Calling Home Nov 25 10:37:48 volumiotop volumio[6662]: info: MPD Permissions set Nov 25 10:37:48 volumiotop volumio[6662]: info: MPD Permissions set Nov 25 10:37:48 volumiotop volumio[6662]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreStateMachine::pushState Nov 25 10:37:48 volumiotop volumio[6662]: info: CorePlayQueue::getTrack 0 Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::volumioPushState Nov 25 10:37:48 volumiotop volumio[6662]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:37:48 volumiotop volumio[6662]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 25 10:37:48 volumiotop volumio[6662]: info: Starting Shairport Sync Nov 25 10:37:48 volumiotop volumio[6662]: info: Starting Shairport Sync Nov 25 10:37:49 volumiotop sudo[6821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:37:49 volumiotop sudo[6821]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:49 volumiotop volumio[6662]: info: Starting Shairport Sync Nov 25 10:37:49 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:37:49 volumiotop sudo[6823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:37:49 volumiotop sudo[6823]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:49 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:37:49 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:37:49 volumiotop volumio[6662]: info: CoreCommandRouter::volumioGetState Nov 25 10:37:49 volumiotop volumio[6662]: info: CorePlayQueue::getTrack 0 Nov 25 10:37:49 volumiotop sudo[6826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:37:49 volumiotop sudo[6826]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:49 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:37:49 volumiotop sudo[6821]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:49 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:37:49 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:37:49 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:37:49 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:37:49 volumiotop sudo[6826]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:49 volumiotop sudo[6823]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:49 volumiotop volumio[6662]: info: Shairport-Sync Started Nov 25 10:37:49 volumiotop volumio[6662]: Error adding Membership: Error: addMembership EINVAL Nov 25 10:37:49 volumiotop volumio[6662]: info: Shairport-Sync Started Nov 25 10:37:49 volumiotop volumio[6662]: info: Shairport-Sync Started Nov 25 10:37:50 volumiotop mpd[6784]: Nov 25 10:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 10:37:50 volumiotop systemd[1]: Started Music Player Daemon. Nov 25 10:37:50 volumiotop sudo[6762]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:50 volumiotop sudo[6753]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:50 volumiotop volumio[6662]: info: Completed starting Core Plugins Nov 25 10:37:50 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:50 volumiotop volumio[6662]: info: ----- MyVolumio plugins startup ---- Nov 25 10:37:50 volumiotop volumio[6662]: info: ------------------------------------------- Nov 25 10:37:50 volumiotop volumio[6662]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 25 10:37:50 volumiotop volumio[6662]: error: MPD error: The expression evaluated to a falsy value: Nov 25 10:37:50 volumiotop volumio[6662]: assert.ok(self.idling) Nov 25 10:37:50 volumiotop volumio[6662]: error: The expression evaluated to a falsy value: Nov 25 10:37:50 volumiotop volumio[6662]: assert.ok(self.idling) Nov 25 10:37:50 volumiotop volumio[6662]: info: MPD running with PID6784 Nov 25 10:37:50 volumiotop volumio[6662]: ,establishing connection Nov 25 10:37:50 volumiotop volumio[6662]: error: MPD error: The expression evaluated to a falsy value: Nov 25 10:37:50 volumiotop volumio[6662]: assert.ok(self.idling) Nov 25 10:37:50 volumiotop volumio[6662]: error: The expression evaluated to a falsy value: Nov 25 10:37:50 volumiotop volumio[6662]: assert.ok(self.idling) Nov 25 10:37:50 volumiotop volumio[6662]: error: updateQueue error: null Nov 25 10:37:53 volumiotop volumio[6662]: info: Volumio called home Nov 25 10:37:54 volumiotop volumio[6662]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 25 10:37:54 volumiotop volumio[6662]: Searching all installed plugins Nov 25 10:37:54 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:37:54 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 10:37:54 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 10:37:54 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 10:37:54 volumiotop volumio[6662]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 10:37:54 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 10:37:54 volumiotop volumio[6662]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 10:37:54 volumiotop volumio[6662]: info: [1732527474279] ControllerCalmRadio::searchCategories Nov 25 10:37:54 volumiotop volumio[6662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:37:54 volumiotop volumio[6662]: TypeError: Cannot read property 'cats' of undefined Nov 25 10:37:54 volumiotop volumio[6662]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 10:37:54 volumiotop volumio[6662]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 10:37:54 volumiotop volumio[6662]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 10:37:54 volumiotop volumio[6662]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 10:37:54 volumiotop volumio[6662]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 10:37:54 volumiotop volumio[6662]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 10:37:54 volumiotop volumio[6662]: at Socket.emit (events.js:315:20) Nov 25 10:37:54 volumiotop volumio[6662]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 10:37:54 volumiotop volumio[6662]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 10:37:54 volumiotop volumio[6662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:37:54 volumiotop sudo[6850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 10:36 Nov 25 10:37:54 volumiotop sudo[6850]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:54 volumiotop sudo[6850]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:55 volumiotop volumio-remote-updater[675]: [2024-11-25 10:37:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 10:37:55 volumiotop volumio-remote-updater[675]: [2024-11-25 10:37:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 10:37:55 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 10:37:55 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 10:37:55 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:37:55 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:37:55 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 10:37:55 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 25 10:37:55 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:37:55 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 10:37:55 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 10:37:55 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:37:57 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:37:57 volumiotop volumio[6862]: info: ----- Volumio3 ---- Nov 25 10:37:57 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:37:57 volumiotop volumio[6862]: info: ----- System startup ---- Nov 25 10:37:57 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:37:57 volumiotop volumio[6862]: info: MYVOLUMIO Environment detected Nov 25 10:37:58 volumiotop volumio[6862]: info: Plugin folders cleanup Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning into folder /volumio/app/plugins/ Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category audio_interface Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category miscellanea Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category music_service Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category plugins.json Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category system_controller Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category user_interface Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning into folder /data/plugins/ Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category music_service Nov 25 10:37:58 volumiotop volumio[6862]: info: Scanning category system_hardware Nov 25 10:37:58 volumiotop volumio[6862]: info: Plugin folders cleanup completed Nov 25 10:37:58 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:37:58 volumiotop volumio[6862]: info: ----- Core plugins startup ---- Nov 25 10:37:58 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:37:58 volumiotop volumio[6862]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 10:37:58 volumiotop volumio[6862]: info: Adding plugin upnp to MyMusic Plugins Nov 25 10:37:58 volumiotop volumio[6862]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 10:37:58 volumiotop volumio[6862]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 10:37:58 volumiotop volumio[6862]: info: Loading plugins from folder /data/plugins/ Nov 25 10:37:58 volumiotop volumio[6862]: info: Loading plugin "system"... Nov 25 10:37:58 volumiotop volumio[6862]: info: Loading plugin "appearance"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "network"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Refreshing Cached IP Addresses Nov 25 10:37:59 volumiotop sudo[6888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 10:37:59 volumiotop sudo[6888]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:59 volumiotop sudo[6888]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:59 volumiotop sudo[6890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 10:37:59 volumiotop sudo[6890]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "services"... Nov 25 10:37:59 volumiotop sudo[6890]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "alsa_controller"... Nov 25 10:37:59 volumiotop sudo[6893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 10:37:59 volumiotop sudo[6893]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:59 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "wizard"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "networkfs"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Starting Udev Watcher for removable devices Nov 25 10:37:59 volumiotop volumio[6862]: info: Ignoring mount for partition: boot Nov 25 10:37:59 volumiotop volumio[6862]: info: Ignoring mount for partition: volumio Nov 25 10:37:59 volumiotop volumio[6862]: info: Ignoring mount for partition: volumio_data Nov 25 10:37:59 volumiotop volumio[6862]: info: Mounting Device 8B0D-6686 Nov 25 10:37:59 volumiotop sudo[6905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/8B0D-6686 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:37:59 volumiotop sudo[6905]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:37:59 volumiotop sudo[6905]: pam_unix(sudo:session): session closed for user root Nov 25 10:37:59 volumiotop volumio[6862]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:37:59 volumiotop volumio[6862]: error: Failed to mount 8B0D-6686: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/8B0D-6686" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:37:59 volumiotop volumio[6862]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:37:59 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "volumio_command_line_client"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Plugin upnp is not enabled Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "my_music"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "mpd"... Nov 25 10:37:59 volumiotop volumio[6862]: info: Plugin upnp_browser is not enabled Nov 25 10:37:59 volumiotop volumio[6862]: info: Loading plugin "alarm-clock"... Nov 25 10:38:00 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:00] [connect] Successful connection Nov 25 10:38:00 volumiotop volumio[6862]: info: Loading plugin "airplay_emulation"... Nov 25 10:38:00 volumiotop volumio[6862]: info: Starting Shairport Sync Nov 25 10:38:00 volumiotop volumio[6862]: info: Loading plugin "last_100"... Nov 25 10:38:00 volumiotop volumio[6862]: info: Loading plugin "webradio"... Nov 25 10:38:00 volumiotop sudo[6893]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:00 volumiotop volumio[6862]: info: Loading plugin "i2s_dacs"... Nov 25 10:38:00 volumiotop volumio[6862]: info: I2S DAC not set, start Auto-detection Nov 25 10:38:00 volumiotop volumio[6862]: info: Loading plugin "volumiodiscovery"... Nov 25 10:38:00 volumiotop volumio[6862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:00 volumiotop node[6862]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:00 volumiotop volumio[6862]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:00 volumiotop node[6862]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:00 volumiotop volumio[6862]: *** WARNING *** For more information see Nov 25 10:38:00 volumiotop node[6862]: *** WARNING *** For more information see Nov 25 10:38:00 volumiotop volumio[6862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:00 volumiotop node[6862]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:00 volumiotop volumio[6862]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:00 volumiotop node[6862]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:00 volumiotop volumio[6862]: *** WARNING *** For more information see Nov 25 10:38:00 volumiotop node[6862]: *** WARNING *** For more information see Nov 25 10:38:00 volumiotop volumio[6862]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 10:38:00 volumiotop volumio[6862]: info: Discovery: Started advertising with name: Volumiotop Nov 25 10:38:00 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:38:00 volumiotop volumio[6862]: info: Loading plugin "calmradio"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "outputs"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "albumart"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Plugin example_plugin is not enabled Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "inputs"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "updater_comm"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Plugin mpdemulation is not enabled Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "rest_api"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "websocket"... Nov 25 10:38:01 volumiotop volumio[6862]: info: Starting Socket.io Server version 2.3.0 Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading plugin "motherearthradio"... Nov 25 10:38:01 volumiotop volumio[6862]: Forking 3 albumart workers Nov 25 10:38:01 volumiotop volumio[6862]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 10:38:01 volumiotop volumio[6862]: info: [1732527481837] [MotherEarth] API delay: 5 Nov 25 10:38:01 volumiotop volumio[6862]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 10:38:01 volumiotop volumio[6862]: info: Loading i18n strings for locale fr Nov 25 10:38:01 volumiotop volumio[6862]: Updating browse sources language Nov 25 10:38:01 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::initPlayerControls Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: Express server listening on port 3000 Nov 25 10:38:02 volumiotop volumio[6862]: [Metrics] WebUI: 5s 470.83ms Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::resetVolumioState Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::getcurrentVolume Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 25 10:38:02 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732527480 101 Nov 25 10:38:02 volumiotop volumio[6862]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 25 10:38:02 volumiotop volumio[6862]: info: Reloading queue from file Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::setRepeat null single undefined Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::pushState Nov 25 10:38:02 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::setRandom true Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::pushState Nov 25 10:38:02 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:02 volumiotop volumio[6862]: info: Setting Device type: Raspberry PI Nov 25 10:38:02 volumiotop volumio[6862]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::pushState Nov 25 10:38:02 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreStateMachine::updateTrackBlock Nov 25 10:38:02 volumiotop volumio[6862]: info: CorePlayQueue::getTrackBlock Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:38:02 volumiotop volumio[6862]: info: Completed loading Core Plugins Nov 25 10:38:02 volumiotop volumio[6862]: info: Preparing to generate the ALSA configuration file Nov 25 10:38:02 volumiotop volumio[6862]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 10:38:02 volumiotop volumio[6862]: info: Reading ALSA contributions from plugins. Nov 25 10:38:02 volumiotop volumio[6862]: info: Asound.conf file unchanged, so no further update is needed Nov 25 10:38:02 volumiotop volumio[6862]: info: Output device has changed, restarting MPD Nov 25 10:38:02 volumiotop volumio[6862]: Starting albumart workers Nov 25 10:38:02 volumiotop volumio[6862]: Starting albumart workers Nov 25 10:38:02 volumiotop volumio[6862]: info: Output device has changed, restarting Shairport Sync Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:02 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:02 volumiotop sudo[6952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:38:02 volumiotop sudo[6952]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:02 volumiotop volumio[6862]: Starting albumart workers Nov 25 10:38:02 volumiotop sudo[6952]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:02 volumiotop sudo[6954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:38:02 volumiotop sudo[6954]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:02 volumiotop volumio[6862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:02 volumiotop volumio[6862]: info: ___________ START PLUGINS ___________ Nov 25 10:38:02 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 10:38:02 volumiotop volumio[6862]: info: ControllerMpd::onStart: Initializing MPD Nov 25 10:38:02 volumiotop volumio[6862]: info: Creating MPD Configuration file Nov 25 10:38:02 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:38:02 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:38:03 volumiotop sudo[6961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:38:03 volumiotop sudo[6961]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:03 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:03 volumiotop sudo[6961]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:03 volumiotop sudo[6963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:38:03 volumiotop sudo[6963]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:03 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 10:38:03 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:38:03 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:38:03 volumiotop volumio[6862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:03 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:38:03 volumiotop volumio[6862]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:03 volumiotop volumio[6862]: info: [1732527483260] CoreMusicLibrary::Adding element Last_100 Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:03 volumiotop volumio[6862]: info: [1732527483285] CoreMusicLibrary::Adding element Webradio Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:03 volumiotop volumio[6862]: info: Initializing BBC Radios Nov 25 10:38:03 volumiotop sudo[6970]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 10:38:03 volumiotop sudo[6970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:03 volumiotop sudo[6970]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:03 volumiotop volumio[6862]: info: Adding Calm Radio to Browse Sources Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:03 volumiotop volumio[6862]: info: [1732527483455] CoreMusicLibrary::Adding element Calm Radio Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:03 volumiotop volumio[6862]: Cannot find translation for source Calm Radio Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:03 volumiotop volumio[6862]: info: [1732527483473] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:03 volumiotop volumio[6862]: Cannot find translation for source Calm Radio Nov 25 10:38:03 volumiotop volumio[6862]: Cannot find translation for source Mother Earth Radio Nov 25 10:38:03 volumiotop volumio[6862]: info: Volumio Calling Home Nov 25 10:38:03 volumiotop volumio[6862]: info: MPD Permissions set Nov 25 10:38:03 volumiotop volumio[6862]: info: MPD Permissions set Nov 25 10:38:03 volumiotop volumio[6862]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreStateMachine::pushState Nov 25 10:38:03 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:03 volumiotop volumio[6862]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:03 volumiotop volumio[6862]: info: Volumio called home Nov 25 10:38:04 volumiotop volumio[6862]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 25 10:38:04 volumiotop volumio[6862]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:04 volumiotop volumio[6862]: info: Starting Shairport Sync Nov 25 10:38:04 volumiotop volumio[6862]: info: Starting Shairport Sync Nov 25 10:38:04 volumiotop volumio[6862]: info: Starting Shairport Sync Nov 25 10:38:04 volumiotop sudo[7023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:04 volumiotop sudo[7023]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:04 volumiotop sudo[7025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:04 volumiotop sudo[7025]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:04 volumiotop sudo[7029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:04 volumiotop sudo[7029]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:04 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:38:04 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:38:04 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:04 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:04 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:04 volumiotop sudo[7025]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:04 volumiotop sudo[7023]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:04 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:38:04 volumiotop systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 25 10:38:04 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:38:04 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:04 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:04 volumiotop sudo[7029]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:04 volumiotop volumio[6862]: info: Shairport-Sync Started Nov 25 10:38:04 volumiotop volumio[6862]: Error adding Membership: Error: addMembership EINVAL Nov 25 10:38:04 volumiotop volumio[6862]: info: Shairport-Sync Started Nov 25 10:38:04 volumiotop volumio[6862]: info: Shairport-Sync Started Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 10:38:04 volumiotop volumio[6862]: info: Discovery: Getting this device information Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:04 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 10:38:04 volumiotop volumio[6862]: verbose: New Socket.io Connection to 192.168.1.165:3000 from 192.168.1.43 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:04 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 25 10:38:04 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 25 10:38:05 volumiotop mpd[6982]: Nov 25 10:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 10:38:05 volumiotop systemd[1]: Started Music Player Daemon. Nov 25 10:38:05 volumiotop sudo[6963]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:05 volumiotop sudo[6954]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:05 volumiotop volumio[6862]: info: Completed starting Core Plugins Nov 25 10:38:05 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:38:05 volumiotop volumio[6862]: info: ----- MyVolumio plugins startup ---- Nov 25 10:38:05 volumiotop volumio[6862]: info: ------------------------------------------- Nov 25 10:38:05 volumiotop volumio[6862]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 25 10:38:05 volumiotop volumio[6862]: error: MPD error: The expression evaluated to a falsy value: Nov 25 10:38:05 volumiotop volumio[6862]: assert.ok(self.idling) Nov 25 10:38:05 volumiotop volumio[6862]: error: The expression evaluated to a falsy value: Nov 25 10:38:05 volumiotop volumio[6862]: assert.ok(self.idling) Nov 25 10:38:05 volumiotop volumio[6862]: info: MPD running with PID6982 Nov 25 10:38:05 volumiotop volumio[6862]: ,establishing connection Nov 25 10:38:05 volumiotop volumio[6862]: error: MPD error: The expression evaluated to a falsy value: Nov 25 10:38:05 volumiotop volumio[6862]: assert.ok(self.idling) Nov 25 10:38:05 volumiotop volumio[6862]: error: The expression evaluated to a falsy value: Nov 25 10:38:05 volumiotop volumio[6862]: assert.ok(self.idling) Nov 25 10:38:05 volumiotop volumio[6862]: error: updateQueue error: null Nov 25 10:38:07 volumiotop volumio[6862]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:07 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 10:38:07 volumiotop volumio[6862]: info: Received Get System Info Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 10:38:07 volumiotop volumio[6862]: info: Discovery: Getting this device information Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:07 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:07 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:07 volumiotop volumio[6862]: info: Listing playlists Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetQueue Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreStateMachine::getQueue Nov 25 10:38:07 volumiotop volumio[6862]: info: CorePlayQueue::getQueue Nov 25 10:38:07 volumiotop volumio[6862]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 10:38:07 volumiotop volumio[6862]: info: Discovery: Found device Volumiotop Nov 25 10:38:07 volumiotop volumio[6862]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:07 volumiotop volumio[6862]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:11 volumiotop volumio[6862]: Cannot compose Albumart path Nov 25 10:38:11 volumiotop volumio[6862]: Searching all installed plugins Nov 25 10:38:11 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:11 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 10:38:11 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 10:38:11 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 10:38:11 volumiotop volumio[6862]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 10:38:11 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 10:38:11 volumiotop volumio[6862]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 10:38:11 volumiotop volumio[6862]: info: [1732527491572] ControllerCalmRadio::searchCategories Nov 25 10:38:11 volumiotop volumio[6862]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:38:11 volumiotop volumio[6862]: TypeError: Cannot read property 'cats' of undefined Nov 25 10:38:11 volumiotop volumio[6862]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 10:38:11 volumiotop volumio[6862]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 10:38:11 volumiotop volumio[6862]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 10:38:11 volumiotop volumio[6862]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 10:38:11 volumiotop volumio[6862]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 10:38:11 volumiotop volumio[6862]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 10:38:11 volumiotop volumio[6862]: at Socket.emit (events.js:315:20) Nov 25 10:38:11 volumiotop volumio[6862]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 10:38:11 volumiotop volumio[6862]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 10:38:11 volumiotop volumio[6862]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:38:12 volumiotop sudo[7051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 10:37 Nov 25 10:38:12 volumiotop sudo[7051]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:12 volumiotop sudo[7051]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:12 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 10:38:12 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 10:38:12 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 10:38:12 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 10:38:12 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:38:12 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:38:12 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 10:38:12 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Nov 25 10:38:12 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 10:38:12 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 10:38:12 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:38:12 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:38:14 volumiotop volumio[7061]: info: ------------------------------------------- Nov 25 10:38:14 volumiotop volumio[7061]: info: ----- Volumio3 ---- Nov 25 10:38:14 volumiotop volumio[7061]: info: ------------------------------------------- Nov 25 10:38:14 volumiotop volumio[7061]: info: ----- System startup ---- Nov 25 10:38:14 volumiotop volumio[7061]: info: ------------------------------------------- Nov 25 10:38:15 volumiotop volumio[7061]: info: MYVOLUMIO Environment detected Nov 25 10:38:15 volumiotop volumio[7061]: info: Plugin folders cleanup Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning into folder /volumio/app/plugins/ Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category audio_interface Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category miscellanea Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category music_service Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category plugins.json Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category system_controller Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category user_interface Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning into folder /data/plugins/ Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category music_service Nov 25 10:38:15 volumiotop volumio[7061]: info: Scanning category system_hardware Nov 25 10:38:15 volumiotop volumio[7061]: info: Plugin folders cleanup completed Nov 25 10:38:15 volumiotop volumio[7061]: info: ------------------------------------------- Nov 25 10:38:15 volumiotop volumio[7061]: info: ----- Core plugins startup ---- Nov 25 10:38:15 volumiotop volumio[7061]: info: ------------------------------------------- Nov 25 10:38:15 volumiotop volumio[7061]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 10:38:15 volumiotop volumio[7061]: info: Adding plugin upnp to MyMusic Plugins Nov 25 10:38:15 volumiotop volumio[7061]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 10:38:15 volumiotop volumio[7061]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 10:38:15 volumiotop volumio[7061]: info: Loading plugins from folder /data/plugins/ Nov 25 10:38:15 volumiotop volumio[7061]: info: Loading plugin "system"... Nov 25 10:38:15 volumiotop volumio[7061]: info: Loading plugin "appearance"... Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "network"... Nov 25 10:38:16 volumiotop volumio[7061]: info: Refreshing Cached IP Addresses Nov 25 10:38:16 volumiotop sudo[7088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 10:38:16 volumiotop sudo[7088]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:16 volumiotop sudo[7088]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:16 volumiotop sudo[7090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "services"... Nov 25 10:38:16 volumiotop sudo[7090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "alsa_controller"... Nov 25 10:38:16 volumiotop sudo[7090]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:16 volumiotop sudo[7093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 10:38:16 volumiotop sudo[7093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:16 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "wizard"... Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "networkfs"... Nov 25 10:38:16 volumiotop volumio[7061]: info: Starting Udev Watcher for removable devices Nov 25 10:38:16 volumiotop volumio[7061]: info: Ignoring mount for partition: boot Nov 25 10:38:16 volumiotop volumio[7061]: info: Ignoring mount for partition: volumio Nov 25 10:38:16 volumiotop volumio[7061]: info: Ignoring mount for partition: volumio_data Nov 25 10:38:16 volumiotop volumio[7061]: info: Mounting Device 8B0D-6686 Nov 25 10:38:16 volumiotop sudo[7104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/8B0D-6686 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:38:16 volumiotop sudo[7104]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:16 volumiotop sudo[7104]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:16 volumiotop volumio[7061]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:38:16 volumiotop volumio[7061]: error: Failed to mount 8B0D-6686: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/8B0D-6686" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:38:16 volumiotop volumio[7061]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:38:16 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "volumio_command_line_client"... Nov 25 10:38:16 volumiotop volumio[7061]: info: Plugin upnp is not enabled Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "my_music"... Nov 25 10:38:16 volumiotop volumio[7061]: info: Loading plugin "mpd"... Nov 25 10:38:17 volumiotop volumio[7061]: info: Plugin upnp_browser is not enabled Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "alarm-clock"... Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "airplay_emulation"... Nov 25 10:38:17 volumiotop volumio[7061]: info: Starting Shairport Sync Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "last_100"... Nov 25 10:38:17 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:17] [connect] Successful connection Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "webradio"... Nov 25 10:38:17 volumiotop sudo[7093]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "i2s_dacs"... Nov 25 10:38:17 volumiotop volumio[7061]: info: I2S DAC not set, start Auto-detection Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "volumiodiscovery"... Nov 25 10:38:17 volumiotop volumio[7061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:17 volumiotop volumio[7061]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:17 volumiotop volumio[7061]: *** WARNING *** For more information see Nov 25 10:38:17 volumiotop node[7061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:17 volumiotop volumio[7061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:17 volumiotop volumio[7061]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:17 volumiotop volumio[7061]: *** WARNING *** For more information see Nov 25 10:38:17 volumiotop node[7061]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:17 volumiotop node[7061]: *** WARNING *** For more information see Nov 25 10:38:17 volumiotop node[7061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:17 volumiotop node[7061]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:17 volumiotop node[7061]: *** WARNING *** For more information see Nov 25 10:38:17 volumiotop volumio[7061]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 10:38:17 volumiotop volumio[7061]: info: Discovery: Started advertising with name: Volumiotop Nov 25 10:38:17 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:38:17 volumiotop volumio[7061]: info: Loading plugin "calmradio"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "outputs"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "albumart"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Plugin example_plugin is not enabled Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "inputs"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "updater_comm"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Plugin mpdemulation is not enabled Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "rest_api"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "websocket"... Nov 25 10:38:18 volumiotop volumio[7061]: info: Starting Socket.io Server version 2.3.0 Nov 25 10:38:18 volumiotop volumio[7061]: info: Loading plugin "motherearthradio"... Nov 25 10:38:19 volumiotop volumio[7061]: Forking 3 albumart workers Nov 25 10:38:19 volumiotop volumio[7061]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 10:38:19 volumiotop volumio[7061]: info: [1732527499103] [MotherEarth] API delay: 5 Nov 25 10:38:19 volumiotop volumio[7061]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 10:38:19 volumiotop volumio[7061]: info: Loading i18n strings for locale fr Nov 25 10:38:19 volumiotop volumio[7061]: Updating browse sources language Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::initPlayerControls Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: Express server listening on port 3000 Nov 25 10:38:19 volumiotop volumio[7061]: [Metrics] WebUI: 5s 463.80ms Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::resetVolumioState Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::getcurrentVolume Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:19 volumiotop volumio[7061]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 25 10:38:19 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732527497 101 Nov 25 10:38:19 volumiotop volumio[7061]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 25 10:38:19 volumiotop volumio[7061]: info: Reloading queue from file Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::setRepeat null single undefined Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::pushState Nov 25 10:38:19 volumiotop volumio[7061]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::setRandom true Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::pushState Nov 25 10:38:19 volumiotop volumio[7061]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:19 volumiotop volumio[7061]: info: Setting Device type: Raspberry PI Nov 25 10:38:19 volumiotop volumio[7061]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::pushState Nov 25 10:38:19 volumiotop volumio[7061]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreStateMachine::updateTrackBlock Nov 25 10:38:19 volumiotop volumio[7061]: info: CorePlayQueue::getTrackBlock Nov 25 10:38:19 volumiotop volumio[7061]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:38:19 volumiotop volumio[7061]: info: Completed loading Core Plugins Nov 25 10:38:19 volumiotop volumio[7061]: info: Preparing to generate the ALSA configuration file Nov 25 10:38:20 volumiotop volumio[7061]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 10:38:20 volumiotop volumio[7061]: info: Discovery: Found device Volumiotop Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:20 volumiotop volumio[7061]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:20 volumiotop volumio[7061]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 10:38:20 volumiotop volumio[7061]: info: Reading ALSA contributions from plugins. Nov 25 10:38:20 volumiotop volumio[7061]: Starting albumart workers Nov 25 10:38:20 volumiotop volumio[7061]: Starting albumart workers Nov 25 10:38:20 volumiotop volumio[7061]: info: Asound.conf file unchanged, so no further update is needed Nov 25 10:38:20 volumiotop volumio[7061]: info: Output device has changed, restarting MPD Nov 25 10:38:20 volumiotop volumio[7061]: info: Output device has changed, restarting Shairport Sync Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:20 volumiotop sudo[7149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:38:20 volumiotop sudo[7149]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:20 volumiotop sudo[7151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:38:20 volumiotop sudo[7151]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:20 volumiotop sudo[7149]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:20 volumiotop volumio[7061]: Starting albumart workers Nov 25 10:38:20 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 10:38:20 volumiotop volumio[7061]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:20 volumiotop volumio[7061]: info: ___________ START PLUGINS ___________ Nov 25 10:38:20 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:38:20 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:38:20 volumiotop volumio[7061]: info: ControllerMpd::onStart: Initializing MPD Nov 25 10:38:20 volumiotop volumio[7061]: info: Creating MPD Configuration file Nov 25 10:38:20 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:38:20 volumiotop sudo[7160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:38:20 volumiotop sudo[7160]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:20 volumiotop sudo[7160]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:20 volumiotop sudo[7162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:38:20 volumiotop sudo[7162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:20 volumiotop volumio[7061]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:20 volumiotop sudo[7157]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 10:38:20 volumiotop sudo[7157]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:20 volumiotop sudo[7157]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:20 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 10:38:20 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:38:20 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:38:20 volumiotop volumio[7061]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:20 volumiotop volumio[7061]: info: [1732527500567] CoreMusicLibrary::Adding element Last_100 Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:20 volumiotop volumio[7061]: info: [1732527500573] CoreMusicLibrary::Adding element Webradio Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:20 volumiotop volumio[7061]: info: Initializing BBC Radios Nov 25 10:38:20 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:20 volumiotop volumio[7061]: info: Adding Calm Radio to Browse Sources Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:20 volumiotop volumio[7061]: info: [1732527500721] CoreMusicLibrary::Adding element Calm Radio Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:20 volumiotop volumio[7061]: Cannot find translation for source Calm Radio Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:20 volumiotop volumio[7061]: info: [1732527500744] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 10:38:20 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:20 volumiotop volumio[7061]: Cannot find translation for source Calm Radio Nov 25 10:38:20 volumiotop volumio[7061]: Cannot find translation for source Mother Earth Radio Nov 25 10:38:20 volumiotop volumio[7061]: info: Volumio Calling Home Nov 25 10:38:20 volumiotop sudo[7170]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 10:38:20 volumiotop sudo[7170]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:20 volumiotop sudo[7170]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:21 volumiotop volumio[7061]: info: MPD Permissions set Nov 25 10:38:21 volumiotop volumio[7061]: info: MPD Permissions set Nov 25 10:38:21 volumiotop volumio[7061]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreStateMachine::pushState Nov 25 10:38:21 volumiotop volumio[7061]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:21 volumiotop volumio[7061]: info: Volumio called home Nov 25 10:38:21 volumiotop volumio[7061]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:21 volumiotop volumio[7061]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 25 10:38:21 volumiotop volumio[7061]: info: Starting Shairport Sync Nov 25 10:38:21 volumiotop volumio[7061]: info: Starting Shairport Sync Nov 25 10:38:21 volumiotop volumio[7061]: info: Starting Shairport Sync Nov 25 10:38:21 volumiotop sudo[7220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:21 volumiotop sudo[7220]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:21 volumiotop sudo[7222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:21 volumiotop sudo[7222]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:21 volumiotop sudo[7226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:21 volumiotop sudo[7226]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:21 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:38:21 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:38:21 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:21 volumiotop volumio[7061]: Searching all installed plugins Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 10:38:21 volumiotop volumio[7061]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 10:38:21 volumiotop volumio[7061]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:38:21 volumiotop volumio[7061]: TypeError: Cannot read property 'then' of undefined Nov 25 10:38:21 volumiotop volumio[7061]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1582:17) Nov 25 10:38:21 volumiotop volumio[7061]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 10:38:21 volumiotop volumio[7061]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 10:38:21 volumiotop volumio[7061]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 10:38:21 volumiotop volumio[7061]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 10:38:21 volumiotop volumio[7061]: at Socket.emit (events.js:315:20) Nov 25 10:38:21 volumiotop volumio[7061]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 10:38:21 volumiotop volumio[7061]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 10:38:21 volumiotop volumio[7061]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:38:21 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:21 volumiotop sudo[7220]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:21 volumiotop sudo[7222]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:21 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:38:21 volumiotop systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 25 10:38:21 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:38:21 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:21 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:21 volumiotop sudo[7226]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:22 volumiotop sudo[7241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 10:37 Nov 25 10:38:22 volumiotop sudo[7241]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:22 volumiotop sudo[7241]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:22 volumiotop mpd[7184]: Nov 25 10:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 10:38:22 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:22] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 10:38:22 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:22] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 10:38:22 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 10:38:22 volumiotop sudo[7162]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:22 volumiotop sudo[7151]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:22 volumiotop systemd[1]: Started Music Player Daemon. Nov 25 10:38:22 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 10:38:22 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:38:22 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:38:23 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 10:38:23 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Nov 25 10:38:23 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 10:38:23 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 10:38:23 volumiotop systemd[1]: Started dynamicswap service. Nov 25 10:38:23 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 10:38:25 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:25 volumiotop volumio[7257]: info: ----- Volumio3 ---- Nov 25 10:38:25 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:25 volumiotop volumio[7257]: info: ----- System startup ---- Nov 25 10:38:25 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:25 volumiotop volumio[7257]: info: MYVOLUMIO Environment detected Nov 25 10:38:25 volumiotop volumio[7257]: info: Plugin folders cleanup Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning into folder /volumio/app/plugins/ Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category audio_interface Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category miscellanea Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category music_service Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category plugins.json Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category system_controller Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category user_interface Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning into folder /data/plugins/ Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category music_service Nov 25 10:38:25 volumiotop volumio[7257]: info: Scanning category system_hardware Nov 25 10:38:25 volumiotop volumio[7257]: info: Plugin folders cleanup completed Nov 25 10:38:25 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:25 volumiotop volumio[7257]: info: ----- Core plugins startup ---- Nov 25 10:38:25 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:25 volumiotop volumio[7257]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 10:38:25 volumiotop volumio[7257]: info: Adding plugin upnp to MyMusic Plugins Nov 25 10:38:25 volumiotop volumio[7257]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 10:38:25 volumiotop volumio[7257]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 10:38:25 volumiotop volumio[7257]: info: Loading plugins from folder /data/plugins/ Nov 25 10:38:25 volumiotop volumio[7257]: info: Loading plugin "system"... Nov 25 10:38:25 volumiotop volumio[7257]: info: Loading plugin "appearance"... Nov 25 10:38:26 volumiotop volumio[7257]: info: Loading plugin "network"... Nov 25 10:38:26 volumiotop volumio[7257]: info: Refreshing Cached IP Addresses Nov 25 10:38:26 volumiotop sudo[7284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 10:38:26 volumiotop sudo[7284]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:26 volumiotop sudo[7284]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:26 volumiotop sudo[7286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 10:38:26 volumiotop volumio[7257]: info: Loading plugin "services"... Nov 25 10:38:26 volumiotop sudo[7286]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:26 volumiotop volumio[7257]: info: Loading plugin "alsa_controller"... Nov 25 10:38:26 volumiotop sudo[7286]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:26 volumiotop sudo[7289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 10:38:26 volumiotop sudo[7289]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:27 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "wizard"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "networkfs"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Starting Udev Watcher for removable devices Nov 25 10:38:27 volumiotop volumio[7257]: info: Ignoring mount for partition: boot Nov 25 10:38:27 volumiotop volumio[7257]: info: Ignoring mount for partition: volumio Nov 25 10:38:27 volumiotop volumio[7257]: info: Ignoring mount for partition: volumio_data Nov 25 10:38:27 volumiotop volumio[7257]: info: Mounting Device 8B0D-6686 Nov 25 10:38:27 volumiotop sudo[7300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/8B0D-6686 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:38:27 volumiotop sudo[7300]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:27 volumiotop sudo[7300]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:27 volumiotop volumio[7257]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:38:27 volumiotop volumio[7257]: error: Failed to mount 8B0D-6686: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/8B0D-6686" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 25 10:38:27 volumiotop volumio[7257]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 10:38:27 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "volumio_command_line_client"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Plugin upnp is not enabled Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "my_music"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "mpd"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Plugin upnp_browser is not enabled Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "alarm-clock"... Nov 25 10:38:27 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:27] [connect] Successful connection Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "airplay_emulation"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Starting Shairport Sync Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "last_100"... Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "webradio"... Nov 25 10:38:27 volumiotop sudo[7289]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "i2s_dacs"... Nov 25 10:38:27 volumiotop volumio[7257]: info: I2S DAC not set, start Auto-detection Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "volumiodiscovery"... Nov 25 10:38:27 volumiotop volumio[7257]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:27 volumiotop volumio[7257]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:27 volumiotop volumio[7257]: *** WARNING *** For more information see Nov 25 10:38:27 volumiotop node[7257]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:27 volumiotop volumio[7257]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:27 volumiotop volumio[7257]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:27 volumiotop volumio[7257]: *** WARNING *** For more information see Nov 25 10:38:27 volumiotop node[7257]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:27 volumiotop node[7257]: *** WARNING *** For more information see Nov 25 10:38:27 volumiotop node[7257]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 10:38:27 volumiotop node[7257]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 10:38:27 volumiotop node[7257]: *** WARNING *** For more information see Nov 25 10:38:27 volumiotop volumio[7257]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 10:38:27 volumiotop volumio[7257]: info: Discovery: Started advertising with name: Volumiotop Nov 25 10:38:27 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 10:38:27 volumiotop volumio[7257]: info: Loading plugin "calmradio"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "outputs"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "albumart"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Plugin example_plugin is not enabled Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "inputs"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "updater_comm"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Plugin mpdemulation is not enabled Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "rest_api"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "websocket"... Nov 25 10:38:29 volumiotop volumio[7257]: info: Starting Socket.io Server version 2.3.0 Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading plugin "motherearthradio"... Nov 25 10:38:29 volumiotop volumio[7257]: Forking 3 albumart workers Nov 25 10:38:29 volumiotop volumio[7257]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 10:38:29 volumiotop volumio[7257]: info: [1732527509561] [MotherEarth] API delay: 5 Nov 25 10:38:29 volumiotop volumio[7257]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 10:38:29 volumiotop volumio[7257]: info: Loading i18n strings for locale fr Nov 25 10:38:29 volumiotop volumio[7257]: Updating browse sources language Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::initPlayerControls Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 10:38:29 volumiotop volumio[7257]: Express server listening on port 3000 Nov 25 10:38:29 volumiotop volumio[7257]: [Metrics] WebUI: 5s 442.72ms Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreStateMachine::resetVolumioState Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreStateMachine::getcurrentVolume Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:38:29 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:30 volumiotop volumio[7257]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 25 10:38:30 volumiotop volumio[7257]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 25 10:38:30 volumiotop volumio-remote-updater[675]: [2024-11-25 10:38:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732527507 101 Nov 25 10:38:30 volumiotop volumio[7257]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Nov 25 10:38:30 volumiotop volumio[7257]: info: Reloading queue from file Nov 25 10:38:30 volumiotop volumio[7257]: info: Setting Device type: Raspberry PI Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreStateMachine::setRepeat null single undefined Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreStateMachine::pushState Nov 25 10:38:30 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreStateMachine::setRandom true Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreStateMachine::pushState Nov 25 10:38:30 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:30 volumiotop volumio[7257]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreStateMachine::pushState Nov 25 10:38:30 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreStateMachine::updateTrackBlock Nov 25 10:38:30 volumiotop volumio[7257]: info: CorePlayQueue::getTrackBlock Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 10:38:30 volumiotop volumio[7257]: info: Completed loading Core Plugins Nov 25 10:38:30 volumiotop volumio[7257]: info: Preparing to generate the ALSA configuration file Nov 25 10:38:30 volumiotop volumio[7257]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 10:38:30 volumiotop volumio[7257]: info: Discovery: Found device Volumiotop Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:30 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:30 volumiotop volumio[7257]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 10:38:30 volumiotop volumio[7257]: info: Reading ALSA contributions from plugins. Nov 25 10:38:30 volumiotop volumio[7257]: info: Asound.conf file unchanged, so no further update is needed Nov 25 10:38:30 volumiotop volumio[7257]: Starting albumart workers Nov 25 10:38:30 volumiotop volumio[7257]: info: Output device has changed, restarting MPD Nov 25 10:38:30 volumiotop volumio[7257]: Starting albumart workers Nov 25 10:38:30 volumiotop volumio[7257]: info: Output device has changed, restarting Shairport Sync Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:30 volumiotop volumio[7257]: Starting albumart workers Nov 25 10:38:30 volumiotop sudo[7346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:38:30 volumiotop sudo[7346]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:30 volumiotop sudo[7346]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:30 volumiotop sudo[7349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:38:30 volumiotop sudo[7349]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:30 volumiotop volumio[7257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:30 volumiotop volumio[7257]: info: ___________ START PLUGINS ___________ Nov 25 10:38:30 volumiotop volumio[7257]: info: ControllerMpd::onStart: Initializing MPD Nov 25 10:38:30 volumiotop volumio[7257]: info: Creating MPD Configuration file Nov 25 10:38:30 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 10:38:30 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:38:30 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:38:30 volumiotop sudo[7356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:30 volumiotop sudo[7356]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:30 volumiotop sudo[7356]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:30 volumiotop sudo[7357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 10:38:30 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:38:30 volumiotop sudo[7357]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:30 volumiotop volumio[7257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:30 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 10:38:30 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 10:38:30 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:30 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:30 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 10:38:30 volumiotop volumio[7257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:31 volumiotop volumio[7257]: info: [1732527511001] CoreMusicLibrary::Adding element Last_100 Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:31 volumiotop volumio[7257]: info: [1732527511009] CoreMusicLibrary::Adding element Webradio Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:31 volumiotop volumio[7257]: info: Initializing BBC Radios Nov 25 10:38:31 volumiotop sudo[7367]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 10:38:31 volumiotop sudo[7367]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:31 volumiotop sudo[7367]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: Adding Calm Radio to Browse Sources Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:31 volumiotop volumio[7257]: info: [1732527511142] CoreMusicLibrary::Adding element Calm Radio Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:31 volumiotop volumio[7257]: Cannot find translation for source Calm Radio Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 10:38:31 volumiotop volumio[7257]: info: [1732527511170] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:31 volumiotop volumio[7257]: Cannot find translation for source Calm Radio Nov 25 10:38:31 volumiotop volumio[7257]: Cannot find translation for source Mother Earth Radio Nov 25 10:38:31 volumiotop volumio[7257]: info: Volumio Calling Home Nov 25 10:38:31 volumiotop volumio[7257]: info: MPD Permissions set Nov 25 10:38:31 volumiotop volumio[7257]: info: MPD Permissions set Nov 25 10:38:31 volumiotop volumio[7257]: info: VolumeController:: Volume=67 Mute =false Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreStateMachine::pushState Nov 25 10:38:31 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::volumioPushState Nov 25 10:38:31 volumiotop volumio[7257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 10:38:31 volumiotop volumio[7257]: info: Starting Shairport Sync Nov 25 10:38:32 volumiotop volumio[7257]: info: Starting Shairport Sync Nov 25 10:38:32 volumiotop volumio[7257]: info: Starting Shairport Sync Nov 25 10:38:32 volumiotop sudo[7453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:32 volumiotop sudo[7453]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:32 volumiotop sudo[7455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:32 volumiotop sudo[7455]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:32 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:38:32 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:38:32 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:32 volumiotop sudo[7459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 10:38:32 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:32 volumiotop sudo[7459]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 10:38:32 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:32 volumiotop sudo[7453]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:32 volumiotop sudo[7455]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:32 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 10:38:32 volumiotop systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 25 10:38:32 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 10:38:32 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:32 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 10:38:32 volumiotop sudo[7459]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:32 volumiotop volumio[7257]: info: Shairport-Sync Started Nov 25 10:38:32 volumiotop volumio[7257]: Error adding Membership: Error: addMembership EINVAL Nov 25 10:38:32 volumiotop volumio[7257]: info: Shairport-Sync Started Nov 25 10:38:32 volumiotop volumio[7257]: info: Shairport-Sync Started Nov 25 10:38:32 volumiotop volumio[7257]: verbose: New Socket.io Connection to 192.168.1.165 from 192.168.1.43 UA: Mozilla/5.0 (Linux; Android 12; M2101K6G Build/SKQ1.210908.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:32 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 10:38:32 volumiotop volumio[7257]: info: Received Get System Info Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 10:38:32 volumiotop volumio[7257]: info: Discovery: Getting this device information Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:32 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetState Nov 25 10:38:32 volumiotop volumio[7257]: info: CorePlayQueue::getTrack 0 Nov 25 10:38:32 volumiotop volumio[7257]: info: Listing playlists Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreCommandRouter::volumioGetQueue Nov 25 10:38:32 volumiotop volumio[7257]: info: CoreStateMachine::getQueue Nov 25 10:38:32 volumiotop volumio[7257]: info: CorePlayQueue::getQueue Nov 25 10:38:32 volumiotop volumio[7257]: info: Volumio called home Nov 25 10:38:33 volumiotop mpd[7377]: Nov 25 10:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 10:38:33 volumiotop systemd[1]: Started Music Player Daemon. Nov 25 10:38:33 volumiotop sudo[7357]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:33 volumiotop sudo[7349]: pam_unix(sudo:session): session closed for user root Nov 25 10:38:33 volumiotop volumio[7257]: info: Completed starting Core Plugins Nov 25 10:38:33 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:33 volumiotop volumio[7257]: info: ----- MyVolumio plugins startup ---- Nov 25 10:38:33 volumiotop volumio[7257]: info: ------------------------------------------- Nov 25 10:38:33 volumiotop volumio[7257]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 25 10:38:33 volumiotop volumio[7257]: info: MPD running with PID7377 Nov 25 10:38:33 volumiotop volumio[7257]: ,establishing connection Nov 25 10:38:33 volumiotop volumio[7257]: error: MPD error: The expression evaluated to a falsy value: Nov 25 10:38:33 volumiotop volumio[7257]: assert.ok(self.idling) Nov 25 10:38:33 volumiotop volumio[7257]: error: The expression evaluated to a falsy value: Nov 25 10:38:33 volumiotop volumio[7257]: assert.ok(self.idling) Nov 25 10:38:33 volumiotop volumio[7257]: error: MPD error: The expression evaluated to a falsy value: Nov 25 10:38:33 volumiotop volumio[7257]: assert.ok(self.idling) Nov 25 10:38:33 volumiotop volumio[7257]: error: The expression evaluated to a falsy value: Nov 25 10:38:33 volumiotop volumio[7257]: assert.ok(self.idling) Nov 25 10:38:33 volumiotop volumio[7257]: error: updateQueue error: null Nov 25 10:38:38 volumiotop volumio[7257]: Cannot compose Albumart path Nov 25 10:38:40 volumiotop volumio[7257]: Searching all installed plugins Nov 25 10:38:40 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 10:38:40 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 10:38:40 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 10:38:40 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 10:38:40 volumiotop volumio[7257]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 10:38:40 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 10:38:40 volumiotop volumio[7257]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 10:38:40 volumiotop volumio[7257]: info: [1732527520800] ControllerCalmRadio::searchCategories Nov 25 10:38:40 volumiotop volumio[7257]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:38:40 volumiotop volumio[7257]: TypeError: Cannot read property 'cats' of undefined Nov 25 10:38:40 volumiotop volumio[7257]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 10:38:40 volumiotop volumio[7257]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 10:38:40 volumiotop volumio[7257]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 10:38:40 volumiotop volumio[7257]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 10:38:40 volumiotop volumio[7257]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 10:38:40 volumiotop volumio[7257]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 10:38:40 volumiotop volumio[7257]: at Socket.emit (events.js:315:20) Nov 25 10:38:40 volumiotop volumio[7257]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 10:38:40 volumiotop volumio[7257]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 10:38:40 volumiotop volumio[7257]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 10:38:41 volumiotop sudo[7479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 10:37 Nov 25 10:38:41 volumiotop sudo[7479]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"