-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2024-11-25 12:31:36 CET. -- Nov 25 12:30:26 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 12:30:26 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 12:30:26 volumiotop volumio[8493]: info: Discovery: Getting this device information Nov 25 12:30:26 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:26 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 12:30:34 volumiotop sudo[13452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 12:30:34 volumiotop sudo[13452]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:34 volumiotop sudo[13452]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:34 volumiotop sudo[13455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 12:30:34 volumiotop sudo[13455]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:34 volumiotop sudo[13455]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:34 volumiotop volumio[8493]: 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 12:30:34 volumiotop sudo[13458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 12:30:34 volumiotop sudo[13458]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:34 volumiotop sudo[13458]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:34 volumiotop sudo[13461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 12:30:34 volumiotop sudo[13461]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:34 volumiotop sudo[13461]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:34 volumiotop volumio[8493]: 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: 7 Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetQueue Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreStateMachine::getQueue Nov 25 12:30:35 volumiotop volumio[8493]: info: CorePlayQueue::getQueue Nov 25 12:30:35 volumiotop volumio[8493]: info: Listing playlists Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 12:30:35 volumiotop volumio[8493]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 25 12:30:35 volumiotop volumio[8493]: info: Received Get System Info Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 12:30:35 volumiotop volumio[8493]: info: Discovery: Getting this device information Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 25 12:30:35 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 25 12:30:36 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 12:30:36 volumiotop volumio[8493]: info: Received Get System Info Nov 25 12:30:36 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 12:30:36 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 12:30:36 volumiotop volumio[8493]: info: Discovery: Getting this device information Nov 25 12:30:36 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:36 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 12:30:37 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 25 12:30:38 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 25 12:30:38 volumiotop volumio[8493]: info: Received Get System Info Nov 25 12:30:38 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 12:30:38 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 12:30:38 volumiotop volumio[8493]: info: Discovery: Getting this device information Nov 25 12:30:38 volumiotop volumio[8493]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:38 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 12:30:44 volumiotop volumio[8493]: Searching all installed plugins Nov 25 12:30:44 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:44 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 12:30:44 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 12:30:44 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 12:30:44 volumiotop volumio[8493]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 12:30:44 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 12:30:44 volumiotop volumio[8493]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 12:30:44 volumiotop volumio[8493]: info: [1732534244647] ControllerCalmRadio::searchCategories Nov 25 12:30:44 volumiotop volumio[8493]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:30:44 volumiotop volumio[8493]: TypeError: Cannot read property 'cats' of undefined Nov 25 12:30:44 volumiotop volumio[8493]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 12:30:44 volumiotop volumio[8493]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 12:30:44 volumiotop volumio[8493]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 12:30:44 volumiotop volumio[8493]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 12:30:44 volumiotop volumio[8493]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 12:30:44 volumiotop volumio[8493]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 12:30:44 volumiotop volumio[8493]: at Socket.emit (events.js:315:20) Nov 25 12:30:44 volumiotop volumio[8493]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 12:30:44 volumiotop volumio[8493]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 12:30:44 volumiotop volumio[8493]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:30:45 volumiotop sudo[13507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 12:29 Nov 25 12:30:45 volumiotop sudo[13507]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:45 volumiotop sudo[13507]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:45 volumiotop volumio-remote-updater[675]: [2024-11-25 12:30:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 12:30:45 volumiotop volumio-remote-updater[675]: [2024-11-25 12:30:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 12:30:45 volumiotop vtcs[8816]: [2024-11-25 12:30:45.412] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 25 12:30:45 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 12:30:45 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 12:30:45 volumiotop systemd[1]: Started dynamicswap service. Nov 25 12:30:45 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 12:30:45 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 12:30:45 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Nov 25 12:30:45 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 12:30:45 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 12:30:45 volumiotop systemd[1]: Started dynamicswap service. Nov 25 12:30:45 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 12:30:47 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:47 volumiotop volumio[13519]: info: ----- Volumio3 ---- Nov 25 12:30:47 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:47 volumiotop volumio[13519]: info: ----- System startup ---- Nov 25 12:30:47 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:48 volumiotop volumio[13519]: info: MYVOLUMIO Environment detected Nov 25 12:30:48 volumiotop volumio[13519]: info: Plugin folders cleanup Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning into folder /volumio/app/plugins/ Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category audio_interface Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category miscellanea Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category music_service Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category plugins.json Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category system_controller Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category user_interface Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning into folder /data/plugins/ Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category music_service Nov 25 12:30:48 volumiotop volumio[13519]: info: Scanning category system_hardware Nov 25 12:30:48 volumiotop volumio[13519]: info: Plugin folders cleanup completed Nov 25 12:30:48 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:48 volumiotop volumio[13519]: info: ----- Core plugins startup ---- Nov 25 12:30:48 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:48 volumiotop volumio[13519]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 12:30:48 volumiotop volumio[13519]: info: Adding plugin upnp to MyMusic Plugins Nov 25 12:30:48 volumiotop volumio[13519]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 12:30:48 volumiotop volumio[13519]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 12:30:48 volumiotop volumio[13519]: info: Loading plugins from folder /data/plugins/ Nov 25 12:30:48 volumiotop volumio[13519]: info: Loading plugin "system"... Nov 25 12:30:48 volumiotop volumio[13519]: info: Loading plugin "appearance"... Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "network"... Nov 25 12:30:49 volumiotop volumio[13519]: info: Refreshing Cached IP Addresses Nov 25 12:30:49 volumiotop sudo[13546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 12:30:49 volumiotop sudo[13546]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:49 volumiotop sudo[13546]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:49 volumiotop sudo[13548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 12:30:49 volumiotop sudo[13548]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "services"... Nov 25 12:30:49 volumiotop sudo[13548]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "alsa_controller"... Nov 25 12:30:49 volumiotop sudo[13551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 12:30:49 volumiotop sudo[13551]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:49 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "wizard"... Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "networkfs"... Nov 25 12:30:49 volumiotop volumio[13519]: info: Starting Udev Watcher for removable devices Nov 25 12:30:49 volumiotop volumio[13519]: info: Ignoring mount for partition: boot Nov 25 12:30:49 volumiotop volumio[13519]: info: Ignoring mount for partition: volumio Nov 25 12:30:49 volumiotop volumio[13519]: info: Ignoring mount for partition: volumio_data Nov 25 12:30:49 volumiotop volumio[13519]: info: Mounting Device 8B0D-6686 Nov 25 12:30:49 volumiotop sudo[13562]: 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 12:30:49 volumiotop sudo[13562]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:49 volumiotop sudo[13562]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:49 volumiotop volumio[13519]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 12:30:49 volumiotop volumio[13519]: 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 12:30:49 volumiotop volumio[13519]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 12:30:49 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "volumio_command_line_client"... Nov 25 12:30:49 volumiotop volumio[13519]: info: Plugin upnp is not enabled Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "my_music"... Nov 25 12:30:49 volumiotop volumio[13519]: info: Loading plugin "mpd"... Nov 25 12:30:50 volumiotop volumio[13519]: info: Plugin upnp_browser is not enabled Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "alarm-clock"... Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "airplay_emulation"... Nov 25 12:30:50 volumiotop volumio[13519]: info: Starting Shairport Sync Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "last_100"... Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "webradio"... Nov 25 12:30:50 volumiotop sudo[13551]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "i2s_dacs"... Nov 25 12:30:50 volumiotop volumio[13519]: info: I2S DAC not set, start Auto-detection Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "volumiodiscovery"... Nov 25 12:30:50 volumiotop volumio-remote-updater[675]: [2024-11-25 12:30:50] [connect] Successful connection Nov 25 12:30:50 volumiotop volumio[13519]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 12:30:50 volumiotop volumio[13519]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:30:50 volumiotop volumio[13519]: *** WARNING *** For more information see Nov 25 12:30:50 volumiotop node[13519]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 12:30:50 volumiotop volumio[13519]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 12:30:50 volumiotop volumio[13519]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:30:50 volumiotop volumio[13519]: *** WARNING *** For more information see Nov 25 12:30:50 volumiotop node[13519]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:30:50 volumiotop node[13519]: *** WARNING *** For more information see Nov 25 12:30:50 volumiotop node[13519]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 12:30:50 volumiotop node[13519]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:30:50 volumiotop node[13519]: *** WARNING *** For more information see Nov 25 12:30:50 volumiotop volumio[13519]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 12:30:50 volumiotop volumio[13519]: info: Discovery: Started advertising with name: Volumiotop Nov 25 12:30:50 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 12:30:50 volumiotop volumio[13519]: info: Loading plugin "calmradio"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "outputs"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "albumart"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Plugin example_plugin is not enabled Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "inputs"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "updater_comm"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Plugin mpdemulation is not enabled Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "rest_api"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "websocket"... Nov 25 12:30:51 volumiotop volumio[13519]: info: Starting Socket.io Server version 2.3.0 Nov 25 12:30:51 volumiotop volumio[13519]: info: Loading plugin "motherearthradio"... Nov 25 12:30:51 volumiotop volumio[13519]: Forking 3 albumart workers Nov 25 12:30:52 volumiotop volumio[13519]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 12:30:52 volumiotop volumio[13519]: info: [1732534252044] [MotherEarth] API delay: 5 Nov 25 12:30:52 volumiotop volumio[13519]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 12:30:52 volumiotop volumio[13519]: info: Loading i18n strings for locale fr Nov 25 12:30:52 volumiotop volumio[13519]: Updating browse sources language Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::initPlayerControls Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: Express server listening on port 3000 Nov 25 12:30:52 volumiotop volumio[13519]: [Metrics] WebUI: 5s 464.95ms Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::resetVolumioState Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::getcurrentVolume Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:52 volumiotop volumio[13519]: 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 12:30:52 volumiotop volumio-remote-updater[675]: [2024-11-25 12:30:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732534250 101 Nov 25 12:30:52 volumiotop volumio[13519]: 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 12:30:52 volumiotop volumio[13519]: info: Reloading queue from file Nov 25 12:30:52 volumiotop volumio[13519]: info: Setting Device type: Raspberry PI Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::setRepeat null single undefined Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::pushState Nov 25 12:30:52 volumiotop volumio[13519]: info: CorePlayQueue::getTrack 0 Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::volumioPushState Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::setRandom true Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::pushState Nov 25 12:30:52 volumiotop volumio[13519]: info: CorePlayQueue::getTrack 0 Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::volumioPushState Nov 25 12:30:52 volumiotop volumio[13519]: info: VolumeController:: Volume=69 Mute =false Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::pushState Nov 25 12:30:52 volumiotop volumio[13519]: info: CorePlayQueue::getTrack 0 Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::volumioPushState Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreStateMachine::updateTrackBlock Nov 25 12:30:52 volumiotop volumio[13519]: info: CorePlayQueue::getTrackBlock Nov 25 12:30:52 volumiotop volumio[13519]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 12:30:52 volumiotop volumio[13519]: info: Completed loading Core Plugins Nov 25 12:30:52 volumiotop volumio[13519]: info: Preparing to generate the ALSA configuration file Nov 25 12:30:52 volumiotop volumio[13519]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 12:30:52 volumiotop volumio[13519]: info: Reading ALSA contributions from plugins. Nov 25 12:30:52 volumiotop volumio[13519]: info: Asound.conf file unchanged, so no further update is needed Nov 25 12:30:52 volumiotop volumio[13519]: info: Output device has changed, restarting MPD Nov 25 12:30:53 volumiotop volumio[13519]: info: Output device has changed, restarting Shairport Sync Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:53 volumiotop sudo[13607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 12:30:53 volumiotop sudo[13607]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:53 volumiotop sudo[13607]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:53 volumiotop sudo[13610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 12:30:53 volumiotop sudo[13610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:53 volumiotop volumio[13519]: Starting albumart workers Nov 25 12:30:53 volumiotop volumio[13519]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:30:53 volumiotop volumio[13519]: Starting albumart workers Nov 25 12:30:53 volumiotop volumio[13519]: info: ___________ START PLUGINS ___________ Nov 25 12:30:53 volumiotop volumio[13519]: info: ControllerMpd::onStart: Initializing MPD Nov 25 12:30:53 volumiotop volumio[13519]: info: Creating MPD Configuration file Nov 25 12:30:53 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 12:30:53 volumiotop sudo[13618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 12:30:53 volumiotop sudo[13618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:53 volumiotop sudo[13618]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:53 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 12:30:53 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 12:30:53 volumiotop sudo[13620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 12:30:53 volumiotop sudo[13620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:53 volumiotop volumio[13519]: Starting albumart workers Nov 25 12:30:53 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 12:30:53 volumiotop volumio[13519]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:53 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 12:30:53 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 12:30:53 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 12:30:53 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 12:30:53 volumiotop volumio[13519]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:30:53 volumiotop volumio[13519]: info: [1732534253369] CoreMusicLibrary::Adding element Last_100 Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:30:53 volumiotop volumio[13519]: info: [1732534253417] CoreMusicLibrary::Adding element Webradio Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:30:53 volumiotop volumio[13519]: info: Initializing BBC Radios Nov 25 12:30:53 volumiotop sudo[13629]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 12:30:53 volumiotop sudo[13629]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:53 volumiotop sudo[13629]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:53 volumiotop volumio[13519]: info: Adding Calm Radio to Browse Sources Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:30:53 volumiotop volumio[13519]: info: [1732534253672] CoreMusicLibrary::Adding element Calm Radio Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:53 volumiotop volumio[13519]: Cannot find translation for source Calm Radio Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:30:53 volumiotop volumio[13519]: info: [1732534253709] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 12:30:53 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:53 volumiotop volumio[13519]: Cannot find translation for source Calm Radio Nov 25 12:30:53 volumiotop volumio[13519]: Cannot find translation for source Mother Earth Radio Nov 25 12:30:53 volumiotop volumio[13519]: info: Volumio Calling Home Nov 25 12:30:54 volumiotop volumio[13519]: 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 12:30:54 volumiotop volumio[13519]: info: MPD Permissions set Nov 25 12:30:54 volumiotop volumio[13519]: info: MPD Permissions set Nov 25 12:30:54 volumiotop volumio[13519]: info: VolumeController:: Volume=69 Mute =false Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreStateMachine::pushState Nov 25 12:30:54 volumiotop volumio[13519]: info: CorePlayQueue::getTrack 0 Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::volumioPushState Nov 25 12:30:54 volumiotop volumio[13519]: info: Volumio called home Nov 25 12:30:54 volumiotop volumio[13519]: 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 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:30:54 volumiotop volumio[13519]: info: Starting Shairport Sync Nov 25 12:30:54 volumiotop volumio[13519]: info: Starting Shairport Sync Nov 25 12:30:54 volumiotop sudo[13678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 12:30:54 volumiotop sudo[13678]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:54 volumiotop volumio[13519]: info: Starting Shairport Sync Nov 25 12:30:54 volumiotop sudo[13680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 12:30:54 volumiotop sudo[13680]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:54 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 12:30:54 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 12:30:54 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 12:30:54 volumiotop volumio[13519]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:54 volumiotop volumio[13519]: info: CorePlayQueue::getTrack 0 Nov 25 12:30:54 volumiotop sudo[13684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 12:30:54 volumiotop sudo[13684]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:54 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 12:30:54 volumiotop sudo[13678]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:54 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 12:30:54 volumiotop systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 25 12:30:54 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 12:30:54 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 12:30:54 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 12:30:54 volumiotop volumio[13519]: info: Shairport-Sync Started Nov 25 12:30:54 volumiotop sudo[13684]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:54 volumiotop sudo[13680]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:54 volumiotop volumio[13519]: Error adding Membership: Error: addMembership EINVAL Nov 25 12:30:54 volumiotop volumio[13519]: info: Shairport-Sync Started Nov 25 12:30:54 volumiotop volumio[13519]: info: Shairport-Sync Started Nov 25 12:30:55 volumiotop mpd[13633]: Nov 25 12:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 12:30:55 volumiotop systemd[1]: Started Music Player Daemon. Nov 25 12:30:55 volumiotop sudo[13610]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:55 volumiotop sudo[13620]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:55 volumiotop volumio[13519]: info: Completed starting Core Plugins Nov 25 12:30:55 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:55 volumiotop volumio[13519]: info: ----- MyVolumio plugins startup ---- Nov 25 12:30:55 volumiotop volumio[13519]: info: ------------------------------------------- Nov 25 12:30:55 volumiotop volumio[13519]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 25 12:30:55 volumiotop volumio[13519]: error: MPD error: The expression evaluated to a falsy value: Nov 25 12:30:55 volumiotop volumio[13519]: assert.ok(self.idling) Nov 25 12:30:55 volumiotop volumio[13519]: error: The expression evaluated to a falsy value: Nov 25 12:30:55 volumiotop volumio[13519]: assert.ok(self.idling) Nov 25 12:30:55 volumiotop volumio[13519]: info: MPD running with PID13633 Nov 25 12:30:55 volumiotop volumio[13519]: ,establishing connection Nov 25 12:30:55 volumiotop volumio[13519]: error: MPD error: The expression evaluated to a falsy value: Nov 25 12:30:55 volumiotop volumio[13519]: assert.ok(self.idling) Nov 25 12:30:55 volumiotop volumio[13519]: error: The expression evaluated to a falsy value: Nov 25 12:30:55 volumiotop volumio[13519]: assert.ok(self.idling) Nov 25 12:30:55 volumiotop volumio[13519]: error: updateQueue error: null Nov 25 12:30:57 volumiotop volumio[13519]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 12:30:57 volumiotop volumio[13519]: info: Discovery: Found device Volumiotop Nov 25 12:30:57 volumiotop volumio[13519]: info: CoreCommandRouter::volumioGetState Nov 25 12:30:57 volumiotop volumio[13519]: info: CorePlayQueue::getTrack 0 Nov 25 12:30:58 volumiotop volumio[13519]: 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 12:30:58 volumiotop volumio[13519]: Searching all installed plugins Nov 25 12:30:58 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:30:58 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 12:30:58 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 12:30:58 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 12:30:58 volumiotop volumio[13519]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 12:30:58 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 12:30:58 volumiotop volumio[13519]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 12:30:58 volumiotop volumio[13519]: info: [1732534258890] ControllerCalmRadio::searchCategories Nov 25 12:30:58 volumiotop volumio[13519]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:30:58 volumiotop volumio[13519]: TypeError: Cannot read property 'cats' of undefined Nov 25 12:30:58 volumiotop volumio[13519]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 12:30:58 volumiotop volumio[13519]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 12:30:58 volumiotop volumio[13519]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 12:30:58 volumiotop volumio[13519]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 12:30:58 volumiotop volumio[13519]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 12:30:58 volumiotop volumio[13519]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 12:30:58 volumiotop volumio[13519]: at Socket.emit (events.js:315:20) Nov 25 12:30:58 volumiotop volumio[13519]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 12:30:58 volumiotop volumio[13519]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 12:30:58 volumiotop volumio[13519]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:30:59 volumiotop sudo[13705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 12:29 Nov 25 12:30:59 volumiotop sudo[13705]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:30:59 volumiotop sudo[13705]: pam_unix(sudo:session): session closed for user root Nov 25 12:30:59 volumiotop volumio-remote-updater[675]: [2024-11-25 12:30:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 12:30:59 volumiotop volumio-remote-updater[675]: [2024-11-25 12:30:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 12:30:59 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 12:30:59 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 12:30:59 volumiotop systemd[1]: Started dynamicswap service. Nov 25 12:30:59 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 12:30:59 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 12:30:59 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Nov 25 12:30:59 volumiotop systemd[1]: Started dynamicswap service. Nov 25 12:30:59 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 12:30:59 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 12:30:59 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 12:31:01 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:01 volumiotop volumio[13719]: info: ----- Volumio3 ---- Nov 25 12:31:01 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:01 volumiotop volumio[13719]: info: ----- System startup ---- Nov 25 12:31:01 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:02 volumiotop volumio[13719]: info: MYVOLUMIO Environment detected Nov 25 12:31:02 volumiotop volumio[13719]: info: Plugin folders cleanup Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning into folder /volumio/app/plugins/ Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category audio_interface Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category miscellanea Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category music_service Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category plugins.json Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category system_controller Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category user_interface Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning into folder /data/plugins/ Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category music_service Nov 25 12:31:02 volumiotop volumio[13719]: info: Scanning category system_hardware Nov 25 12:31:02 volumiotop volumio[13719]: info: Plugin folders cleanup completed Nov 25 12:31:02 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:02 volumiotop volumio[13719]: info: ----- Core plugins startup ---- Nov 25 12:31:02 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:02 volumiotop volumio[13719]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 12:31:02 volumiotop volumio[13719]: info: Adding plugin upnp to MyMusic Plugins Nov 25 12:31:02 volumiotop volumio[13719]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 12:31:02 volumiotop volumio[13719]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 12:31:02 volumiotop volumio[13719]: info: Loading plugins from folder /data/plugins/ Nov 25 12:31:02 volumiotop volumio[13719]: info: Loading plugin "system"... Nov 25 12:31:02 volumiotop volumio[13719]: info: Loading plugin "appearance"... Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "network"... Nov 25 12:31:03 volumiotop volumio[13719]: info: Refreshing Cached IP Addresses Nov 25 12:31:03 volumiotop sudo[13745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 12:31:03 volumiotop sudo[13745]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:03 volumiotop sudo[13745]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "services"... Nov 25 12:31:03 volumiotop sudo[13747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 12:31:03 volumiotop sudo[13747]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "alsa_controller"... Nov 25 12:31:03 volumiotop sudo[13747]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:03 volumiotop sudo[13750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 12:31:03 volumiotop sudo[13750]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:03 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "wizard"... Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "networkfs"... Nov 25 12:31:03 volumiotop volumio[13719]: info: Starting Udev Watcher for removable devices Nov 25 12:31:03 volumiotop volumio[13719]: info: Ignoring mount for partition: boot Nov 25 12:31:03 volumiotop volumio[13719]: info: Ignoring mount for partition: volumio Nov 25 12:31:03 volumiotop volumio[13719]: info: Ignoring mount for partition: volumio_data Nov 25 12:31:03 volumiotop volumio[13719]: info: Mounting Device 8B0D-6686 Nov 25 12:31:03 volumiotop sudo[13761]: 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 12:31:03 volumiotop sudo[13761]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:03 volumiotop sudo[13761]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:03 volumiotop volumio[13719]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 12:31:03 volumiotop volumio[13719]: 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 12:31:03 volumiotop volumio[13719]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 12:31:03 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "volumio_command_line_client"... Nov 25 12:31:03 volumiotop volumio[13719]: info: Plugin upnp is not enabled Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "my_music"... Nov 25 12:31:03 volumiotop volumio[13719]: info: Loading plugin "mpd"... Nov 25 12:31:04 volumiotop volumio[13719]: info: Plugin upnp_browser is not enabled Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "alarm-clock"... Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "airplay_emulation"... Nov 25 12:31:04 volumiotop volumio[13719]: info: Starting Shairport Sync Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "last_100"... Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "webradio"... Nov 25 12:31:04 volumiotop sudo[13750]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:04 volumiotop volumio-remote-updater[675]: [2024-11-25 12:31:04] [connect] Successful connection Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "i2s_dacs"... Nov 25 12:31:04 volumiotop volumio[13719]: info: I2S DAC not set, start Auto-detection Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "volumiodiscovery"... Nov 25 12:31:04 volumiotop volumio[13719]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:04 volumiotop volumio[13719]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:04 volumiotop volumio[13719]: *** WARNING *** For more information see Nov 25 12:31:04 volumiotop node[13719]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:04 volumiotop volumio[13719]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:04 volumiotop volumio[13719]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:04 volumiotop volumio[13719]: *** WARNING *** For more information see Nov 25 12:31:04 volumiotop node[13719]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:04 volumiotop node[13719]: *** WARNING *** For more information see Nov 25 12:31:04 volumiotop node[13719]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:04 volumiotop node[13719]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:04 volumiotop node[13719]: *** WARNING *** For more information see Nov 25 12:31:04 volumiotop volumio[13719]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 12:31:04 volumiotop volumio[13719]: info: Discovery: Started advertising with name: Volumiotop Nov 25 12:31:04 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 12:31:04 volumiotop volumio[13719]: info: Loading plugin "calmradio"... Nov 25 12:31:05 volumiotop volumio[13719]: info: Loading plugin "outputs"... Nov 25 12:31:05 volumiotop volumio[13719]: info: Loading plugin "albumart"... Nov 25 12:31:05 volumiotop volumio[13719]: info: Plugin example_plugin is not enabled Nov 25 12:31:05 volumiotop volumio[13719]: info: Loading plugin "inputs"... Nov 25 12:31:05 volumiotop volumio[13719]: info: Loading plugin "updater_comm"... Nov 25 12:31:05 volumiotop volumio[13719]: info: Plugin mpdemulation is not enabled Nov 25 12:31:05 volumiotop volumio[13719]: info: Loading plugin "rest_api"... Nov 25 12:31:06 volumiotop volumio[13719]: info: Loading plugin "websocket"... Nov 25 12:31:06 volumiotop volumio[13719]: info: Starting Socket.io Server version 2.3.0 Nov 25 12:31:06 volumiotop volumio[13719]: info: Loading plugin "motherearthradio"... Nov 25 12:31:06 volumiotop volumio[13719]: Forking 3 albumart workers Nov 25 12:31:06 volumiotop volumio[13719]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 12:31:06 volumiotop volumio[13719]: info: [1732534266280] [MotherEarth] API delay: 5 Nov 25 12:31:06 volumiotop volumio[13719]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 12:31:06 volumiotop volumio[13719]: info: Loading i18n strings for locale fr Nov 25 12:31:06 volumiotop volumio[13719]: Updating browse sources language Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::initPlayerControls Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: Express server listening on port 3000 Nov 25 12:31:06 volumiotop volumio[13719]: [Metrics] WebUI: 5s 405.63ms Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreStateMachine::resetVolumioState Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreStateMachine::getcurrentVolume Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:06 volumiotop volumio[13719]: 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 12:31:06 volumiotop volumio[13719]: 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 12:31:06 volumiotop volumio-remote-updater[675]: [2024-11-25 12:31:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732534264 101 Nov 25 12:31:06 volumiotop volumio[13719]: 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 12:31:06 volumiotop volumio[13719]: 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 12:31:06 volumiotop volumio[13719]: info: Reloading queue from file Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreStateMachine::setRepeat null single undefined Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreStateMachine::pushState Nov 25 12:31:06 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:06 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreStateMachine::setRandom true Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreStateMachine::pushState Nov 25 12:31:07 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:07 volumiotop volumio[13719]: info: Setting Device type: Raspberry PI Nov 25 12:31:07 volumiotop volumio[13719]: info: VolumeController:: Volume=69 Mute =false Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreStateMachine::pushState Nov 25 12:31:07 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreStateMachine::updateTrackBlock Nov 25 12:31:07 volumiotop volumio[13719]: info: CorePlayQueue::getTrackBlock Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 12:31:07 volumiotop volumio[13719]: info: Completed loading Core Plugins Nov 25 12:31:07 volumiotop volumio[13719]: info: Preparing to generate the ALSA configuration file Nov 25 12:31:07 volumiotop volumio[13719]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 12:31:07 volumiotop volumio[13719]: info: Discovery: Found device Volumiotop Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetState Nov 25 12:31:07 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:07 volumiotop volumio[13719]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 12:31:07 volumiotop volumio[13719]: info: Reading ALSA contributions from plugins. Nov 25 12:31:07 volumiotop volumio[13719]: info: Asound.conf file unchanged, so no further update is needed Nov 25 12:31:07 volumiotop volumio[13719]: info: Output device has changed, restarting MPD Nov 25 12:31:07 volumiotop volumio[13719]: Starting albumart workers Nov 25 12:31:07 volumiotop volumio[13719]: info: Output device has changed, restarting Shairport Sync Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:07 volumiotop volumio[13719]: Starting albumart workers Nov 25 12:31:07 volumiotop volumio[13719]: Starting albumart workers Nov 25 12:31:07 volumiotop sudo[13807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 12:31:07 volumiotop sudo[13807]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:07 volumiotop sudo[13809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 12:31:07 volumiotop sudo[13809]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:07 volumiotop sudo[13807]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:07 volumiotop volumio[13719]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:31:07 volumiotop volumio[13719]: info: ___________ START PLUGINS ___________ Nov 25 12:31:07 volumiotop volumio[13719]: info: ControllerMpd::onStart: Initializing MPD Nov 25 12:31:07 volumiotop volumio[13719]: info: Creating MPD Configuration file Nov 25 12:31:07 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:07 volumiotop sudo[13816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 12:31:07 volumiotop sudo[13816]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:07 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 12:31:07 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 12:31:07 volumiotop sudo[13817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 12:31:07 volumiotop sudo[13817]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:07 volumiotop sudo[13816]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:07 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 12:31:07 volumiotop volumio[13719]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:07 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 12:31:07 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 12:31:07 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 12:31:07 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 12:31:07 volumiotop volumio[13719]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:07 volumiotop volumio[13719]: info: [1732534267630] CoreMusicLibrary::Adding element Last_100 Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:07 volumiotop volumio[13719]: info: [1732534267637] CoreMusicLibrary::Adding element Webradio Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:31:07 volumiotop volumio[13719]: info: Initializing BBC Radios Nov 25 12:31:07 volumiotop sudo[13824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 12:31:07 volumiotop sudo[13824]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:07 volumiotop sudo[13824]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:07 volumiotop volumio[13719]: info: Adding Calm Radio to Browse Sources Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:07 volumiotop volumio[13719]: info: [1732534267878] CoreMusicLibrary::Adding element Calm Radio Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:07 volumiotop volumio[13719]: Cannot find translation for source Calm Radio Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:07 volumiotop volumio[13719]: info: [1732534267901] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 12:31:07 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:07 volumiotop volumio[13719]: Cannot find translation for source Calm Radio Nov 25 12:31:07 volumiotop volumio[13719]: Cannot find translation for source Mother Earth Radio Nov 25 12:31:07 volumiotop volumio[13719]: info: Volumio Calling Home Nov 25 12:31:08 volumiotop volumio[13719]: info: MPD Permissions set Nov 25 12:31:08 volumiotop volumio[13719]: info: MPD Permissions set Nov 25 12:31:08 volumiotop volumio[13719]: info: VolumeController:: Volume=69 Mute =false Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreStateMachine::pushState Nov 25 12:31:08 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:08 volumiotop volumio[13719]: info: Volumio called home Nov 25 12:31:08 volumiotop volumio[13719]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:08 volumiotop volumio[13719]: info: Starting Shairport Sync Nov 25 12:31:08 volumiotop volumio[13719]: info: Starting Shairport Sync Nov 25 12:31:08 volumiotop volumio[13719]: info: Starting Shairport Sync Nov 25 12:31:08 volumiotop sudo[13876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 12:31:08 volumiotop sudo[13876]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:08 volumiotop sudo[13878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 12:31:08 volumiotop sudo[13878]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:08 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetState Nov 25 12:31:08 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:08 volumiotop sudo[13882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 25 12:31:08 volumiotop sudo[13882]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:08 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 12:31:08 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 12:31:08 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 12:31:08 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 12:31:08 volumiotop sudo[13876]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:08 volumiotop volumio[13719]: info: Shairport-Sync Started Nov 25 12:31:08 volumiotop volumio[13719]: Error adding Membership: Error: addMembership EINVAL Nov 25 12:31:08 volumiotop sudo[13878]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:08 volumiotop volumio[13719]: info: Shairport-Sync Started Nov 25 12:31:08 volumiotop systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 25 12:31:08 volumiotop systemd[1]: shairport-sync.service: Succeeded. Nov 25 12:31:08 volumiotop systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 25 12:31:08 volumiotop systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 25 12:31:08 volumiotop sudo[13882]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:08 volumiotop volumio[13719]: info: Shairport-Sync Started Nov 25 12:31:09 volumiotop mpd[13831]: Nov 25 12:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 25 12:31:09 volumiotop systemd[1]: Started Music Player Daemon. Nov 25 12:31:09 volumiotop sudo[13809]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:09 volumiotop sudo[13817]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:09 volumiotop volumio[13719]: info: Completed starting Core Plugins Nov 25 12:31:09 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:09 volumiotop volumio[13719]: info: ----- MyVolumio plugins startup ---- Nov 25 12:31:09 volumiotop volumio[13719]: info: ------------------------------------------- Nov 25 12:31:09 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 25 12:31:09 volumiotop volumio[13719]: error: MPD error: The expression evaluated to a falsy value: Nov 25 12:31:09 volumiotop volumio[13719]: assert.ok(self.idling) Nov 25 12:31:09 volumiotop volumio[13719]: error: The expression evaluated to a falsy value: Nov 25 12:31:09 volumiotop volumio[13719]: assert.ok(self.idling) Nov 25 12:31:09 volumiotop volumio[13719]: info: MPD running with PID13831 Nov 25 12:31:09 volumiotop volumio[13719]: ,establishing connection Nov 25 12:31:09 volumiotop volumio[13719]: error: updateQueue error: null Nov 25 12:31:09 volumiotop volumio[13719]: error: updateQueue error: null Nov 25 12:31:11 volumiotop volumio[13719]: 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 12:31:11 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetVisibleSources Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetState Nov 25 12:31:12 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetQueue Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreStateMachine::getQueue Nov 25 12:31:12 volumiotop volumio[13719]: info: CorePlayQueue::getQueue Nov 25 12:31:12 volumiotop volumio[13719]: info: Listing playlists Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 25 12:31:12 volumiotop volumio[13719]: info: Received Get System Info Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 25 12:31:12 volumiotop volumio[13719]: info: Discovery: Getting this device information Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetState Nov 25 12:31:12 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 25 12:31:12 volumiotop volumio[13719]: info: CoreCommandRouter::volumioGetState Nov 25 12:31:12 volumiotop volumio[13719]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:15 volumiotop volumio[13719]: Cannot compose Albumart path Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 25 12:31:18 volumiotop volumio[13719]: info: Adding plugin bluetooth to MyMusic Plugins Nov 25 12:31:18 volumiotop volumio[13719]: info: Adding plugin multiroom to MyMusic Plugins Nov 25 12:31:18 volumiotop volumio[13719]: info: Adding plugin metavolumio to MyMusic Plugins Nov 25 12:31:18 volumiotop volumio[13719]: info: Adding plugin cd_controller to MyMusic Plugins Nov 25 12:31:18 volumiotop volumio[13719]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 25 12:31:18 volumiotop volumio[13719]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 25 12:31:18 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 25 12:31:19 volumiotop volumio[13719]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 25 12:31:19 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 25 12:31:19 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:19 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:19 volumiotop volumio[13719]: info: Starting MyVolumio Remote Streaming Endpoints Nov 25 12:31:19 volumiotop volumio[13719]: info: MyVolumio login type: Token Nov 25 12:31:19 volumiotop volumio[13719]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 25 12:31:19 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 25 12:31:20 volumiotop volumio[13719]: info: Starting Streaming Service Transparent Proxy Nov 25 12:31:20 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 25 12:31:20 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 25 12:31:20 volumiotop volumio[13719]: info: Streaming services startup Nov 25 12:31:20 volumiotop volumio[13719]: info: Starting Streaming Daemon Nov 25 12:31:20 volumiotop sudo[13904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 25 12:31:20 volumiotop sudo[13904]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:20 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 25 12:31:20 volumiotop sudo[13904]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:20 volumiotop volumio[13719]: error: Cannot start Volumio Streaming Daemon Nov 25 12:31:20 volumiotop volumio[13719]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 25 12:31:20 volumiotop volumio[13719]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 25 12:31:20 volumiotop volumio[13719]: error: MyVolumio Custom Token format not valid, refreshing it Nov 25 12:31:21 volumiotop volumio[13719]: STREAMING PROXY: Starting server on port 3245 Nov 25 12:31:21 volumiotop volumio[13719]: Node JS runtime: 14 Nov 25 12:31:21 volumiotop volumio[13719]: info: MyVolumio login type: Token Nov 25 12:31:22 volumiotop volumio[13719]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 25 12:31:23 volumiotop volumio[13719]: info: MyVolumio token set successfully Nov 25 12:31:23 volumiotop volumio[13719]: info: MYVOLUMIO: Adding device Nov 25 12:31:23 volumiotop volumio[13719]: info: MYVOLUMIO: Evaluating Server Nov 25 12:31:23 volumiotop volumio[13719]: info: MyVolumio Plan changed: superstar Nov 25 12:31:23 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Nov 25 12:31:23 volumiotop volumio[13719]: info: Adding audio output: Nov 25 12:31:23 volumiotop volumio[13719]: info: MYVOLUMIO: Adding device Nov 25 12:31:23 volumiotop volumio[13719]: info: MYVOLUMIO: Evaluating Server Nov 25 12:31:23 volumiotop volumio[13719]: info: Remote config written successfully Nov 25 12:31:23 volumiotop volumio[13719]: info: Starting Tunnel 1 Nov 25 12:31:23 volumiotop volumio[13719]: info: Starting Tunnel Connection Checker Nov 25 12:31:23 volumiotop volumio[13719]: info: Completed starting MyVolumio Plugin Nov 25 12:31:23 volumiotop volumio[13719]: info: MYVolumio Device enabled Nov 25 12:31:23 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 25 12:31:23 volumiotop volumio[13719]: info: MyVolumio status changed Nov 25 12:31:23 volumiotop volumio[13719]: info: Streaming services startup Nov 25 12:31:23 volumiotop volumio[13719]: info: Starting Streaming Daemon Nov 25 12:31:23 volumiotop sudo[13945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 25 12:31:23 volumiotop sudo[13945]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:23 volumiotop volumio[13719]: info: Setting Geolocation for MyVolumio to eu7 Nov 25 12:31:23 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:23 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:23 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:24 volumiotop sudo[13945]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:24 volumiotop volumio[13719]: error: Cannot start Volumio Streaming Daemon Nov 25 12:31:24 volumiotop volumio[13719]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 25 12:31:24 volumiotop volumio[13719]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 25 12:31:24 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 25 12:31:24 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 25 12:31:24 volumiotop volumio[13719]: info: [1732534284213] Starting BluetoothController Nov 25 12:31:24 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 25 12:31:25 volumiotop sudo[13948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 25 12:31:25 volumiotop sudo[13948]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:25 volumiotop sudo[13948]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:25 volumiotop volumio[13719]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Nov 25 12:31:25 volumiotop volumio[13719]: info: MRS: MultiRoom plugin initialized Nov 25 12:31:25 volumiotop volumio[13719]: info: MRS: STOPPING SNAPCLIENT Nov 25 12:31:25 volumiotop volumio[13719]: info: MRS: Snap server stop Nov 25 12:31:25 volumiotop sudo[13964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 25 12:31:25 volumiotop sudo[13964]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:25 volumiotop volumio[13719]: info: MRS: STOPPING volumioStreaming Nov 25 12:31:25 volumiotop sudo[13967]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 25 12:31:25 volumiotop sudo[13967]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 25 12:31:25 volumiotop sudo[13970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 25 12:31:25 volumiotop sudo[13970]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:25 volumiotop sudo[13964]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:25 volumiotop sudo[13972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 25 12:31:25 volumiotop sudo[13972]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:25 volumiotop sudo[13967]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:25 volumiotop sudo[13972]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:25 volumiotop sudo[13970]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 25 12:31:25 volumiotop volumio[13719]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 25 12:31:26 volumiotop volumio[13719]: info: Preparing to generate the ALSA configuration file Nov 25 12:31:26 volumiotop volumio[13719]: info: Updating MyVolumio device info Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:26 volumiotop volumio[13719]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 12:31:26 volumiotop volumio[13719]: info: Reading ALSA contributions from plugins. Nov 25 12:31:26 volumiotop volumio[13719]: info: Setting Geolocation for MyVolumio to eu10 Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:26 volumiotop volumio[13719]: Searching all installed plugins Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 25 12:31:26 volumiotop volumio[13719]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 25 12:31:26 volumiotop volumio[13719]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 25 12:31:26 volumiotop volumio[13719]: info: [1732534286132] ControllerCalmRadio::searchCategories Nov 25 12:31:26 volumiotop volumio[13719]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:31:26 volumiotop volumio[13719]: TypeError: Cannot read property 'cats' of undefined Nov 25 12:31:26 volumiotop volumio[13719]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 25 12:31:26 volumiotop volumio[13719]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 25 12:31:26 volumiotop volumio[13719]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 12:31:26 volumiotop volumio[13719]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 12:31:26 volumiotop volumio[13719]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 12:31:26 volumiotop volumio[13719]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 12:31:26 volumiotop volumio[13719]: at Socket.emit (events.js:315:20) Nov 25 12:31:26 volumiotop volumio[13719]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 12:31:26 volumiotop volumio[13719]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 12:31:26 volumiotop volumio[13719]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:31:26 volumiotop sudo[13984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 12:30 Nov 25 12:31:26 volumiotop sudo[13984]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:26 volumiotop sudo[13984]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:26 volumiotop volumio-remote-updater[675]: [2024-11-25 12:31:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 25 12:31:26 volumiotop volumio-remote-updater[675]: [2024-11-25 12:31:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 25 12:31:26 volumiotop systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 25 12:31:26 volumiotop systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 25 12:31:26 volumiotop systemd[1]: Started dynamicswap service. Nov 25 12:31:26 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 12:31:27 volumiotop systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 25 12:31:27 volumiotop systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Nov 25 12:31:27 volumiotop systemd[1]: Stopped Volumio Backend Module. Nov 25 12:31:27 volumiotop systemd[1]: Started Volumio Backend Module. Nov 25 12:31:27 volumiotop systemd[1]: Started dynamicswap service. Nov 25 12:31:27 volumiotop systemd[1]: dynamicswap.service: Succeeded. Nov 25 12:31:28 volumiotop volumio[13994]: info: ------------------------------------------- Nov 25 12:31:28 volumiotop volumio[13994]: info: ----- Volumio3 ---- Nov 25 12:31:28 volumiotop volumio[13994]: info: ------------------------------------------- Nov 25 12:31:28 volumiotop volumio[13994]: info: ----- System startup ---- Nov 25 12:31:28 volumiotop volumio[13994]: info: ------------------------------------------- Nov 25 12:31:29 volumiotop volumio[13994]: info: MYVOLUMIO Environment detected Nov 25 12:31:29 volumiotop volumio[13994]: info: Plugin folders cleanup Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning into folder /volumio/app/plugins/ Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category audio_interface Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category miscellanea Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category music_service Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category plugins.json Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category system_controller Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category user_interface Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning into folder /data/plugins/ Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category music_service Nov 25 12:31:29 volumiotop volumio[13994]: info: Scanning category system_hardware Nov 25 12:31:29 volumiotop volumio[13994]: info: Plugin folders cleanup completed Nov 25 12:31:29 volumiotop volumio[13994]: info: ------------------------------------------- Nov 25 12:31:29 volumiotop volumio[13994]: info: ----- Core plugins startup ---- Nov 25 12:31:29 volumiotop volumio[13994]: info: ------------------------------------------- Nov 25 12:31:29 volumiotop volumio[13994]: info: Loading plugins from folder /volumio/app/plugins/ Nov 25 12:31:29 volumiotop volumio[13994]: info: Adding plugin upnp to MyMusic Plugins Nov 25 12:31:29 volumiotop volumio[13994]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 25 12:31:29 volumiotop volumio[13994]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 25 12:31:29 volumiotop volumio[13994]: info: Loading plugins from folder /data/plugins/ Nov 25 12:31:29 volumiotop volumio[13994]: info: Loading plugin "system"... Nov 25 12:31:29 volumiotop volumio[13994]: info: Loading plugin "appearance"... Nov 25 12:31:30 volumiotop volumio[13994]: info: Loading plugin "network"... Nov 25 12:31:30 volumiotop volumio[13994]: info: Refreshing Cached IP Addresses Nov 25 12:31:30 volumiotop sudo[14021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 25 12:31:30 volumiotop sudo[14021]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:30 volumiotop sudo[14021]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:30 volumiotop sudo[14023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 25 12:31:30 volumiotop sudo[14023]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:30 volumiotop volumio[13994]: info: Loading plugin "services"... Nov 25 12:31:30 volumiotop sudo[14023]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:30 volumiotop volumio[13994]: info: Loading plugin "alsa_controller"... Nov 25 12:31:30 volumiotop sudo[14026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 25 12:31:30 volumiotop sudo[14026]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:31 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "wizard"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "networkfs"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Starting Udev Watcher for removable devices Nov 25 12:31:31 volumiotop volumio[13994]: info: Ignoring mount for partition: boot Nov 25 12:31:31 volumiotop volumio[13994]: info: Ignoring mount for partition: volumio Nov 25 12:31:31 volumiotop volumio[13994]: info: Ignoring mount for partition: volumio_data Nov 25 12:31:31 volumiotop volumio[13994]: info: Mounting Device 8B0D-6686 Nov 25 12:31:31 volumiotop sudo[14037]: 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 12:31:31 volumiotop sudo[14037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:31 volumiotop sudo[14037]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:31 volumiotop volumio[13994]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 12:31:31 volumiotop volumio[13994]: 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 12:31:31 volumiotop volumio[13994]: mount: /media/8B0D-6686: /dev/sda1 already mounted on /media/8B0D-6686. Nov 25 12:31:31 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "volumio_command_line_client"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Plugin upnp is not enabled Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "my_music"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "mpd"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Plugin upnp_browser is not enabled Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "alarm-clock"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "airplay_emulation"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Starting Shairport Sync Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "last_100"... Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "webradio"... Nov 25 12:31:31 volumiotop sudo[14026]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:31 volumiotop volumio-remote-updater[675]: [2024-11-25 12:31:31] [connect] Successful connection Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "i2s_dacs"... Nov 25 12:31:31 volumiotop volumio[13994]: info: I2S DAC not set, start Auto-detection Nov 25 12:31:31 volumiotop volumio[13994]: info: Loading plugin "volumiodiscovery"... Nov 25 12:31:31 volumiotop volumio[13994]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:31 volumiotop node[13994]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:31 volumiotop volumio[13994]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:31 volumiotop node[13994]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:31 volumiotop volumio[13994]: *** WARNING *** For more information see Nov 25 12:31:31 volumiotop node[13994]: *** WARNING *** For more information see Nov 25 12:31:31 volumiotop volumio[13994]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:31 volumiotop node[13994]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 25 12:31:31 volumiotop volumio[13994]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:31 volumiotop node[13994]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 25 12:31:31 volumiotop volumio[13994]: *** WARNING *** For more information see Nov 25 12:31:31 volumiotop node[13994]: *** WARNING *** For more information see Nov 25 12:31:31 volumiotop volumio[13994]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 25 12:31:31 volumiotop volumio[13994]: info: Discovery: Started advertising with name: Volumiotop Nov 25 12:31:32 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 25 12:31:32 volumiotop volumio[13994]: info: Loading plugin "calmradio"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "outputs"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "albumart"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Plugin example_plugin is not enabled Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "inputs"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "updater_comm"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Plugin mpdemulation is not enabled Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "rest_api"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "websocket"... Nov 25 12:31:33 volumiotop volumio[13994]: info: Starting Socket.io Server version 2.3.0 Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading plugin "motherearthradio"... Nov 25 12:31:33 volumiotop volumio[13994]: Forking 3 albumart workers Nov 25 12:31:33 volumiotop volumio[13994]: info: Applying required configuration parameters for plugin motherearthradio Nov 25 12:31:33 volumiotop volumio[13994]: info: [1732534293574] [MotherEarth] API delay: 5 Nov 25 12:31:33 volumiotop volumio[13994]: info: Plugin audiophonics_evo_sabre is not enabled Nov 25 12:31:33 volumiotop volumio[13994]: info: Loading i18n strings for locale fr Nov 25 12:31:33 volumiotop volumio[13994]: Updating browse sources language Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::initPlayerControls Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 25 12:31:33 volumiotop volumio[13994]: Express server listening on port 3000 Nov 25 12:31:33 volumiotop volumio[13994]: [Metrics] WebUI: 5s 489.17ms Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreStateMachine::resetVolumioState Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreStateMachine::getcurrentVolume Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 12:31:33 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:34 volumiotop volumio[13994]: 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 12:31:34 volumiotop volumio-remote-updater[675]: [2024-11-25 12:31:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732534291 101 Nov 25 12:31:34 volumiotop volumio[13994]: 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 12:31:34 volumiotop volumio[13994]: info: Reloading queue from file Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreStateMachine::setRepeat null single undefined Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreStateMachine::pushState Nov 25 12:31:34 volumiotop volumio[13994]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreStateMachine::setRandom true Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreStateMachine::pushState Nov 25 12:31:34 volumiotop volumio[13994]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:34 volumiotop volumio[13994]: info: Setting Device type: Raspberry PI Nov 25 12:31:34 volumiotop volumio[13994]: info: VolumeController:: Volume=69 Mute =false Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreStateMachine::pushState Nov 25 12:31:34 volumiotop volumio[13994]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreStateMachine::updateTrackBlock Nov 25 12:31:34 volumiotop volumio[13994]: info: CorePlayQueue::getTrackBlock Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioRetrievevolume Nov 25 12:31:34 volumiotop volumio[13994]: info: Completed loading Core Plugins Nov 25 12:31:34 volumiotop volumio[13994]: info: Preparing to generate the ALSA configuration file Nov 25 12:31:34 volumiotop volumio[13994]: info: Discovery: adding 2ac0e9ef-7576-4597-b19c-b22708cdf332 Nov 25 12:31:34 volumiotop volumio[13994]: info: Discovery: Found device Volumiotop Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioGetState Nov 25 12:31:34 volumiotop volumio[13994]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:34 volumiotop volumio[13994]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 25 12:31:34 volumiotop volumio[13994]: info: Reading ALSA contributions from plugins. Nov 25 12:31:34 volumiotop volumio[13994]: info: Asound.conf file unchanged, so no further update is needed Nov 25 12:31:34 volumiotop volumio[13994]: info: Output device has changed, restarting MPD Nov 25 12:31:34 volumiotop volumio[13994]: info: Output device has changed, restarting Shairport Sync Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:34 volumiotop sudo[14084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 12:31:34 volumiotop sudo[14084]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:34 volumiotop sudo[14084]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:34 volumiotop volumio[13994]: Starting albumart workers Nov 25 12:31:34 volumiotop sudo[14087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 12:31:34 volumiotop sudo[14087]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:34 volumiotop volumio[13994]: Starting albumart workers Nov 25 12:31:34 volumiotop volumio[13994]: Starting albumart workers Nov 25 12:31:34 volumiotop volumio[13994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:31:34 volumiotop systemd[1]: Stopping Music Player Daemon... Nov 25 12:31:34 volumiotop volumio[13994]: info: ___________ START PLUGINS ___________ Nov 25 12:31:34 volumiotop volumio[13994]: info: ControllerMpd::onStart: Initializing MPD Nov 25 12:31:34 volumiotop volumio[13994]: info: Creating MPD Configuration file Nov 25 12:31:34 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 12:31:34 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:34 volumiotop sudo[14093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 25 12:31:34 volumiotop sudo[14093]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:34 volumiotop sudo[14093]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:34 volumiotop sudo[14096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 25 12:31:34 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 12:31:34 volumiotop sudo[14096]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:34 volumiotop systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 25 12:31:34 volumiotop systemd[1]: mpd.service: Succeeded. Nov 25 12:31:34 volumiotop systemd[1]: Stopped Music Player Daemon. Nov 25 12:31:34 volumiotop volumio[13994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:34 volumiotop systemd[1]: Starting Music Player Daemon... Nov 25 12:31:34 volumiotop volumio[13994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:34 volumiotop volumio[13994]: info: [1732534294897] CoreMusicLibrary::Adding element Last_100 Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:34 volumiotop volumio[13994]: info: [1732534294906] CoreMusicLibrary::Adding element Webradio Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:34 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:31:34 volumiotop volumio[13994]: info: Initializing BBC Radios Nov 25 12:31:34 volumiotop sudo[14102]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 25 12:31:34 volumiotop sudo[14102]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 25 12:31:34 volumiotop sudo[14102]: pam_unix(sudo:session): session closed for user root Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: Adding Calm Radio to Browse Sources Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:35 volumiotop volumio[13994]: info: [1732534295058] CoreMusicLibrary::Adding element Calm Radio Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:35 volumiotop volumio[13994]: Cannot find translation for source Calm Radio Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 25 12:31:35 volumiotop volumio[13994]: info: [1732534295124] CoreMusicLibrary::Adding element Mother Earth Radio Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:35 volumiotop volumio[13994]: Cannot find translation for source Calm Radio Nov 25 12:31:35 volumiotop volumio[13994]: Cannot find translation for source Mother Earth Radio Nov 25 12:31:35 volumiotop volumio[13994]: info: Volumio Calling Home Nov 25 12:31:35 volumiotop volumio[13994]: info: MPD Permissions set Nov 25 12:31:35 volumiotop volumio[13994]: info: MPD Permissions set Nov 25 12:31:35 volumiotop volumio[13994]: info: VolumeController:: Volume=69 Mute =false Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreStateMachine::pushState Nov 25 12:31:35 volumiotop volumio[13994]: info: CorePlayQueue::getTrack 0 Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::volumioPushState Nov 25 12:31:35 volumiotop volumio[13994]: 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 12:31:35 volumiotop volumio[13994]: info: Volumio called home Nov 25 12:31:35 volumiotop volumio[13994]: 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 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 25 12:31:35 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 25 12:31:36 volumiotop volumio[13994]: Searching all installed plugins Nov 25 12:31:36 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 25 12:31:36 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: , search Nov 25 12:31:36 volumiotop volumio[13994]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 25 12:31:36 volumiotop volumio[13994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:31:36 volumiotop volumio[13994]: TypeError: Cannot read property 'then' of undefined Nov 25 12:31:36 volumiotop volumio[13994]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1582:17) Nov 25 12:31:36 volumiotop volumio[13994]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Nov 25 12:31:36 volumiotop volumio[13994]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 25 12:31:36 volumiotop volumio[13994]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 25 12:31:36 volumiotop volumio[13994]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 25 12:31:36 volumiotop volumio[13994]: at Socket.emit (events.js:315:20) Nov 25 12:31:36 volumiotop volumio[13994]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 25 12:31:36 volumiotop volumio[13994]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 25 12:31:36 volumiotop volumio[13994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 25 12:31:36 volumiotop sudo[14162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-25 12:30 Nov 25 12:31:36 volumiotop sudo[14162]: 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"