-- Logs begin at Fri 2024-08-30 06:53:41 +07, end at Fri 2024-08-30 18:49:49 +07. -- Aug 30 18:48:11 ht1 volumio[1408]: info: Aug 30 18:48:11 ht1 volumio[1408]: ---------------------------- MPD announces system playlist update Aug 30 18:48:11 ht1 volumio[1408]: info: Ignoring MPD Status Update Aug 30 18:48:11 ht1 volumio[1408]: info: Aug 30 18:48:11 ht1 volumio[1408]: ---------------------------- MPD announces state update: player Aug 30 18:48:11 ht1 volumio[1408]: info: ControllerMpd::getState Aug 30 18:48:11 ht1 volumio[1408]: verbose: ControllerMpd::sendMpdCommand status Aug 30 18:48:11 ht1 volumio[1408]: error: updateQueue error: null Aug 30 18:48:11 ht1 volumio[1408]: info: ------------------------------ 0ms Aug 30 18:48:11 ht1 volumio[1408]: info: sendMpdCommand status took 0 milliseconds Aug 30 18:48:11 ht1 volumio[1408]: verbose: ControllerMpd::parseState Aug 30 18:48:11 ht1 volumio[1408]: info: ControllerMpd::pushState Aug 30 18:48:11 ht1 volumio[1408]: info: CoreCommandRouter::servicePushState Aug 30 18:48:11 ht1 volumio[1408]: info: CoreStateMachine::pushState Aug 30 18:48:11 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:48:11 ht1 volumio[1408]: info: CoreCommandRouter::volumioPushState Aug 30 18:48:11 ht1 volumio[1408]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:48:11 ht1 volumio[1408]: info: MRS: Pushing multiroomSync output Aug 30 18:48:11 ht1 volumio[1408]: info: CorePlayQueue::getTrack 44 Aug 30 18:48:11 ht1 volumio[1408]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 30 18:48:11 ht1 volumio[1408]: verbose: CURRENT POSITION 44 Aug 30 18:48:11 ht1 volumio[1408]: info: CoreStateMachine::syncState stateService stop Aug 30 18:48:11 ht1 volumio[1408]: info: CoreStateMachine::syncState currentStatus play Aug 30 18:48:11 ht1 volumio[1408]: info: CoreCommandRouter::volumioPushState Aug 30 18:48:11 ht1 volumio[1408]: info: MRS: Pushing multiroomSync output update for this device Aug 30 18:48:11 ht1 volumio[1408]: info: MRS: Pushing multiroomSync output Aug 30 18:48:11 ht1 volumio[1408]: info: CoreStateMachine::stPlaybackTimer Aug 30 18:48:11 ht1 volumio[1408]: info: ------------------------------ 4ms Aug 30 18:48:57 ht1 ntpd[1397]: Soliciting pool server 2401:5f80:5001:1f::31f Aug 30 18:49:09 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 18:49:09 ht1 volumio[1408]: info: Received Get System Info Aug 30 18:49:09 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:09 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:09 ht1 volumio[1408]: info: Discovery: Getting this device information Aug 30 18:49:09 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:09 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:27 ht1 volumio[1408]: info: Discovery: Getting this device information Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:27 ht1 volumio[1408]: info: Discovery: Getting this device information Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:27 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:29 ht1 volumio[1408]: verbose: New Socket.io Connection to 192.168.1.15:3000 from 192.168.1.4 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Aug 30 18:49:29 ht1 volumio[1408]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.4 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetQueue Aug 30 18:49:29 ht1 volumio[1408]: info: CoreStateMachine::getQueue Aug 30 18:49:29 ht1 volumio[1408]: info: CorePlayQueue::getQueue Aug 30 18:49:29 ht1 volumio[1408]: info: Listing playlists Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 18:49:29 ht1 volumio[1408]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 30 18:49:29 ht1 volumio[1408]: info: Received Get System Info Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:29 ht1 volumio[1408]: info: Discovery: Getting this device information Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:29 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 18:49:30 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 18:49:30 ht1 volumio[1408]: info: Received Get System Info Aug 30 18:49:30 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:30 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:30 ht1 volumio[1408]: info: Discovery: Getting this device information Aug 30 18:49:30 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:30 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:49:42 ht1 volumio[1408]: info: Retrieving Cloud Streaming UI Aug 30 18:49:42 ht1 volumio[1408]: info: Getting Tidal Cloud Configuration Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:49:42 ht1 volumio[1408]: info: Getting Qobuz Cloud Configuration Aug 30 18:49:42 ht1 volumio[1408]: info: Asking plugin for UI Config Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:49:42 ht1 volumio[1408]: info: Getting Spotify Cloud Configuration Aug 30 18:49:42 ht1 volumio[1408]: info: Asking plugin for UI Config Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:49:42 ht1 volumio[1408]: info: Saving Spotify Acccount Aug 30 18:49:42 ht1 volumio[1408]: info: Got it Aug 30 18:49:42 ht1 volumio[1408]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 18:49:42 ht1 volumio[1408]: info: Got Tidal Cloud Configuration Aug 30 18:49:42 ht1 volumio[1408]: info: Got it Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:49:42 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 18:49:43 ht1 volumio[1408]: info: Disabling MyMusic plugin upnp Aug 30 18:49:43 ht1 sudo[5715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 30 18:49:43 ht1 sudo[5715]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:43 ht1 systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 30 18:49:43 ht1 volumio[1408]: error: Upnp client error: Error: This socket has been ended by the other party Aug 30 18:49:44 ht1 volumio[1408]: info: Enabling MyMusic plugin upnp Aug 30 18:49:44 ht1 volumio[1408]: info: Enabling plugin upnp Aug 30 18:49:44 ht1 volumio[1408]: info: Loading plugin "upnp"... Aug 30 18:49:44 ht1 volumio[1408]: info: [1725018584146] Starting Upmpd Daemon Aug 30 18:49:44 ht1 volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:49:44 ht1 volumio[1408]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:49:44 ht1 volumio[1408]: Error: listen EADDRINUSE: address already in use :::6599 Aug 30 18:49:44 ht1 volumio[1408]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Aug 30 18:49:44 ht1 volumio[1408]: at listenInCluster (net.js:1379:12) Aug 30 18:49:44 ht1 volumio[1408]: at Server.listen (net.js:1465:7) Aug 30 18:49:44 ht1 volumio[1408]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 30 18:49:44 ht1 volumio[1408]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 30 18:49:44 ht1 volumio[1408]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 30 18:49:44 ht1 volumio[1408]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 30 18:49:44 ht1 volumio[1408]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Aug 30 18:49:44 ht1 volumio[1408]: code: 'EADDRINUSE', Aug 30 18:49:44 ht1 volumio[1408]: errno: -98, Aug 30 18:49:44 ht1 volumio[1408]: syscall: 'listen', Aug 30 18:49:44 ht1 volumio[1408]: address: '::', Aug 30 18:49:44 ht1 volumio[1408]: port: 6599 Aug 30 18:49:44 ht1 volumio[1408]: } Aug 30 18:49:44 ht1 volumio[1408]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:49:44 ht1 sudo[5726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 18:48 Aug 30 18:49:44 ht1 sudo[5726]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:44 ht1 sudo[5726]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:45 ht1 volumio-remote-updater[844]: [2024-08-30 18:49:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 30 18:49:45 ht1 volumio-remote-updater[844]: [2024-08-30 18:49:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 30 18:49:45 ht1 vtcs[2485]: [2024-08-30 18:49:45.202] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Aug 30 18:49:45 ht1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 30 18:49:45 ht1 sudo[5715]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:45 ht1 systemd[1]: upmpdcli.service: Succeeded. Aug 30 18:49:45 ht1 systemd[1]: Stopped UPnP Renderer front-end to MPD. Aug 30 18:49:45 ht1 systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 30 18:49:45 ht1 systemd[1]: Started dynamicswap service. Aug 30 18:49:45 ht1 systemd[1]: dynamicswap.service: Succeeded. Aug 30 18:49:45 ht1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 30 18:49:45 ht1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Aug 30 18:49:45 ht1 systemd[1]: Started dynamicswap service. Aug 30 18:49:45 ht1 systemd[1]: Stopped Volumio Backend Module. Aug 30 18:49:45 ht1 systemd[1]: Started Volumio Backend Module. Aug 30 18:49:45 ht1 systemd[1]: dynamicswap.service: Succeeded. Aug 30 18:49:45 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:45 ht1 volumio[5742]: info: ----- Volumio3 ---- Aug 30 18:49:45 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:45 ht1 volumio[5742]: info: ----- System startup ---- Aug 30 18:49:45 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:45 ht1 volumio[5742]: info: MYVOLUMIO Environment detected Aug 30 18:49:45 ht1 volumio[5742]: info: Plugin folders cleanup Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning into folder /volumio/app/plugins/ Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category audio_interface Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category miscellanea Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category music_service Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category plugins.json Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category system_controller Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category user_interface Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning into folder /data/plugins/ Aug 30 18:49:45 ht1 volumio[5742]: info: Scanning category music_service Aug 30 18:49:45 ht1 volumio[5742]: info: Plugin folders cleanup completed Aug 30 18:49:45 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:45 ht1 volumio[5742]: info: ----- Core plugins startup ---- Aug 30 18:49:45 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:45 ht1 volumio[5742]: info: Loading plugins from folder /volumio/app/plugins/ Aug 30 18:49:45 ht1 volumio[5742]: info: Adding plugin upnp to MyMusic Plugins Aug 30 18:49:45 ht1 volumio[5742]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 30 18:49:45 ht1 volumio[5742]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 30 18:49:45 ht1 volumio[5742]: info: Loading plugins from folder /data/plugins/ Aug 30 18:49:45 ht1 volumio[5742]: info: Loading plugin "system"... Aug 30 18:49:45 ht1 volumio[5742]: info: Loading plugin "appearance"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "network"... Aug 30 18:49:46 ht1 volumio[5742]: info: Refreshing Cached IP Addresses Aug 30 18:49:46 ht1 sudo[5769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 18:49:46 ht1 sudo[5769]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5769]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 sudo[5771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 18:49:46 ht1 sudo[5771]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "services"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "alsa_controller"... Aug 30 18:49:46 ht1 sudo[5771]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 sudo[5774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 30 18:49:46 ht1 sudo[5774]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "wizard"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "networkfs"... Aug 30 18:49:46 ht1 volumio[5742]: info: Starting Udev Watcher for removable devices Aug 30 18:49:46 ht1 volumio[5742]: info: Ignoring mount for partition: boot Aug 30 18:49:46 ht1 volumio[5742]: info: Ignoring mount for partition: volumio Aug 30 18:49:46 ht1 volumio[5742]: info: Ignoring mount for partition: volumio_data Aug 30 18:49:46 ht1 volumio[5742]: info: Mounting Device NAS Aug 30 18:49:46 ht1 sudo[5783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/NAS -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 30 18:49:46 ht1 sudo[5783]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5783]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 volumio[5742]: mount: /media/NAS: /dev/sda1 already mounted on /media/NAS. Aug 30 18:49:46 ht1 volumio[5742]: error: Failed to mount NAS: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/NAS" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 30 18:49:46 ht1 volumio[5742]: mount: /media/NAS: /dev/sda1 already mounted on /media/NAS. Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "volumio_command_line_client"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "upnp"... Aug 30 18:49:46 ht1 volumio[5742]: info: [1725018586141] Starting Upmpd Daemon Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "my_music"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "mpd"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "upnp_browser"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "alarm-clock"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "airplay_emulation"... Aug 30 18:49:46 ht1 volumio[5742]: info: Starting Shairport Sync Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "last_100"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "webradio"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "i2s_dacs"... Aug 30 18:49:46 ht1 volumio[5742]: info: I2S DAC not set, start Auto-detection Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "volumiodiscovery"... Aug 30 18:49:46 ht1 volumio[5742]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 18:49:46 ht1 volumio[5742]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:49:46 ht1 volumio[5742]: *** WARNING *** For more information see Aug 30 18:49:46 ht1 volumio[5742]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 18:49:46 ht1 node[5742]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 30 18:49:46 ht1 volumio[5742]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:49:46 ht1 volumio[5742]: *** WARNING *** For more information see Aug 30 18:49:46 ht1 node[5742]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:49:46 ht1 node[5742]: *** WARNING *** For more information see Aug 30 18:49:46 ht1 node[5742]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 30 18:49:46 ht1 node[5742]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 30 18:49:46 ht1 node[5742]: *** WARNING *** For more information see Aug 30 18:49:46 ht1 volumio[5742]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 30 18:49:46 ht1 volumio[5742]: info: Discovery: Started advertising with name: HT1 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "youtube2"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "outputs"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "albumart"... Aug 30 18:49:46 ht1 volumio[5742]: info: Plugin example_plugin is not enabled Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "inputs"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "updater_comm"... Aug 30 18:49:46 ht1 volumio[5742]: info: Plugin mpdemulation is not enabled Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "rest_api"... Aug 30 18:49:46 ht1 volumio[5742]: info: Loading plugin "websocket"... Aug 30 18:49:46 ht1 volumio[5742]: info: Starting Socket.io Server version 2.3.0 Aug 30 18:49:46 ht1 volumio[5742]: info: Loading i18n strings for locale vi Aug 30 18:49:46 ht1 volumio[5742]: Forking 3 albumart workers Aug 30 18:49:46 ht1 volumio[5742]: Updating browse sources language Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::initPlayerControls Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: Express server listening on port 3000 Aug 30 18:49:46 ht1 volumio[5742]: [Metrics] WebUI: 1s 37.51ms Aug 30 18:49:46 ht1 volumio[5742]: info: Setting Device type: x86 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::resetVolumioState Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::getcurrentVolume Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::pushState Aug 30 18:49:46 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioPushState Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 30 18:49:46 ht1 volumio[5742]: info: Completed loading Core Plugins Aug 30 18:49:46 ht1 volumio[5742]: info: Preparing to generate the ALSA configuration file Aug 30 18:49:46 ht1 volumio[5742]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 30 18:49:46 ht1 volumio[5742]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 30 18:49:46 ht1 volumio[5742]: verbose: New Socket.io Connection to 192.168.1.15:3000 from 192.168.1.4 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:46 ht1 volumio[5742]: info: Discovery: Getting this device information Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:46 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:46 ht1 volumio[5742]: info: Reloading queue from file Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::setRepeat null single undefined Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::pushState Aug 30 18:49:46 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioPushState Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::setRandom false Aug 30 18:49:46 ht1 volumio[5742]: info: CoreStateMachine::pushState Aug 30 18:49:46 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioPushState Aug 30 18:49:46 ht1 volumio[5742]: info: Asound.conf file written Aug 30 18:49:46 ht1 sudo[5820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 30 18:49:46 ht1 sudo[5820]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 volumio[5742]: Starting albumart workers Aug 30 18:49:46 ht1 sudo[5820]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 volumio[5742]: info: Output device has changed, restarting MPD Aug 30 18:49:46 ht1 volumio[5742]: Starting albumart workers Aug 30 18:49:46 ht1 volumio[5742]: Starting albumart workers Aug 30 18:49:46 ht1 volumio[5742]: info: Output device has changed, restarting Shairport Sync Aug 30 18:49:46 ht1 sudo[5826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:46 ht1 sudo[5826]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 18:49:46 ht1 sudo[5828]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5826]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 systemd[1]: Stopping Music Player Daemon... Aug 30 18:49:46 ht1 volumio[5742]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 18:49:46 ht1 volumio[5742]: info: ___________ START PLUGINS ___________ Aug 30 18:49:46 ht1 volumio[5742]: info: ControllerMpd::onStart: Initializing MPD Aug 30 18:49:46 ht1 volumio[5742]: info: Creating MPD Configuration file Aug 30 18:49:46 ht1 systemd[1]: mpd.service: Succeeded. Aug 30 18:49:46 ht1 systemd[1]: Stopped Music Player Daemon. Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:49:46 ht1 volumio[5742]: info: [1725018586895] CoreMusicLibrary::Adding element Máy chủ Media Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:46 ht1 systemd[1]: Starting Music Player Daemon... Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:46 ht1 sudo[5838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 18:49:46 ht1 sudo[5838]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 18:49:46 ht1 sudo[5835]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5835]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 30 18:49:46 ht1 systemd[1]: mpd.service: Succeeded. Aug 30 18:49:46 ht1 systemd[1]: Stopped Music Player Daemon. Aug 30 18:49:46 ht1 systemd[1]: Starting Music Player Daemon... Aug 30 18:49:46 ht1 volumio[5742]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:46 ht1 sudo[5852]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 18:49:46 ht1 sudo[5852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:46 ht1 sudo[5852]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:46 ht1 volumio[5742]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:49:46 ht1 volumio[5742]: info: [1725018586989] CoreMusicLibrary::Adding element Last_100 Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:49:46 ht1 volumio[5742]: info: [1725018586990] CoreMusicLibrary::Adding element Webradio Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:46 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 18:49:46 ht1 volumio[5742]: info: Initializing BBC Radios Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 30 18:49:47 ht1 volumio[5742]: info: [1725018587046] CoreMusicLibrary::Adding element YouTube2 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:47 ht1 volumio[5742]: Cannot find translation for source YouTube2 Aug 30 18:49:47 ht1 volumio[5742]: info: Volumio Calling Home Aug 30 18:49:47 ht1 volumio[5742]: info: MPD Permissions set Aug 30 18:49:47 ht1 volumio[5742]: info: MPD Permissions set Aug 30 18:49:47 ht1 volumio[5742]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.4 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Aug 30 18:49:47 ht1 volumio[5742]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 18:49:47 ht1 volumio[5742]: info: Starting Shairport Sync Aug 30 18:49:47 ht1 volumio[5742]: info: Starting Shairport Sync Aug 30 18:49:47 ht1 sudo[5901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 18:49:47 ht1 sudo[5901]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:47 ht1 volumio[5742]: info: Starting Shairport Sync Aug 30 18:49:47 ht1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 18:49:47 ht1 systemd[1]: shairport-sync.service: Succeeded. Aug 30 18:49:47 ht1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 18:49:47 ht1 sudo[5904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 18:49:47 ht1 sudo[5904]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:47 ht1 sudo[5908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 18:49:47 ht1 sudo[5908]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 18:49:47 ht1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 18:49:47 ht1 mpd[5858]: Aug 30 18:49 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 18:49:47 ht1 sudo[5901]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:47 ht1 volumio[5742]: info: Shairport-Sync Started Aug 30 18:49:47 ht1 volumio[5742]: Error adding Membership: Error: addMembership EINVAL Aug 30 18:49:47 ht1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 30 18:49:47 ht1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 30 18:49:47 ht1 systemd[1]: shairport-sync.service: Succeeded. Aug 30 18:49:47 ht1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 30 18:49:47 ht1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 30 18:49:47 ht1 sudo[5908]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:47 ht1 sudo[5904]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:47 ht1 volumio[5742]: info: Shairport-Sync Started Aug 30 18:49:47 ht1 volumio[5742]: info: Shairport-Sync Started Aug 30 18:49:47 ht1 systemd[1]: Started Music Player Daemon. Aug 30 18:49:47 ht1 sudo[5828]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 sudo[5838]: pam_unix(sudo:session): session closed for user root Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetQueue Aug 30 18:49:47 ht1 volumio[5742]: info: CoreStateMachine::getQueue Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getQueue Aug 30 18:49:47 ht1 volumio[5742]: info: Listing playlists Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 18:49:47 ht1 volumio[5742]: info: Received Get System Info Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:47 ht1 volumio[5742]: info: Discovery: Getting this device information Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: Completed starting Core Plugins Aug 30 18:49:47 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:47 ht1 volumio[5742]: info: ----- MyVolumio plugins startup ---- Aug 30 18:49:47 ht1 volumio[5742]: info: ------------------------------------------- Aug 30 18:49:47 ht1 volumio[5742]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 30 18:49:47 ht1 volumio[5742]: error: MPD error: The expression evaluated to a falsy value: Aug 30 18:49:47 ht1 volumio[5742]: assert.ok(self.idling) Aug 30 18:49:47 ht1 volumio[5742]: error: The expression evaluated to a falsy value: Aug 30 18:49:47 ht1 volumio[5742]: assert.ok(self.idling) Aug 30 18:49:47 ht1 volumio[5742]: error: updateQueue error: null Aug 30 18:49:47 ht1 volumio[5742]: info: MPD running with PID5858 Aug 30 18:49:47 ht1 volumio[5742]: ,establishing connection Aug 30 18:49:47 ht1 volumio[5742]: error: updateQueue error: null Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: Discovery: adding e3cc3b63-8180-4db1-bbcb-129a5e64ada3 Aug 30 18:49:47 ht1 volumio[5742]: info: Discovery: Found device HT1 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 18:49:47 ht1 volumio[5742]: info: Received Get System Info Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 18:49:47 ht1 volumio[5742]: info: Discovery: Getting this device information Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 18:49:47 ht1 volumio[5742]: info: CoreCommandRouter::volumioGetState Aug 30 18:49:47 ht1 volumio[5742]: info: CorePlayQueue::getTrack 0 Aug 30 18:49:47 ht1 volumio[5742]: info: Listing playlists Aug 30 18:49:47 ht1 volumio[5742]: info: Volumio called home Aug 30 18:49:49 ht1 volumio[5742]: info: Enabling MyMusic plugin bluetooth Aug 30 18:49:49 ht1 volumio[5742]: info: [MyVolumio PluginManager] Enabling and starting plugin audio_interface bluetooth Aug 30 18:49:49 ht1 volumio[5742]: info: [MyVolumio PluginManager] Plugin audio_interface bluetooth not in user plan, not enabling Aug 30 18:49:49 ht1 volumio[5742]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:49:49 ht1 volumio[5742]: TypeError: Cannot read property 'toUpperCase' of undefined Aug 30 18:49:49 ht1 volumio[5742]: at MyVolumioPluginManager.pushMyVolumioRequiredAccountModal (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:19516) Aug 30 18:49:49 ht1 volumio[5742]: at MyVolumioPluginManager.enablePlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:18418) Aug 30 18:49:49 ht1 volumio[5742]: at MyVolumioPluginManager.enableAndStartPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:17039) Aug 30 18:49:49 ht1 volumio[5742]: at PluginManager.enableDisableMyMusicPlugin (/volumio/app/pluginmanager.js:2024:48) Aug 30 18:49:49 ht1 volumio[5742]: at CoreCommandRouter.enableDisableMyMusicPlugin (/volumio/app/index.js:2088:29) Aug 30 18:49:49 ht1 volumio[5742]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1921:59) Aug 30 18:49:49 ht1 volumio[5742]: at Socket.emit (events.js:400:28) Aug 30 18:49:49 ht1 volumio[5742]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 18:49:49 ht1 volumio[5742]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 30 18:49:49 ht1 volumio[5742]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 18:49:49 ht1 sudo[5929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-30 18:48 Aug 30 18:49:49 ht1 sudo[5929]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"