-- Logs begin at Sun 2024-10-13 13:02:28 CST, end at Sun 2024-10-13 13:04:12 CST. -- Oct 13 13:03:02 volumio volumio[1174]: info: MYVOLUMIO Environment detected Oct 13 13:03:03 volumio volumio[1174]: info: Plugin folders cleanup Oct 13 13:03:03 volumio volumio[1174]: info: Scanning into folder /volumio/app/plugins/ Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category audio_interface Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category miscellanea Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category music_service Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category plugins.json Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category system_controller Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category user_interface Oct 13 13:03:03 volumio volumio[1174]: info: Scanning into folder /data/plugins/ Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category music_service Oct 13 13:03:03 volumio volumio[1174]: info: Scanning category user_interface Oct 13 13:03:03 volumio volumio[1174]: info: Plugin folders cleanup completed Oct 13 13:03:03 volumio volumio[1174]: info: ------------------------------------------- Oct 13 13:03:03 volumio volumio[1174]: info: ----- Core plugins startup ---- Oct 13 13:03:03 volumio volumio[1174]: info: ------------------------------------------- Oct 13 13:03:03 volumio volumio[1174]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 13:03:03 volumio volumio[1174]: info: Adding plugin upnp to MyMusic Plugins Oct 13 13:03:03 volumio volumio[1174]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 13:03:03 volumio volumio[1174]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 13:03:03 volumio volumio[1174]: info: Loading plugins from folder /data/plugins/ Oct 13 13:03:03 volumio volumio[1174]: info: Loading plugin "system"... Oct 13 13:03:03 volumio volumio[1174]: info: Loading plugin "appearance"... Oct 13 13:03:03 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "network"... Oct 13 13:03:07 volumio volumio[1174]: info: Refreshing Cached IP Addresses Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "services"... Oct 13 13:03:07 volumio sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 13:03:07 volumio sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 13:03:07 volumio sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "alsa_controller"... Oct 13 13:03:07 volumio sudo[1417]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:07 volumio sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:07 volumio sudo[1415]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:07 volumio sudo[1419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 13 13:03:07 volumio sudo[1419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:07 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 13 13:03:07 volumio volumio[1174]: warn: Unable to locate the audio output device HDMI. Please configure a valid output device. Oct 13 13:03:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "wizard"... Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "networkfs"... Oct 13 13:03:07 volumio volumio[1174]: info: Starting Udev Watcher for removable devices Oct 13 13:03:07 volumio volumio[1174]: info: Ignoring mount for partition: boot Oct 13 13:03:07 volumio volumio[1174]: info: Ignoring mount for partition: volumio Oct 13 13:03:07 volumio volumio[1174]: info: Ignoring mount for partition: volumio_data Oct 13 13:03:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "volumio_command_line_client"... Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "upnp"... Oct 13 13:03:07 volumio volumio[1174]: info: [1728795787610] Starting Upmpd Daemon Oct 13 13:03:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "my_music"... Oct 13 13:03:07 volumio volumio[1174]: info: Loading plugin "mpd"... Oct 13 13:03:08 volumio volumio[1174]: info: Loading plugin "upnp_browser"... Oct 13 13:03:09 volumio volumio[1174]: info: Loading plugin "alarm-clock"... Oct 13 13:03:09 volumio sudo[1419]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:09 volumio volumio[1174]: info: Loading plugin "airplay_emulation"... Oct 13 13:03:09 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:03:09 volumio volumio[1174]: info: Loading plugin "last_100"... Oct 13 13:03:09 volumio volumio[1174]: info: Loading plugin "webradio"... Oct 13 13:03:09 volumio volumio[1174]: info: Loading plugin "i2s_dacs"... Oct 13 13:03:09 volumio volumio[1174]: info: I2S DAC not set, start Auto-detection Oct 13 13:03:09 volumio volumio[1174]: info: Loading plugin "volumiodiscovery"... Oct 13 13:03:09 volumio volumio[1174]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 13:03:09 volumio node[1174]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 13:03:09 volumio node[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 13:03:09 volumio volumio[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 13:03:09 volumio volumio[1174]: *** WARNING *** For more information see Oct 13 13:03:09 volumio volumio[1174]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 13:03:09 volumio volumio[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 13:03:09 volumio volumio[1174]: *** WARNING *** For more information see Oct 13 13:03:09 volumio node[1174]: *** WARNING *** For more information see Oct 13 13:03:09 volumio node[1174]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 13:03:09 volumio node[1174]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 13:03:09 volumio node[1174]: *** WARNING *** For more information see Oct 13 13:03:09 volumio volumio[1174]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 13:03:09 volumio volumio[1174]: info: Discovery: Started advertising with name: Volumio Oct 13 13:03:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 13:03:10 volumio volumio[1174]: info: Loading plugin "jellyfin"... Oct 13 13:03:12 volumio volumio[1174]: info: Loading plugin "peppy_screensaver"... Oct 13 13:03:13 volumio volumio[1174]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "outputs"... Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "albumart"... Oct 13 13:03:13 volumio volumio[1174]: info: Plugin example_plugin is not enabled Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "inputs"... Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "updater_comm"... Oct 13 13:03:13 volumio volumio-remote-updater[673]: [2024-10-13 13:03:13] [connect] Successful connection Oct 13 13:03:13 volumio volumio[1174]: Forking 1 albumart workers Oct 13 13:03:13 volumio volumio[1174]: info: Plugin mpdemulation is not enabled Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "rest_api"... Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "websocket"... Oct 13 13:03:13 volumio volumio[1174]: info: Starting Socket.io Server version 2.3.0 Oct 13 13:03:13 volumio volumio[1174]: info: Loading plugin "RoonBridge"... Oct 13 13:03:14 volumio volumio[1174]: info: Applying required configuration parameters for plugin RoonBridge Oct 13 13:03:14 volumio volumio[1174]: info: Loading i18n strings for locale zh Oct 13 13:03:14 volumio volumio[1174]: Updating browse sources language Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:14 volumio volumio[1174]: Starting albumart workers Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::initPlayerControls Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:03:14 volumio volumio[1174]: Express server listening on port 3000 Oct 13 13:03:14 volumio volumio[1174]: [Metrics] WebUI: 16s 528.26ms Oct 13 13:03:15 volumio volumio[1174]: info: Setting Device type: x86 Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::resetVolumioState Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::getcurrentVolume Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:15 volumio volumio[1174]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 13 13:03:15 volumio volumio[1174]: info: Completed loading Core Plugins Oct 13 13:03:15 volumio volumio[1174]: info: Preparing to generate the ALSA configuration file Oct 13 13:03:15 volumio volumio[1174]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 13 13:03:15 volumio volumio[1174]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 13 13:03:15 volumio volumio[1174]: info: Reading ALSA contributions from plugins. Oct 13 13:03:15 volumio volumio-remote-updater[673]: [2024-10-13 13:03:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728795793 101 Oct 13 13:03:15 volumio volumio[1174]: 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: 1 Oct 13 13:03:15 volumio volumio[1174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:15 volumio volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 13 13:03:15 volumio volumio[1174]: info: Reloading queue from file Oct 13 13:03:15 volumio volumio[1174]: info: VolumeController:: Volume=undefined Mute =false Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::updateTrackBlock Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrackBlock Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::setRepeat null single undefined Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::setRandom null Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:15 volumio volumio[1174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Oct 13 13:03:15 volumio volumio[1174]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 13 13:03:15 volumio volumio[1174]: info: Discovery: Found device Volumio Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:15 volumio volumio[1174]: info: Discovery: this is already registered, 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 13 13:03:15 volumio volumio[1174]: info: Discovery: Found device Volumio Oct 13 13:03:15 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:15 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:15 volumio volumio[1174]: info: VolumeController:: Volume=undefined Mute =false Oct 13 13:03:15 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:16 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:16 volumio volumio[1174]: info: Asound.conf file unchanged, so no further update is needed Oct 13 13:03:16 volumio volumio[1174]: info: Output device has changed, restarting MPD Oct 13 13:03:16 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 13:03:16 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:16 volumio volumio[1174]: info: Output device has changed, restarting Shairport Sync Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:16 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:16 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 13:03:16 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:16 volumio volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 13:03:16 volumio volumio[1174]: info: ___________ START PLUGINS ___________ Oct 13 13:03:16 volumio systemd[1]: Listening on mpd.socket. Oct 13 13:03:16 volumio volumio[1174]: info: ControllerMpd::onStart: Initializing MPD Oct 13 13:03:16 volumio volumio[1174]: info: Creating MPD Configuration file Oct 13 13:03:16 volumio systemd[1]: Starting Music Player Daemon... Oct 13 13:03:16 volumio sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 13:03:16 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 13:03:16 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:16 volumio volumio[1174]: info: [1728795796454] CoreMusicLibrary::Adding element 媒体服务器 Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:16 volumio sudo[1558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 13:03:16 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:16 volumio sudo[1553]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 13:03:16 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:16 volumio sudo[1553]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 13:03:16 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 13 13:03:16 volumio systemd[1]: mpd.service: Succeeded. Oct 13 13:03:16 volumio systemd[1]: Stopped Music Player Daemon. Oct 13 13:03:16 volumio systemd[1]: Starting Music Player Daemon... Oct 13 13:03:16 volumio volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:16 volumio sudo[1565]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 13:03:16 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:16 volumio sudo[1565]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 13:03:16 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:16 volumio volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 13:03:16 volumio volumio[1174]: info: [1728795796893] CoreMusicLibrary::Adding element Last_100 Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 13:03:16 volumio volumio[1174]: info: [1728795796901] CoreMusicLibrary::Adding element Webradio Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:16 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:03:16 volumio volumio[1174]: info: Initializing BBC Radios Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:17 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:17 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 13:03:17 volumio volumio[1174]: info: [1728795797166] CoreMusicLibrary::Adding element Jellyfin Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:17 volumio volumio[1174]: Cannot find translation for source Jellyfin Oct 13 13:03:17 volumio volumio[1174]: info: [jellyfin] Initialized plugin with device info: {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://192.168.31.204","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://192.168.31.204/albumart?cacheid=609&web=%E8%B5%B5%E9%B9%8F/%E4%B8%AD%E5%9B%BD%E6%9C%80%E5%A5%BD%E5%A3%B0%E9%9F%B3%3A%E4%BA%BA%E5%A3%B0%E6%B5%8B%E8%AF%95%E4%B8%93%E7%94%A8%E5%A4%A7%E7%A2%9F/extralarge&path=%2FUSB&metadata=false"}} Oct 13 13:03:17 volumio volumio[1174]: info: Loading i18n strings for locale zh Oct 13 13:03:17 volumio volumio[1174]: info: /tmp/myfifo created Oct 13 13:03:17 volumio volumio[1174]: info: /tmp/myfifosa created Oct 13 13:03:17 volumio sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 13 13:03:17 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:17 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:17 volumio volumio[1174]: info: snd-dummy loaded Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:17 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:17 volumio volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 13:03:18 volumio volumio[1174]: info: Volumio Calling Home Oct 13 13:03:18 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 13 13:03:18 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:18 volumio systemd[1]: Started RoonBridge. Oct 13 13:03:18 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:18 volumio sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 13:03:18 volumio sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:18 volumio sudo[1621]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:19 volumio sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 13:03:19 volumio sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:19 volumio volumio[1174]: info: MPD Permissions set Oct 13 13:03:19 volumio sudo[1624]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:19 volumio volumio[1174]: info: Volumio called home Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:19 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:19 volumio sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Oct 13 13:03:19 volumio sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:19 volumio volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 13 13:03:19 volumio sudo[1631]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:19 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:20 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Oct 13 13:03:20 volumio sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 13:03:20 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:20 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:21 volumio RoonBridge[1610]: 00:00:00.048 Warn: get lock file path: /tmp/.rnbgem0- Oct 13 13:03:21 volumio volumio[1174]: info: MPD Permissions set Oct 13 13:03:21 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:03:21 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:03:21 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:03:21 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:03:21 volumio sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 13:03:21 volumio sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:21 volumio sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 13:03:21 volumio sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 13:03:21 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 13 13:03:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:21 volumio sudo[1651]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 13:03:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 13 13:03:21 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 13 13:03:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:21 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 13:03:21 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:21 volumio sudo[1649]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:21 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:21 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:21 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 13 13:03:21 volumio sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 13:03:21 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 13:03:22 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 13 13:03:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:22 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:22 volumio mpd[1570]: Oct 13 13:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 13:03:22 volumio systemd[1]: Started Music Player Daemon. Oct 13 13:03:22 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 13:03:22 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:22 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 13 13:03:22 volumio sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 13:03:22 volumio sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:01.688 Trace: [childprocess] using unix child process Oct 13 13:03:22 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:22 volumio systemd[1]: Stopping Music Player Daemon... Oct 13 13:03:22 volumio sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 13 13:03:22 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:22 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:01.975 Debug: PathForResource, filename: ../.update Oct 13 13:03:22 volumio volumio[1174]: info: Shairport-Sync Started Oct 13 13:03:22 volumio volumio[1174]: Error adding Membership: Error: addMembership EINVAL Oct 13 13:03:22 volumio volumio[1174]: info: Shairport-Sync Started Oct 13 13:03:22 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:22 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:22 volumio systemd[1]: mpd.service: Succeeded. Oct 13 13:03:22 volumio systemd[1]: Stopped Music Player Daemon. Oct 13 13:03:22 volumio systemd[1]: Starting Music Player Daemon... Oct 13 13:03:22 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:02.135 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 13 13:03:22 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:02.161 Debug: PathForResource, filename: RAATServer Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:02.164 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:02.166 Debug: PathForResource, filename: RoonBridgeHelper Oct 13 13:03:22 volumio RoonBridge[1610]: 00:00:02.166 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 13 13:03:22 volumio RoonBridge[1610]: Initializing Oct 13 13:03:23 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:23 volumio RoonBridge[1610]: 00:00:02.351 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 13 13:03:23 volumio sudo[1683]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 13:03:23 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:23 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:23 volumio volumio[1174]: info: Shairport-Sync Started Oct 13 13:03:23 volumio volumio[1174]: info: Completed starting Core Plugins Oct 13 13:03:23 volumio volumio[1174]: info: ------------------------------------------- Oct 13 13:03:23 volumio volumio[1174]: info: ----- MyVolumio plugins startup ---- Oct 13 13:03:23 volumio volumio[1174]: info: ------------------------------------------- Oct 13 13:03:23 volumio volumio[1174]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 13 13:03:23 volumio RoonBridge[1610]: 00:00:02.572 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 13 13:03:23 volumio volumio[1174]: error: Cannot initialize MPD Connection: MPD is not running Oct 13 13:03:23 volumio volumio[1174]: info: Upmpdcli Daemon Started Oct 13 13:03:23 volumio RoonBridge[1610]: Not Running (.o) Oct 13 13:03:25 volumio RoonBridge[1610]: Running Oct 13 13:03:26 volumio RoonBridge[1610]: 00:00:00.133 Warn: get lock file path: /tmp/.rnbhgem0- Oct 13 13:03:26 volumio mpd[1690]: Oct 13 13:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 13:03:26 volumio systemd[1]: Started Music Player Daemon. Oct 13 13:03:26 volumio sudo[1667]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:26 volumio volumio[1174]: error: MPD error: The expression evaluated to a falsy value: Oct 13 13:03:26 volumio volumio[1174]: assert.ok(self.idling) Oct 13 13:03:26 volumio volumio[1174]: error: The expression evaluated to a falsy value: Oct 13 13:03:26 volumio volumio[1174]: assert.ok(self.idling) Oct 13 13:03:26 volumio volumio[1174]: Upnp client error: Error: This socket has been ended by the other party Oct 13 13:03:26 volumio volumio[1174]: error: updateQueue error: null Oct 13 13:03:26 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:26 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:26 volumio volumio[1174]: error: updateQueue error: null Oct 13 13:03:27 volumio volumio[1678]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 13 13:03:27 volumio volumio[1174]: 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: 4 Oct 13 13:03:29 volumio volumio[1678]: ..................................++++ Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 13 13:03:32 volumio volumio[1174]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 13:03:32 volumio volumio[1174]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 13:03:32 volumio volumio[1174]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 13:03:32 volumio volumio[1174]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 13:03:32 volumio volumio[1174]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 13:03:32 volumio volumio[1174]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 13:03:32 volumio volumio[1174]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 13 13:03:32 volumio RoonBridge[1610]: 00:00:11.682 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 13 13:03:32 volumio RoonBridge[1610]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 13 13:03:32 volumio RoonBridge[1610]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 13 13:03:32 volumio RoonBridge[1610]: Not Running (.o) Oct 13 13:03:32 volumio RoonBridge[1610]: 00:00:11.726 Debug: PathForResource, filename: ../.update Oct 13 13:03:32 volumio RoonBridge[1610]: 00:00:11.726 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 13 13:03:34 volumio volumio[1678]: ................................................................................++++ Oct 13 13:03:34 volumio volumio[1678]: e is 65537 (0x010001) Oct 13 13:03:34 volumio volumio[1678]: writing RSA key Oct 13 13:03:34 volumio RoonBridge[1610]: 00:00:13.728 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 13 13:03:34 volumio RoonBridge[1610]: Running Oct 13 13:03:34 volumio volumio[1174]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 13 13:03:34 volumio volumio[1174]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 13 13:03:34 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:34 volumio volumio[1174]: info: Starting MyVolumio Remote Streaming Endpoints Oct 13 13:03:35 volumio volumio[1174]: info: MyVolumio login type: Token Oct 13 13:03:35 volumio volumio[1174]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 13 13:03:35 volumio volumio[1174]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 13 13:03:37 volumio volumio[1174]: info: Starting Streaming Service Transparent Proxy Oct 13 13:03:37 volumio volumio[1174]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 13 13:03:37 volumio volumio[1174]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 13 13:03:37 volumio volumio[1174]: info: Streaming services startup Oct 13 13:03:37 volumio volumio[1174]: info: Starting Streaming Daemon Oct 13 13:03:37 volumio sudo[2000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 13:03:37 volumio sudo[2000]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:37 volumio sudo[2000]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:37 volumio volumio[1174]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 13 13:03:38 volumio volumio[1174]: error: Cannot start Volumio Streaming Daemon Oct 13 13:03:38 volumio volumio[1174]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 13:03:38 volumio volumio[1174]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 13:03:38 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:38 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:39 volumio volumio[1174]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 13 13:03:40 volumio volumio[1174]: STREAMING PROXY: Starting server on port 3245 Oct 13 13:03:40 volumio volumio[1174]: Node JS runtime: 14 Oct 13 13:03:40 volumio RoonBridge[1610]: 00:00:19.743 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 13 13:03:40 volumio RoonBridge[1610]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 13 13:03:40 volumio RoonBridge[1610]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 13 13:03:40 volumio RoonBridge[1610]: Not Running (.o) Oct 13 13:03:40 volumio RoonBridge[1610]: 00:00:19.757 Debug: PathForResource, filename: ../.update Oct 13 13:03:40 volumio RoonBridge[1610]: 00:00:19.757 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 13 13:03:40 volumio volumio[1174]: info: MyVolumio token set successfully Oct 13 13:03:40 volumio volumio[1174]: info: MYVOLUMIO: Adding device Oct 13 13:03:40 volumio volumio[1174]: info: MYVOLUMIO: Evaluating Server Oct 13 13:03:41 volumio volumio[1174]: info: MyVolumio status changed Oct 13 13:03:41 volumio volumio[1174]: info: Streaming services startup Oct 13 13:03:41 volumio volumio[1174]: info: Starting Streaming Daemon Oct 13 13:03:41 volumio volumio[1174]: info: Removing browser output: myVolumio user plan is not superstar Oct 13 13:03:41 volumio volumio[1174]: info: Removing audio output: Oct 13 13:03:41 volumio volumio[1174]: info: Stoppping Tunnel 1 Oct 13 13:03:41 volumio sudo[2034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 13:03:41 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:41 volumio sudo[2036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 13 13:03:41 volumio sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:41 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:41 volumio volumio[1174]: error: Cannot start Volumio Streaming Daemon Oct 13 13:03:41 volumio volumio[1174]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 13 13:03:41 volumio volumio[1174]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 13 13:03:41 volumio sudo[2036]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:41 volumio volumio[1174]: info: Remote SSH Stopped Oct 13 13:03:42 volumio RoonBridge[1610]: 00:00:21.758 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 13 13:03:42 volumio RoonBridge[1610]: Running Oct 13 13:03:43 volumio volumio[1174]: 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 Oct 13 13:03:43 volumio volumio[1174]: info: Setting Geolocation for MyVolumio to as1 Oct 13 13:03:43 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:43 volumio volumio[1174]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 13 13:03:44 volumio volumio[1174]: info: Updating MyVolumio device info Oct 13 13:03:44 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:44 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 13:03:45 volumio volumio[1174]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 13:03:45 volumio volumio[1174]: info: Received Get System Info Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:45 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: Listing playlists Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 13:03:45 volumio volumio[1174]: info: Received Get System Info Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:45 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 13:03:47 volumio volumio[1174]: info: MYVOLUMIO: Adding device Oct 13 13:03:47 volumio volumio[1174]: info: MYVOLUMIO: Evaluating Server Oct 13 13:03:48 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:03:48 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:48 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 13:03:49 volumio volumio-remote-updater[673]: No test mode Oct 13 13:03:49 volumio volumio-remote-updater[673]: No alpha test mode Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 13:03:49 volumio volumio[1174]: info: Received Get System Info Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:49 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:49 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:49 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:49 volumio volumio[1174]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 13:03:49 volumio volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 13 13:03:50 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:50 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:51 volumio volumio[1174]: info: Setting Geolocation for MyVolumio to us2 Oct 13 13:03:51 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:52 volumio volumio[1174]: info: Updating MyVolumio device info Oct 13 13:03:52 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:52 volumio volumio[1174]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 13 13:03:53 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 13:03:53 volumio volumio[1174]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:55 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:55 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:55 volumio volumio[1174]: verbose: New Socket.io Connection to 192.168.31.157:3000 from 192.168.31.109 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:55 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 13:03:55 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:56 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:56 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:56 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:56 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:57 volumio volumio[1174]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 13 13:03:57 volumio volumio[1174]: info: Completed starting MyVolumio Plugin Oct 13 13:03:57 volumio volumio[1174]: info: BOOT COMPLETED Oct 13 13:03:57 volumio volumio[1174]: [Metrics] CommandRouter: 57s 982.83ms Oct 13 13:03:57 volumio volumio[1174]: info: CoreCommandRouter::volumiosetStartupVolume Oct 13 13:03:57 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:03:57 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:03:57 volumio volumio[1174]: info: CoreCommandRouter::Close All Modals sent Oct 13 13:03:58 volumio volumio[1174]: info: CoreCommandRouter::Close All Modals sent Oct 13 13:03:58 volumio volumio[1174]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 13 13:03:58 volumio volumio[1174]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 13 13:03:58 volumio volumio[1174]: aplay: main:828: audio open error: No such device Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 13 13:03:59 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 13:03:59 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:59 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:59 volumio sudo[2098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 13:03:59 volumio sudo[2098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:59 volumio sudo[2098]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:59 volumio volumio[1174]: verbose: New Socket.io Connection to 192.168.31.204 from 192.168.31.109 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:59 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 13:03:59 volumio volumio[1174]: info: Received Get System Info Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:59 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:59 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:59 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:59 volumio volumio[1174]: info: Listing playlists Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 13:03:59 volumio sudo[2102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 13:03:59 volumio sudo[2102]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:59 volumio sudo[2102]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:59 volumio sudo[2105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 13:03:59 volumio sudo[2105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 13:03:59 volumio sudo[2105]: pam_unix(sudo:session): session closed for user root Oct 13 13:03:59 volumio volumio[1174]: verbose: New Socket.io Connection to 192.168.31.204 from 192.168.31.109 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.81 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:59 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 13:03:59 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 13:03:59 volumio volumio[1174]: info: Received Get System Info Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:04:00 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:00 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:00 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:00 volumio volumio[1174]: info: Listing playlists Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 13:04:00 volumio volumio[1174]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 13:04:00 volumio volumio[1174]: info: Received Get System Version Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:04:00 volumio volumio[1174]: info: Received Get System Info Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:04:00 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:00 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:04:00 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:00 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:01 volumio ntpd[1216]: Soliciting pool server 162.159.200.1 Oct 13 13:04:01 volumio ntpd[1216]: Soliciting pool server 111.203.6.13 Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 13:04:01 volumio volumio[1174]: info: Received Get System Info Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:04:01 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:01 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:04:01 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 13 13:04:04 volumio ntpd[1216]: Soliciting pool server 193.182.111.12 Oct 13 13:04:05 volumio ntpd[1216]: Soliciting pool server 202.112.31.197 Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 13:04:05 volumio volumio[1174]: info: Received Get System Info Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:04:05 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:05 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 13:04:06 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 13:04:06 volumio ntpd[1216]: Soliciting pool server 117.80.112.205 Oct 13 13:04:06 volumio ntpd[1216]: Soliciting pool server 101.133.146.204 Oct 13 13:04:07 volumio ntpd[1216]: Soliciting pool server 162.159.200.1 Oct 13 13:04:07 volumio ntpd[1216]: Soliciting pool server 2001:253:207::1:123 Oct 13 13:04:09 volumio volumio[1174]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 13 13:04:09 volumio volumio[1174]: info: Preparing to save Alsa Options, stopping services first Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:09 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::volumioPause Oct 13 13:04:09 volumio volumio[1174]: info: CoreStateMachine::pause Oct 13 13:04:09 volumio volumio[1174]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:09 volumio volumio[1174]: info: Found match in Cards Database: setting mixer for card HDMI Oct 13 13:04:09 volumio volumio[1174]: info: Setting mixer IEC958 for card HDMI Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 13 13:04:09 volumio volumio[1174]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 13 13:04:09 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 13 13:04:09 volumio volumio[1174]: info: Disabling external Volume Control Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 13:04:10 volumio volumio[1174]: info: Preparing to generate the ALSA configuration file Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 13:04:10 volumio volumio[1174]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 13 13:04:10 volumio volumio[1174]: info: Reading ALSA contributions from plugins. Oct 13 13:04:10 volumio volumio[1174]: Unable to parse: Oct 13 13:04:10 volumio volumio[1174]: Simple mixer control 'IEC958',0 Oct 13 13:04:10 volumio volumio[1174]: Capabilities: pswitch pswitch-joined Oct 13 13:04:10 volumio volumio[1174]: Playback channels: Mono Oct 13 13:04:10 volumio volumio[1174]: Mono: Playback [off] Oct 13 13:04:10 volumio volumio[1174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 13:04:10 volumio volumio[1174]: Unable to parse: Oct 13 13:04:10 volumio volumio[1174]: Simple mixer control 'IEC958',0 Oct 13 13:04:10 volumio volumio[1174]: Capabilities: pswitch pswitch-joined Oct 13 13:04:10 volumio volumio[1174]: Playback channels: Mono Oct 13 13:04:10 volumio volumio[1174]: Mono: Playback [off] Oct 13 13:04:10 volumio volumio[1174]: info: VolumeController:: Volume=undefined Mute =false Oct 13 13:04:10 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:04:10 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:04:10 volumio volumio[1174]: info: Asound.conf file written Oct 13 13:04:10 volumio sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 13 13:04:10 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:10 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:10 volumio volumio[1174]: info: Output device has changed, restarting MPD Oct 13 13:04:10 volumio sudo[2140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 13:04:10 volumio sudo[2140]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:10 volumio volumio[1174]: info: Output device has changed, restarting Shairport Sync Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:10 volumio sudo[2140]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:10 volumio sudo[2143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 13:04:10 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:10 volumio systemd[1]: Stopping Music Player Daemon... Oct 13 13:04:10 volumio volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 13:04:10 volumio volumio[1174]: info: Output device has changed, restarting MPD Oct 13 13:04:10 volumio systemd[1]: mpd.service: Succeeded. Oct 13 13:04:10 volumio systemd[1]: Stopped Music Player Daemon. Oct 13 13:04:10 volumio systemd[1]: Starting Music Player Daemon... Oct 13 13:04:10 volumio sudo[2150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 13:04:10 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:10 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:10 volumio volumio[1174]: info: Output device has changed, restarting Shairport Sync Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:10 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 13:04:10 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:10 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 13 13:04:10 volumio systemd[1]: mpd.service: Succeeded. Oct 13 13:04:10 volumio systemd[1]: Stopped Music Player Daemon. Oct 13 13:04:10 volumio systemd[1]: Starting Music Player Daemon... Oct 13 13:04:10 volumio volumio[1174]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 13:04:10 volumio volumio[1174]: info: MPD Permissions set Oct 13 13:04:10 volumio volumio[1174]: info: MPD Permissions set Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 13:04:10 volumio sudo[2156]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 13:04:10 volumio sudo[2156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:10 volumio sudo[2156]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:10 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 13 13:04:11 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:11 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:04:11 volumio volumio[1174]: info: Starting Shairport Sync Oct 13 13:04:11 volumio sudo[2174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 13:04:11 volumio sudo[2174]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 13:04:11 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 13 13:04:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 13:04:11 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:11 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 13:04:11 volumio sudo[2174]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:11 volumio volumio[1174]: info: Shairport-Sync Started Oct 13 13:04:11 volumio sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 13:04:11 volumio sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:04:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 13:04:11 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 13 13:04:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 13 13:04:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 13:04:11 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 13:04:11 volumio sudo[2177]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:11 volumio volumio[1174]: info: Shairport-Sync Started Oct 13 13:04:12 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:12 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:12 volumio mpd[2164]: Oct 13 13:04 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 13:04:12 volumio systemd[1]: Started Music Player Daemon. Oct 13 13:04:12 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:12 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root Oct 13 13:04:12 volumio volumio[1174]: error: MPD error: The expression evaluated to a falsy value: Oct 13 13:04:12 volumio volumio[1174]: assert.ok(self.idling) Oct 13 13:04:12 volumio volumio[1174]: error: The expression evaluated to a falsy value: Oct 13 13:04:12 volumio volumio[1174]: assert.ok(self.idling) Oct 13 13:04:12 volumio volumio[1174]: error: updateQueue error: null Oct 13 13:03:39 volumio ntpd[1216]: receive: Unexpected origin timestamp 0xeab5d34d.777bb981 does not match aorg 0000000000.00000000 from server@117.80.112.205 xmt 0xeab5d32b.efb4a646 Oct 13 13:03:40 volumio ntpd[1216]: receive: Unexpected origin timestamp 0xeab5d34d.7781edf9 does not match aorg 0000000000.00000000 from server@193.182.111.12 xmt 0xeab5d32c.1085a871 Oct 13 13:03:40 volumio volumio[1174]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 13 13:03:40 volumio volumio[1174]: info: CoreStateMachine::getcurrentVolume Oct 13 13:03:40 volumio volumio[1174]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 13:03:40 volumio volumio[1174]: Unable to parse: Oct 13 13:03:40 volumio volumio[1174]: Simple mixer control 'IEC958',0 Oct 13 13:03:40 volumio volumio[1174]: Capabilities: pswitch pswitch-joined Oct 13 13:03:40 volumio volumio[1174]: Playback channels: Mono Oct 13 13:03:40 volumio volumio[1174]: Mono: Playback [off] Oct 13 13:03:40 volumio volumio[1174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 13 13:03:40 volumio volumio[1174]: Unable to parse: Oct 13 13:03:40 volumio volumio[1174]: Simple mixer control 'IEC958',0 Oct 13 13:03:40 volumio volumio[1174]: Capabilities: pswitch pswitch-joined Oct 13 13:03:40 volumio volumio[1174]: Playback channels: Mono Oct 13 13:03:40 volumio volumio[1174]: Mono: Playback [off] Oct 13 13:03:40 volumio volumio[1174]: info: VolumeController:: Volume=undefined Mute =false Oct 13 13:03:40 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:40 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:40 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:40 volumio volumio[1174]: info: CoreStateMachine::updateTrackBlock Oct 13 13:03:40 volumio volumio[1174]: info: CorePlayQueue::getTrackBlock Oct 13 13:03:40 volumio volumio[1174]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 13:03:40 volumio volumio[1174]: Unable to parse: Oct 13 13:03:40 volumio volumio[1174]: Simple mixer control 'IEC958',0 Oct 13 13:03:40 volumio volumio[1174]: Capabilities: pswitch pswitch-joined Oct 13 13:03:40 volumio volumio[1174]: Playback channels: Mono Oct 13 13:03:40 volumio volumio[1174]: Mono: Playback [off] Oct 13 13:03:40 volumio volumio[1174]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Oct 13 13:03:40 volumio volumio[1174]: Unable to parse: Oct 13 13:03:40 volumio volumio[1174]: Simple mixer control 'IEC958',0 Oct 13 13:03:40 volumio volumio[1174]: Capabilities: pswitch pswitch-joined Oct 13 13:03:40 volumio volumio[1174]: Playback channels: Mono Oct 13 13:03:40 volumio volumio[1174]: Mono: Playback [off] Oct 13 13:03:40 volumio volumio[1174]: info: VolumeController:: Volume=undefined Mute =false Oct 13 13:03:40 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:40 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:40 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPlay Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::play index undefined Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::startPlaybackTimer Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand stop took 3 milliseconds Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand clear Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces system playlist update Oct 13 13:03:45 volumio volumio[1174]: info: Ignoring MPD Status Update Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand clear took 4 milliseconds Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces system playlist update Oct 13 13:03:45 volumio volumio[1174]: info: Ignoring MPD Status Update Oct 13 13:03:45 volumio volumio[1174]: error: updateQueue error: null Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces system playlist update Oct 13 13:03:45 volumio volumio[1174]: info: Ignoring MPD Status Update Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 10ms Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 6 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 6ms Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand play Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces system playlist update Oct 13 13:03:45 volumio volumio[1174]: info: Ignoring MPD Status Update Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 9ms Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand play took 5 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 4ms Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces state update: player Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::getState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces state update: player Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::getState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces state update: player Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand status took 105 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand status took 102 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::getState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 13:03:45 volumio volumio[1174]: info: Oct 13 13:03:45 volumio volumio[1174]: ---------------------------- MPD announces state update: player Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::getState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand status took 40 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand status took 5 milliseconds Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseTrackInfo Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseTrackInfo Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseState Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::servicePushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 13 13:03:45 volumio volumio[1174]: verbose: CURRENT POSITION 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState stateService play Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState currentStatus stop Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::servicePushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"301 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 13 13:03:45 volumio volumio[1174]: verbose: CURRENT POSITION 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState stateService play Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState currentStatus play Oct 13 13:03:45 volumio volumio[1174]: info: Received an update from plugin. extracting info from payload Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 185ms Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 200ms Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand playlistinfo took 53 milliseconds Oct 13 13:03:45 volumio volumio[1174]: info: sendMpdCommand playlistinfo took 51 milliseconds Oct 13 13:03:45 volumio volumio[1174]: verbose: ControllerMpd::parseTrackInfo Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::servicePushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"533 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 13 13:03:45 volumio volumio[1174]: verbose: CURRENT POSITION 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState stateService play Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState currentStatus play Oct 13 13:03:45 volumio volumio[1174]: info: Received an update from plugin. extracting info from payload Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: ControllerMpd::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::servicePushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 13 13:03:45 volumio volumio[1174]: verbose: CURRENT POSITION 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState stateService play Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::syncState currentStatus play Oct 13 13:03:45 volumio volumio[1174]: info: Received an update from plugin. extracting info from payload Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 188ms Oct 13 13:03:45 volumio volumio[1174]: info: ------------------------------ 149ms Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 13 13:03:45 volumio volumio[1174]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 13 13:03:45 volumio volumio[1174]: info: Received Get System Version Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 13:03:45 volumio volumio[1174]: info: Received Get System Info Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:45 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:45 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:45 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:49 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:49 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:49 volumio sudo[2210]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 13 13:03:49 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 13:03:51 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 13:03:51 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 13:03:51 volumio volumio[1174]: info: Discovery: Getting this device information Oct 13 13:03:51 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:51 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:51 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 13:03:54 volumio volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 13 13:03:54 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:54 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:56 volumio volumio[1174]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 13 13:03:56 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:03:56 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:03:57 volumio kernel: usb 3-4: new high-speed USB device number 2 using ehci-pci Oct 13 13:03:57 volumio kernel: usb 3-4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Oct 13 13:03:57 volumio kernel: usb 3-4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 13 13:03:57 volumio kernel: usb 3-4: Product: SMSL USB AUDIO Oct 13 13:03:57 volumio kernel: usb 3-4: Manufacturer: SMSL Oct 13 13:03:57 volumio kernel: hid-generic 0003:152A:85DD.0001: hiddev0,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:12.2-4/input3 Oct 13 13:03:58 volumio kernel: usb 3-4: 1:3 : unsupported format bits 0x100000000 Oct 13 13:03:58 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 13 13:03:58 volumio systemd-udevd[2250]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 13 13:03:58 volumio volumio[1174]: info: Oct 13 13:03:58 volumio volumio[1174]: ---------------------------- USB Audio Device Attached Oct 13 13:03:58 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 13 13:04:01 volumio volumio-remote-updater[673]: [2024-10-13 13:04:01] [disconnect] Disconnect close local:[1000] remote:[1000] Oct 13 13:04:01 volumio systemd[1]: volumio-remote-updater.service: Succeeded. Oct 13 13:04:02 volumio systemd[1]: volumio-remote-updater.service: Service RestartSec=100ms expired, scheduling restart. Oct 13 13:04:02 volumio systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Oct 13 13:04:02 volumio systemd[1]: Stopped volumio-remote-updater.service. Oct 13 13:04:02 volumio systemd[1]: Started volumio-remote-updater.service. Oct 13 13:04:02 volumio volumio-remote-updater[2279]: Error: No active session Oct 13 13:04:02 volumio volumio-remote-updater[2279]: [2024-10-13 13:04:02] [connect] Successful connection Oct 13 13:04:02 volumio volumio-remote-updater[2279]: [2024-10-13 13:04:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728795842 101 Oct 13 13:04:02 volumio volumio[1174]: 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: 9 Oct 13 13:04:04 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 13 13:04:05 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 13:04:07 volumio volumio[1174]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 13 13:04:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 13 13:04:07 volumio volumio[1174]: info: Preparing to save Alsa Options, stopping services first Oct 13 13:04:07 volumio volumio[1174]: info: CoreCommandRouter::volumioGetState Oct 13 13:04:07 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:07 volumio volumio[1174]: info: CoreCommandRouter::volumioPause Oct 13 13:04:07 volumio volumio[1174]: info: CoreStateMachine::pause Oct 13 13:04:07 volumio volumio[1174]: info: CoreStateMachine::stPlaybackTimer Oct 13 13:04:07 volumio volumio[1174]: info: CoreStateMachine::servicePause Oct 13 13:04:07 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:07 volumio volumio[1174]: info: CoreCommandRouter::servicePause Oct 13 13:04:07 volumio volumio[1174]: info: ControllerMpd::pause Oct 13 13:04:07 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand pause Oct 13 13:04:07 volumio volumio[1174]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"}} Oct 13 13:04:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:07 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 13:04:08 volumio volumio[1174]: info: Device 5 does not have any Mixer Control Available Oct 13 13:04:08 volumio volumio[1174]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 13 13:04:08 volumio volumio[1174]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 13 13:04:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 13 13:04:08 volumio volumio[1174]: info: Disabling external Volume Control Oct 13 13:04:08 volumio volumio[1174]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 13:04:08 volumio volumio[1174]: info: CoreStateMachine::pushState Oct 13 13:04:08 volumio volumio[1174]: info: CorePlayQueue::getTrack 0 Oct 13 13:04:08 volumio volumio[1174]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 13:04:08 volumio volumio[1174]: info: CoreCommandRouter::volumioPushState Oct 13 13:04:08 volumio volumio[1174]: info: Preparing to generate the ALSA configuration file Oct 13 13:04:08 volumio volumio[1174]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 13 13:04:08 volumio volumio[1174]: info: Reading ALSA contributions from plugins. Oct 13 13:04:08 volumio volumio[1174]: info: Oct 13 13:04:08 volumio volumio[1174]: ---------------------------- MPD announces state update: player Oct 13 13:04:08 volumio volumio[1174]: info: sendMpdCommand pause took 433 milliseconds Oct 13 13:04:08 volumio volumio[1174]: info: ControllerMpd::getState Oct 13 13:04:08 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Oct 13 13:04:08 volumio volumio[1174]: info: Oct 13 13:04:08 volumio volumio[1174]: ---------------------------- MPD announces state update: player Oct 13 13:04:08 volumio volumio[1174]: info: ControllerMpd::getState Oct 13 13:04:08 volumio volumio[1174]: verbose: ControllerMpd::sendMpdCommand status Oct 13 13:04:08 volumio volumio[1174]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 13:04:08 volumio volumio[1174]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 13 13:04:08 volumio volumio[1174]: at Object.readdirSync (fs.js:1048:3) Oct 13 13:04:08 volumio volumio[1174]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 13 13:04:08 volumio volumio[1174]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 13 13:04:08 volumio volumio[1174]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 13 13:04:08 volumio volumio[1174]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 13 13:04:08 volumio volumio[1174]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 13 13:04:08 volumio volumio[1174]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 13 13:04:08 volumio volumio[1174]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 13 13:04:08 volumio volumio[1174]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 13 13:04:08 volumio volumio[1174]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 13 13:04:08 volumio volumio[1174]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 13 13:04:08 volumio volumio[1174]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 13 13:04:08 volumio volumio[1174]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 13 13:04:08 volumio volumio[1174]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 13 13:04:08 volumio volumio[1174]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 13 13:04:08 volumio volumio[1174]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 13 13:04:08 volumio volumio[1174]: errno: -20, Oct 13 13:04:08 volumio volumio[1174]: syscall: 'scandir', Oct 13 13:04:08 volumio volumio[1174]: code: 'ENOTDIR', Oct 13 13:04:08 volumio volumio[1174]: path: '/tmp/peppyrunning' Oct 13 13:04:08 volumio volumio[1174]: } Oct 13 13:04:08 volumio volumio[1174]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 13:04:08 volumio sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 13:03 Oct 13 13:04:08 volumio sudo[2333]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"