-- Logs begin at Sun 2019-12-29 21:31:51 CST, end at Thu 2024-10-10 17:16:20 CST. -- Oct 10 17:15:05 volumio systemd[1]: Starting Daily man-db regeneration... Oct 10 17:15:05 volumio systemd[1]: Starting Daily apt download activities... Oct 10 17:15:05 volumio systemd[1]: man-db.service: Succeeded. Oct 10 17:15:05 volumio systemd[1]: Started Daily man-db regeneration. Oct 10 17:15:06 volumio volumio[1095]: info: Loading plugin "network"... Oct 10 17:15:06 volumio volumio[1095]: info: Refreshing Cached IP Addresses Oct 10 17:15:06 volumio sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 17:15:06 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:06 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:06 volumio volumio[1095]: info: Loading plugin "services"... Oct 10 17:15:06 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 17:15:06 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:06 volumio volumio[1095]: info: Loading plugin "alsa_controller"... Oct 10 17:15:06 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:06 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 10 17:15:06 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:06 volumio volumio[1095]: warn: Unable to locate the audio output device HDMI. Please configure a valid output device. Oct 10 17:15:06 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 17:15:06 volumio volumio[1095]: info: Loading plugin "wizard"... Oct 10 17:15:06 volumio volumio[1095]: info: Loading plugin "networkfs"... Oct 10 17:15:06 volumio sh[646]: timed out Oct 10 17:15:06 volumio sh[646]: dhcpcd exited Oct 10 17:15:06 volumio dhcpcd[692]: timed out Oct 10 17:15:06 volumio dhcpcd[692]: dhcpcd exited Oct 10 17:15:06 volumio sh[646]: ifup: failed to bring up eth0 Oct 10 17:15:06 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 10 17:15:06 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 10 17:15:07 volumio volumio[1095]: info: Starting Udev Watcher for removable devices Oct 10 17:15:07 volumio volumio[1095]: info: Ignoring mount for partition: boot Oct 10 17:15:07 volumio volumio[1095]: info: Ignoring mount for partition: volumio Oct 10 17:15:07 volumio volumio[1095]: info: Ignoring mount for partition: volumio_data Oct 10 17:15:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 17:15:07 volumio volumio[1095]: info: Loading plugin "volumio_command_line_client"... Oct 10 17:15:07 volumio volumio[1095]: info: Loading plugin "upnp"... Oct 10 17:15:07 volumio volumio[1095]: info: [1728551707164] Starting Upmpd Daemon Oct 10 17:15:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 17:15:07 volumio volumio[1095]: info: Loading plugin "my_music"... Oct 10 17:15:07 volumio volumio[1095]: info: Loading plugin "mpd"... Oct 10 17:15:08 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 10 17:15:08 volumio volumio[1095]: info: Loading plugin "upnp_browser"... Oct 10 17:15:08 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:09 volumio volumio[1095]: info: Loading plugin "alarm-clock"... Oct 10 17:15:10 volumio volumio[1095]: info: Loading plugin "airplay_emulation"... Oct 10 17:15:10 volumio volumio[1095]: info: Starting Shairport Sync Oct 10 17:15:10 volumio volumio[1095]: info: Loading plugin "last_100"... Oct 10 17:15:10 volumio volumio[1095]: info: Loading plugin "webradio"... Oct 10 17:15:10 volumio volumio[1095]: info: Loading plugin "i2s_dacs"... Oct 10 17:15:10 volumio volumio[1095]: info: I2S DAC not set, start Auto-detection Oct 10 17:15:10 volumio volumio[1095]: info: Loading plugin "volumiodiscovery"... Oct 10 17:15:10 volumio volumio[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 17:15:10 volumio volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 17:15:10 volumio volumio[1095]: *** WARNING *** For more information see Oct 10 17:15:10 volumio volumio[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 17:15:10 volumio volumio[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 17:15:10 volumio volumio[1095]: *** WARNING *** For more information see Oct 10 17:15:10 volumio node[1095]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 17:15:10 volumio node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 17:15:10 volumio node[1095]: *** WARNING *** For more information see Oct 10 17:15:10 volumio node[1095]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 17:15:10 volumio node[1095]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 17:15:10 volumio node[1095]: *** WARNING *** For more information see Oct 10 17:15:10 volumio volumio[1095]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 17:15:10 volumio volumio[1095]: info: Discovery: Started advertising with name: Volumio Oct 10 17:15:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 17:15:10 volumio volumio[1095]: info: Loading plugin "jellyfin"... Oct 10 17:15:13 volumio volumio[1095]: info: Loading plugin "jellyfin_server"... Oct 10 17:15:13 volumio volumio[1095]: info: Loading plugin "autostart"... Oct 10 17:15:14 volumio volumio-remote-updater[735]: [2024-10-10 17:15:14] [connect] Successful connection Oct 10 17:15:14 volumio volumio[1095]: info: Applying required configuration parameters for plugin autostart Oct 10 17:15:14 volumio volumio[1095]: info: AutoStart - onVolumioStart - read config.json Oct 10 17:15:14 volumio volumio[1095]: info: Loading plugin "peppy_screensaver"... Oct 10 17:15:16 volumio volumio[1095]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 10 17:15:16 volumio volumio[1095]: info: Loading plugin "outputs"... Oct 10 17:15:16 volumio volumio[1095]: info: Loading plugin "albumart"... Oct 10 17:15:16 volumio volumio[1095]: info: Plugin example_plugin is not enabled Oct 10 17:15:16 volumio volumio[1095]: info: Loading plugin "inputs"... Oct 10 17:15:16 volumio volumio[1095]: info: Loading plugin "updater_comm"... Oct 10 17:15:17 volumio volumio[1095]: Forking 1 albumart workers Oct 10 17:15:17 volumio volumio[1095]: info: Plugin mpdemulation is not enabled Oct 10 17:15:17 volumio volumio[1095]: info: Loading plugin "rest_api"... Oct 10 17:15:17 volumio volumio[1095]: info: Loading plugin "websocket"... Oct 10 17:15:17 volumio volumio[1095]: info: Starting Socket.io Server version 2.3.0 Oct 10 17:15:17 volumio volumio[1095]: info: Loading plugin "RoonBridge"... Oct 10 17:15:18 volumio volumio[1095]: Starting albumart workers Oct 10 17:15:21 volumio volumio[1095]: info: Applying required configuration parameters for plugin RoonBridge Oct 10 17:15:22 volumio volumio[1095]: info: Loading plugin "lms"... Oct 10 17:15:22 volumio volumio[1095]: info: Loading plugin "minidlna"... Oct 10 17:15:26 volumio volumio[1095]: info: Applying required configuration parameters for plugin minidlna Oct 10 17:15:26 volumio volumio[1095]: info: Loading i18n strings for locale zh Oct 10 17:15:26 volumio volumio[1095]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 17:15:26 volumio volumio[1095]: Updating browse sources language Oct 10 17:15:26 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::initPlayerControls Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:15:27 volumio volumio[1095]: Express server listening on port 3000 Oct 10 17:15:27 volumio volumio[1095]: [Metrics] WebUI: 29s 133.56ms Oct 10 17:15:27 volumio volumio[1095]: info: Setting Device type: x86 Oct 10 17:15:27 volumio volumio[1095]: info: CoreStateMachine::resetVolumioState Oct 10 17:15:27 volumio volumio[1095]: info: CoreStateMachine::getcurrentVolume Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:27 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 17:15:27 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:27 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:27 volumio volumio[1095]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 10 17:15:27 volumio volumio[1095]: info: Completed loading Core Plugins Oct 10 17:15:27 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file Oct 10 17:15:27 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 17:15:27 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:27 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:27 volumio volumio[1095]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Oct 10 17:15:27 volumio volumio[1095]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 10 17:15:27 volumio volumio[1095]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 10 17:15:27 volumio volumio[1095]: info: Reading ALSA contributions from plugins. Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:27 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:27 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 10 17:15:27 volumio volumio[1095]: info: VolumeController:: Volume=undefined Mute =false Oct 10 17:15:27 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:15:27 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:15:27 volumio volumio[1095]: info: CoreStateMachine::updateTrackBlock Oct 10 17:15:27 volumio volumio[1095]: info: CorePlayQueue::getTrackBlock Oct 10 17:15:27 volumio volumio[1095]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 17:15:28 volumio volumio[1095]: info: Reloading queue from file Oct 10 17:15:28 volumio volumio[1095]: info: CoreStateMachine::setRepeat null single undefined Oct 10 17:15:28 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:15:28 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:15:28 volumio volumio[1095]: info: CoreStateMachine::setRandom null Oct 10 17:15:28 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:15:28 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:15:28 volumio volumio[1095]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Oct 10 17:15:28 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 10 17:15:28 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:28 volumio volumio[1095]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 10 17:15:28 volumio volumio[1095]: info: Discovery: Found device Volumio Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:28 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:28 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 10 17:15:28 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:28 volumio volumio[1095]: info: VolumeController:: Volume=undefined Mute =false Oct 10 17:15:28 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:15:28 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:15:28 volumio volumio[1095]: info: Upmpdcli Daemon Started Oct 10 17:15:28 volumio volumio[1095]: info: Asound.conf file unchanged, so no further update is needed Oct 10 17:15:28 volumio volumio[1095]: info: Output device has changed, restarting MPD Oct 10 17:15:28 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 17:15:28 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:28 volumio volumio[1095]: info: Output device has changed, restarting Shairport Sync Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:28 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:28 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:28 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 17:15:28 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:28 volumio systemd[1]: Listening on mpd.socket. Oct 10 17:15:28 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 17:15:28 volumio volumio[1095]: info: ___________ START PLUGINS ___________ Oct 10 17:15:28 volumio volumio[1095]: info: ControllerMpd::onStart: Initializing MPD Oct 10 17:15:28 volumio volumio[1095]: info: Creating MPD Configuration file Oct 10 17:15:28 volumio systemd[1]: Starting Music Player Daemon... Oct 10 17:15:29 volumio volumio-remote-updater[735]: [2024-10-10 17:15:29] [connect] Successful connection Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:15:29 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 17:15:29 volumio volumio[1095]: info: [1728551729132] CoreMusicLibrary::Adding element 媒体服务器 Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:29 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:29 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:29 volumio sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 17:15:29 volumio sudo[1543]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 17:15:29 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:29 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:29 volumio sudo[1543]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 10 17:15:29 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 10 17:15:29 volumio systemd[1]: mpd.service: Succeeded. Oct 10 17:15:29 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 17:15:29 volumio systemd[1]: Starting Music Player Daemon... Oct 10 17:15:29 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:29 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 17:15:29 volumio volumio[1095]: info: [1728551729599] CoreMusicLibrary::Adding element Last_100 Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 17:15:29 volumio sudo[1566]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 17:15:29 volumio sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:29 volumio volumio[1095]: info: [1728551729613] CoreMusicLibrary::Adding element Webradio Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 17:15:29 volumio sudo[1566]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 10 17:15:29 volumio sudo[1566]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:29 volumio volumio[1095]: info: Initializing BBC Radios Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 17:15:29 volumio systemd[1]: apt-daily.service: Succeeded. Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:29 volumio systemd[1]: Started Daily apt download activities. Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:15:29 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:29 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 17:15:29 volumio volumio[1095]: info: [1728551729948] CoreMusicLibrary::Adding element Jellyfin Oct 10 17:15:29 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:29 volumio volumio[1095]: Cannot find translation for source Jellyfin Oct 10 17:15:29 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Oct 10 17:15:29 volumio volumio[1095]: info: [jellyfin] Initialized plugin with device info: {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://192.168.31.153","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://192.168.31.153/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 10 17:15:29 volumio volumio[1095]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Oct 10 17:15:30 volumio volumio[1095]: info: AutoStart - onStart Oct 10 17:15:30 volumio volumio[1095]: info: Loading i18n strings for locale zh Oct 10 17:15:30 volumio volumio[1095]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 17:15:30 volumio sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Oct 10 17:15:30 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:30 volumio volumio[1095]: info: /tmp/myfifo created Oct 10 17:15:30 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 10 17:15:30 volumio volumio[1095]: info: /tmp/myfifosa created Oct 10 17:15:30 volumio systemd[1]: Starting Jellyfin... Oct 10 17:15:30 volumio systemd[1]: Started Jellyfin. Oct 10 17:15:30 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:30 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 10 17:15:30 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:30 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:30 volumio volumio[1095]: info: snd-dummy loaded Oct 10 17:15:30 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:31 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:31 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Oct 10 17:15:31 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:31 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:31 volumio volumio[1095]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 17:15:31 volumio sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 10 17:15:31 volumio sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:31 volumio volumio[1095]: info: Loading i18n strings for locale zh Oct 10 17:15:31 volumio systemd[1]: Started RoonBridge. Oct 10 17:15:31 volumio volumio[1095]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 17:15:31 volumio sudo[1650]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:32 volumio sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Oct 10 17:15:32 volumio sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:32 volumio volumio[1095]: info: Volumio Calling Home Oct 10 17:15:32 volumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Oct 10 17:15:32 volumio systemd[1]: Starting Logitech Media Server Daemon... Oct 10 17:15:32 volumio systemd[1]: Started Logitech Media Server Daemon. Oct 10 17:15:32 volumio systemd[1670]: logitechmediaserver.service: Failed to execute command: No such file or directory Oct 10 17:15:32 volumio systemd[1670]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Oct 10 17:15:32 volumio sudo[1653]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:32 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Oct 10 17:15:32 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Oct 10 17:15:34 volumio volumio[1095]: info: logitechmediaserver started Oct 10 17:15:34 volumio volumio[1095]: info: MPD Permissions set Oct 10 17:15:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:15:34 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:15:35 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:15:35 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:35 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:35 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:15:35 volumio volumio[1095]: info: minidlna: Found miniDLNA version 1.2.1 Oct 10 17:15:35 volumio volumio[1095]: info: minidlna: Starting minidlna.service Oct 10 17:15:35 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 10 17:15:35 volumio RoonBridge[1654]: 00:00:00.065 Warn: get lock file path: /tmp/.rnbgem0- Oct 10 17:15:35 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:35 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 10 17:15:35 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:36 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Oct 10 17:15:36 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:36 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:36 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:36 volumio volumio[1095]: 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 10 17:15:36 volumio volumio[1095]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:36 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 10 17:15:36 volumio sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 10 17:15:36 volumio sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:36 volumio systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:37 volumio sudo[1702]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.102 Trace: [childprocess] using unix child process Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.447 Debug: PathForResource, filename: ../.update Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:15:37 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:37 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.606 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 10 17:15:37 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:15:37 volumio mpd[1579]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 17:15:37 volumio mpd[1579]: output: No 'audio_output' defined in config file Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.649 Debug: PathForResource, filename: RAATServer Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.657 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.659 Debug: PathForResource, filename: RoonBridgeHelper Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.659 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 10 17:15:37 volumio RoonBridge[1654]: Initializing Oct 10 17:15:37 volumio mpd[1579]: couldn't open play stream: No such file or directory Oct 10 17:15:37 volumio mpd[1579]: sndio_output: Error opening default sndio device Oct 10 17:15:37 volumio mpd[1579]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 10 17:15:37 volumio mpd[1579]: alsa_output: Error opening default ALSA device: No such device Oct 10 17:15:37 volumio mpd[1579]: output: Successfully detected a jack audio device Oct 10 17:15:37 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 10 17:15:37 volumio mpd[1579]: zeroconf: No global port, disabling zeroconf Oct 10 17:15:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Oct 10 17:15:37 volumio systemd[1]: Started Music Player Daemon. Oct 10 17:15:37 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:37 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:37 volumio RoonBridge[1654]: 00:00:02.891 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 10 17:15:38 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Oct 10 17:15:38 volumio RoonBridge[1654]: 00:00:03.128 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 10 17:15:38 volumio volumio[1095]: info: minidlna: systemctl start minidlna.service succeeded. Oct 10 17:15:38 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 17:15:38 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:38 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:38 volumio volumio[1095]: Upnp client error: Error: This socket has been ended by the other party Oct 10 17:15:38 volumio RoonBridge[1654]: Not Running (.o) Oct 10 17:15:39 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:38] [INF] [1] Main: Jellyfin version: 10.9.6 Oct 10 17:15:39 volumio sudo[1802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"] Oct 10 17:15:39 volumio sudo[1802]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Oct 10 17:15:39 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.31.153:3000 from 192.168.31.109 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Architecture: X64 Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: 64-Bit Process: True Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: User Interactive: True Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Processor count: 2 Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Program data path: /opt/jellyfin/data Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Log directory path: /opt/jellyfin/log Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Config directory path: /opt/jellyfin/config Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Cache path: /opt/jellyfin/cache Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Oct 10 17:15:39 volumio jellyfin[1610]: [17:15:39] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Oct 10 17:15:39 volumio volumio[1095]: info: MPD running with PID1579 Oct 10 17:15:39 volumio volumio[1095]: ,establishing connection Oct 10 17:15:39 volumio volumio[1095]: info: MPD Permissions set Oct 10 17:15:39 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 17:15:39 volumio volumio[1095]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 10 17:15:39 volumio systemd[1]: mpd.service: Succeeded. Oct 10 17:15:39 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 17:15:39 volumio volumio[1095]: info: Starting Shairport Sync Oct 10 17:15:39 volumio systemd[1]: Starting Music Player Daemon... Oct 10 17:15:39 volumio sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 10 17:15:39 volumio sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:40 volumio volumio[1095]: info: Starting Shairport Sync Oct 10 17:15:40 volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:40 volumio sudo[1806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 17:15:40 volumio sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:40 volumio volumio[1095]: info: Starting Shairport Sync Oct 10 17:15:40 volumio sudo[1806]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 10 17:15:40 volumio sudo[1806]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:40 volumio sudo[1809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 17:15:40 volumio sudo[1809]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 17:15:40 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 17:15:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:40 volumio sudo[1809]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:40 volumio sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 17:15:40 volumio volumio[1095]: info: Starting Shairport Sync Oct 10 17:15:40 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 17:15:40 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 17:15:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:40 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 17:15:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:40 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:40 volumio sudo[1812]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 17:15:41 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 17:15:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:41 volumio sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 17:15:41 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:41 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:41 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 17:15:41 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 17:15:41 volumio RoonBridge[1654]: Running Oct 10 17:15:41 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:41 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 10 17:15:41 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 10 17:15:41 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:41 volumio volumio[1095]: error: MPD error: Error: write EPIPE Oct 10 17:15:41 volumio volumio[1095]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 10 17:15:41 volumio volumio[1095]: error: MPD error: The expression evaluated to a falsy value: Oct 10 17:15:41 volumio volumio[1095]: assert.ok(self.idling) Oct 10 17:15:41 volumio volumio[1095]: error: The expression evaluated to a falsy value: Oct 10 17:15:41 volumio volumio[1095]: assert.ok(self.idling) Oct 10 17:15:41 volumio volumio[1095]: error: MPD error: Error: write EPIPE Oct 10 17:15:41 volumio volumio[1095]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 10 17:15:41 volumio volumio[1095]: error: MPD error: The expression evaluated to a falsy value: Oct 10 17:15:41 volumio volumio[1095]: assert.ok(self.idling) Oct 10 17:15:41 volumio volumio[1095]: error: The expression evaluated to a falsy value: Oct 10 17:15:41 volumio volumio[1095]: assert.ok(self.idling) Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:41 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:41 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:42 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:15:42 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 10 17:15:42 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 17:15:42 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:42 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:42 volumio volumio[1095]: error: MPD error: Error: write EPIPE Oct 10 17:15:42 volumio volumio[1095]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Oct 10 17:15:42 volumio screensaver.sh[744]: 1638 Oct 10 17:15:43 volumio volumio[1095]: info: Shairport-Sync Started Oct 10 17:15:43 volumio volumio[1095]: Error adding Membership: Error: addMembership EINVAL Oct 10 17:15:43 volumio volumio[1095]: info: Shairport-Sync Started Oct 10 17:15:43 volumio volumio[1095]: info: Shairport-Sync Started Oct 10 17:15:43 volumio volumio[1095]: info: Shairport-Sync Started Oct 10 17:15:43 volumio volumio[1095]: info: Volumio called home Oct 10 17:15:43 volumio volumio[1095]: warn: peppy_screensaver: An error occurred on pygame check Command failed: python3 -c "import pygame" Oct 10 17:15:43 volumio volumio[1095]: Terminated Oct 10 17:15:43 volumio volumio[1095]: {"killed":false,"code":143,"signal":null,"cmd":"python3 -c \"import pygame\"","stack":"Error: Command failed: python3 -c \"import pygame\"\nTerminated\n\n at ChildProcess.exithandler (child_process.js:383:12)\n at ChildProcess.emit (events.js:400:28)\n at maybeClose (internal/child_process.js:1088:16)\n at Socket. (internal/child_process.js:446:11)\n at Socket.emit (events.js:400:28)\n at Pipe. (net.js:686:12)"} Oct 10 17:15:43 volumio jellyfin[1610]: [17:15:43] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"] Oct 10 17:15:43 volumio volumio[1095]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 10 17:15:44 volumio volumio-remote-updater[735]: [2024-10-10 17:15:44] [connect] Successful connection Oct 10 17:15:44 volumio sudo[1877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 10 17:15:44 volumio sudo[1877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:44 volumio volumio-remote-updater[735]: [2024-10-10 17:15:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728551744 101 Oct 10 17:15:44 volumio volumio[1095]: 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: 4 Oct 10 17:15:44 volumio sudo[1877]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:44 volumio volumio[1095]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 10 17:15:44 volumio jellyfin[1610]: [17:15:44] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Oct 10 17:15:45 volumio volumio[1095]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 10 17:15:45 volumio RoonBridge[1654]: 00:00:00.442 Warn: get lock file path: /tmp/.rnbhgem0- Oct 10 17:15:45 volumio sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 10 17:15:45 volumio sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:45 volumio sudo[1901]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:46 volumio jellyfin[1610]: [17:15:46] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Oct 10 17:15:46 volumio volumio[1095]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 10 17:15:46 volumio sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 10 17:15:46 volumio sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:46 volumio sudo[1931]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:46 volumio volumio[1095]: info: Completed starting Core Plugins Oct 10 17:15:46 volumio volumio[1095]: info: ------------------------------------------- Oct 10 17:15:46 volumio volumio[1095]: info: ----- MyVolumio plugins startup ---- Oct 10 17:15:46 volumio volumio[1095]: info: ------------------------------------------- Oct 10 17:15:46 volumio volumio[1095]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 10 17:15:47 volumio mpd[1813]: Oct 10 17:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 17:15:47 volumio systemd[1]: Started Music Player Daemon. Oct 10 17:15:47 volumio sudo[1802]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:47 volumio volumio[1095]: error: updateQueue error: null Oct 10 17:15:48 volumio RoonBridge[1654]: 00:00:13.376 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 10 17:15:48 volumio RoonBridge[1654]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 10 17:15:48 volumio RoonBridge[1654]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 10 17:15:48 volumio RoonBridge[1654]: Not Running (.o) Oct 10 17:15:48 volumio sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 17:15:48 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:48 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:48 volumio RoonBridge[1654]: 00:00:13.584 Debug: PathForResource, filename: ../.update Oct 10 17:15:48 volumio RoonBridge[1654]: 00:00:13.601 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 10 17:15:48 volumio sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 17:15:48 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:48 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:48 volumio jellyfin[1610]: [17:15:48] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Oct 10 17:15:48 volumio jellyfin[1610]: [17:15:48] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Oct 10 17:15:48 volumio jellyfin[1610]: [17:15:48] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Oct 10 17:15:48 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.31.153 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: 6 Oct 10 17:15:49 volumio jellyfin[1610]: [17:15:49] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.31.153"] Oct 10 17:15:49 volumio jellyfin[1610]: [17:15:49] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Oct 10 17:15:49 volumio jellyfin[1610]: [17:15:49] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Oct 10 17:15:49 volumio jellyfin[1610]: [17:15:49] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Oct 10 17:15:49 volumio volumio[1529]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:49 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 17:15:49 volumio volumio[1095]: info: Received Get System Info Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:15:49 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:49 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:49 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:49 volumio volumio[1095]: info: Listing playlists Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 17:15:49 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 17:15:49 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 17:15:49 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:50 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:50 volumio volumio[1095]: info: AutoStart - Plugin is starting Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioGetQueue Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::getQueue Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getQueue Oct 10 17:15:50 volumio volumio[1095]: info: AutoStart - start playing Oct 10 17:15:50 volumio volumio[1095]: info: AutoStart - start playing with no specific position Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPlay Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::play index 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::stop Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 10 17:15:50 volumio sudo[2052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::play index undefined Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::startPlaybackTimer Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand stop Oct 10 17:15:50 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand stop took 69 milliseconds Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand clear Oct 10 17:15:50 volumio volumio[1095]: info: Oct 10 17:15:50 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Oct 10 17:15:50 volumio volumio[1095]: info: Ignoring MPD Status Update Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand clear took 43 milliseconds Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Oct 10 17:15:50 volumio volumio[1095]: verbose: New Socket.io Connection to 192.168.31.153 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 10 17:15:50 volumio volumio[1095]: error: updateQueue error: null Oct 10 17:15:50 volumio volumio[1095]: info: Oct 10 17:15:50 volumio volumio[1095]: ---------------------------- MPD announces system playlist update Oct 10 17:15:50 volumio volumio[1095]: info: Ignoring MPD Status Update Oct 10 17:15:50 volumio volumio[1095]: info: ------------------------------ 106ms Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 91 milliseconds Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand play Oct 10 17:15:50 volumio volumio[1095]: info: Oct 10 17:15:50 volumio volumio[1095]: ---------------------------- MPD announces state update: player Oct 10 17:15:50 volumio volumio[1095]: info: ------------------------------ 119ms Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand play took 104 milliseconds Oct 10 17:15:50 volumio volumio[1095]: info: ControllerMpd::getState Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 17:15:50 volumio volumio[1095]: info: Oct 10 17:15:50 volumio volumio[1095]: ---------------------------- MPD announces state update: player Oct 10 17:15:50 volumio volumio[1095]: info: ControllerMpd::getState Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand status took 37 milliseconds Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::parseState Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand status took 23 milliseconds Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::parseState Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Oct 10 17:15:50 volumio volumio[1095]: info: ControllerMpd::pushState Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: 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 10 17:15:50 volumio volumio[1095]: verbose: CURRENT POSITION 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus stop Oct 10 17:15:50 volumio volumio[1095]: info: ------------------------------ 106ms Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioGetVisibleSources Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 10 17:15:50 volumio volumio[1095]: info: Received Get System Info Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:15:50 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: info: Listing playlists Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 17:15:50 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 137 milliseconds Oct 10 17:15:50 volumio RoonBridge[1654]: 00:00:15.602 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 10 17:15:50 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Oct 10 17:15:50 volumio volumio[1095]: info: ControllerMpd::pushState Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: 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 10 17:15:50 volumio volumio[1095]: verbose: CURRENT POSITION 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::syncState stateService play Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus play Oct 10 17:15:50 volumio volumio[1095]: info: Received an update from plugin. extracting info from payload Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 17:15:50 volumio RoonBridge[1654]: Running Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:15:50 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:15:50 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:50 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:15:50 volumio volumio[1095]: info: ------------------------------ 304ms Oct 10 17:15:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 10 17:15:51 volumio volumio[1095]: info: Received Get System Info Oct 10 17:15:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:15:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:15:51 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:15:51 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:51 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:51 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:15:52 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 10 17:15:54 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:15:54 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:15:54 volumio volumio[1095]: 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: 8 Oct 10 17:15:54 volumio sudo[2107]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 10 17:15:54 volumio sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:15:56 volumio RoonBridge[1654]: 00:00:21.639 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 10 17:15:56 volumio RoonBridge[1654]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 10 17:15:56 volumio RoonBridge[1654]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 10 17:15:56 volumio RoonBridge[1654]: Not Running (.o) Oct 10 17:15:56 volumio RoonBridge[1654]: 00:00:21.648 Debug: PathForResource, filename: ../.update Oct 10 17:15:56 volumio RoonBridge[1654]: 00:00:21.648 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 10 17:15:57 volumio volumio[1095]: info: Adding plugin bluetooth to MyMusic Plugins Oct 10 17:15:57 volumio volumio[1095]: info: Adding plugin multiroom to MyMusic Plugins Oct 10 17:15:57 volumio volumio[1095]: info: Adding plugin metavolumio to MyMusic Plugins Oct 10 17:15:57 volumio volumio[1095]: info: Adding plugin cd_controller to MyMusic Plugins Oct 10 17:15:57 volumio volumio[1095]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 10 17:15:57 volumio volumio[1095]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 10 17:15:57 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 10 17:15:58 volumio volumio[1529]: ................................................................................................++++ Oct 10 17:15:58 volumio RoonBridge[1654]: 00:00:23.652 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 10 17:15:58 volumio RoonBridge[1654]: Running Oct 10 17:16:00 volumio ntpd[1093]: Soliciting pool server 162.159.200.123 Oct 10 17:16:01 volumio ntpd[1093]: Soliciting pool server 162.159.200.123 Oct 10 17:16:01 volumio volumio[1529]: .....................................++++ Oct 10 17:16:01 volumio volumio[1529]: e is 65537 (0x010001) Oct 10 17:16:01 volumio volumio[1529]: writing RSA key Oct 10 17:16:02 volumio volumio[1095]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 10 17:16:02 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 10 17:16:02 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:16:02 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:16:02 volumio volumio[1095]: info: Starting MyVolumio Remote Streaming Endpoints Oct 10 17:16:02 volumio volumio[1095]: info: MyVolumio login type: Token Oct 10 17:16:02 volumio volumio[1095]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 10 17:16:02 volumio volumio[1095]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 10 17:16:03 volumio ntpd[1093]: Soliciting pool server 202.118.1.130 Oct 10 17:16:04 volumio ntpd[1093]: Soliciting pool server 193.182.111.142 Oct 10 17:16:04 volumio RoonBridge[1654]: 00:00:29.714 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 10 17:16:04 volumio RoonBridge[1654]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 10 17:16:04 volumio RoonBridge[1654]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 10 17:16:04 volumio RoonBridge[1654]: 00:00:29.726 Debug: PathForResource, filename: ../.update Oct 10 17:16:04 volumio RoonBridge[1654]: 00:00:29.726 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 10 17:16:04 volumio RoonBridge[1654]: Not Running (.o) Oct 10 17:16:06 volumio volumio[1095]: info: Starting Streaming Service Transparent Proxy Oct 10 17:16:06 volumio volumio[1095]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 10 17:16:06 volumio volumio[1095]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 10 17:16:06 volumio volumio[1095]: info: Streaming services startup Oct 10 17:16:06 volumio volumio[1095]: info: Starting Streaming Daemon Oct 10 17:16:06 volumio sudo[2186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 17:16:06 volumio sudo[2186]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:16:06 volumio sudo[2186]: pam_unix(sudo:session): session closed for user root Oct 10 17:16:06 volumio volumio[1095]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 10 17:16:06 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 17:16:06 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:16:06 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 17:16:06 volumio RoonBridge[1654]: 00:00:31.726 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 10 17:16:06 volumio RoonBridge[1654]: Running Oct 10 17:16:06 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 10 17:16:07 volumio volumio[1095]: error: Cannot start Volumio Streaming Daemon Oct 10 17:16:07 volumio volumio[1095]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 10 17:16:07 volumio volumio[1095]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 10 17:16:07 volumio ntpd[1093]: Soliciting pool server 162.159.200.123 Oct 10 17:16:07 volumio volumio[1095]: Upnp client error: Error: This socket has been ended by the other party Oct 10 17:16:07 volumio volumio[1095]: 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: 8 Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:16:07 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:07 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:16:07 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:07 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:07 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:16:07 volumio volumio[1095]: Upnp client error: Error: This socket has been ended by the other party Oct 10 17:16:08 volumio volumio[1095]: error: MyVolumio Custom Token format not valid, refreshing it Oct 10 17:16:08 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:08 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:08 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 17:16:09 volumio volumio-remote-updater[735]: No test mode Oct 10 17:16:09 volumio volumio-remote-updater[735]: No alpha test mode Oct 10 17:16:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 10 17:16:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 10 17:16:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:16:09 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 10 17:16:10 volumio volumio[1095]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 10 17:16:10 volumio volumio[1095]: info: Received Get System Version Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 17:16:10 volumio volumio[1095]: info: Received Get System Info Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 10 17:16:10 volumio volumio[1095]: info: Discovery: Getting this device information Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:10 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:10 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 10 17:16:11 volumio volumio[1095]: STREAMING PROXY: Starting server on port 3245 Oct 10 17:16:11 volumio volumio[1095]: Node JS runtime: 14 Oct 10 17:16:11 volumio volumio[1095]: info: MyVolumio login type: Token Oct 10 17:16:11 volumio volumio[1095]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 10 17:16:11 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 10 17:16:12 volumio volumio[1095]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 10 17:16:12 volumio RoonBridge[1654]: 00:00:37.756 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 10 17:16:12 volumio RoonBridge[1654]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 10 17:16:12 volumio RoonBridge[1654]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 10 17:16:12 volumio RoonBridge[1654]: Not Running (.o) Oct 10 17:16:12 volumio RoonBridge[1654]: 00:00:37.772 Debug: PathForResource, filename: ../.update Oct 10 17:16:12 volumio RoonBridge[1654]: 00:00:37.783 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 10 17:16:13 volumio volumio[1095]: 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 10 17:16:13 volumio volumio[1095]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 10 17:16:13 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 10 17:16:13 volumio volumio[1095]: info: Preparing to save Alsa Options, stopping services first Oct 10 17:16:13 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:13 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:13 volumio volumio[1095]: info: CoreCommandRouter::volumioPause Oct 10 17:16:13 volumio volumio[1095]: info: CoreStateMachine::pause Oct 10 17:16:13 volumio volumio[1095]: info: CoreStateMachine::stPlaybackTimer Oct 10 17:16:13 volumio volumio[1095]: info: CoreStateMachine::servicePause Oct 10 17:16:13 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:13 volumio volumio[1095]: info: CoreCommandRouter::servicePause Oct 10 17:16:13 volumio volumio[1095]: info: ControllerMpd::pause Oct 10 17:16:13 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand pause Oct 10 17:16:13 volumio volumio[1095]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB AUDIO 2.0 Device"}} Oct 10 17:16:13 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:14 volumio volumio[1095]: info: Setting mixer USB AUDIO 2.0 Device for card USB AUDIO 2.0 Device Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 10 17:16:14 volumio volumio[1095]: info: Updating Volume Controller Parameters: Device: 5 Name: USB AUDIO 2.0 Device Mixer: USB AUDIO 2.0 Device Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 10 17:16:14 volumio volumio[1095]: info: Disabling external Volume Control Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 17:16:14 volumio volumio[1095]: info: Preparing to generate the ALSA configuration file Oct 10 17:16:14 volumio RoonBridge[1654]: 00:00:39.783 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 10 17:16:14 volumio RoonBridge[1654]: Running Oct 10 17:16:14 volumio volumio[1095]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 10 17:16:14 volumio volumio[1095]: info: Preparing to save Alsa Options, stopping services first Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:14 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::volumioPause Oct 10 17:16:14 volumio volumio[1095]: info: CoreStateMachine::pause Oct 10 17:16:14 volumio volumio[1095]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB AUDIO 2.0 Device"}} Oct 10 17:16:14 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:15 volumio volumio[1095]: info: Setting mixer USB AUDIO 2.0 Device for card USB AUDIO 2.0 Device Oct 10 17:16:15 volumio volumio[1095]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 10 17:16:15 volumio volumio[1095]: info: Updating Volume Controller Parameters: Device: 5 Name: USB AUDIO 2.0 Device Mixer: USB AUDIO 2.0 Device Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 10 17:16:15 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 10 17:16:15 volumio volumio[1095]: info: Disabling external Volume Control Oct 10 17:16:15 volumio volumio[1095]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 10 17:16:15 volumio volumio[1095]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 10 17:16:15 volumio volumio[1095]: info: Reading ALSA contributions from plugins. Oct 10 17:16:16 volumio volumio[1095]: info: Oct 10 17:16:16 volumio volumio[1095]: ---------------------------- MPD announces state update: player Oct 10 17:16:16 volumio volumio[1095]: info: sendMpdCommand pause took 2411 milliseconds Oct 10 17:16:16 volumio volumio[1095]: info: ControllerMpd::getState Oct 10 17:16:16 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand status Oct 10 17:16:16 volumio volumio[1095]: info: MyVolumio token set successfully Oct 10 17:16:16 volumio volumio[1095]: info: MYVOLUMIO: Adding device Oct 10 17:16:16 volumio volumio[1095]: info: MYVOLUMIO: Evaluating Server Oct 10 17:16:16 volumio volumio[1095]: info: sendMpdCommand status took 194 milliseconds Oct 10 17:16:16 volumio volumio[1095]: verbose: ControllerMpd::parseState Oct 10 17:16:16 volumio volumio[1095]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 10 17:16:16 volumio volumio[1095]: Upnp client error: Error: This socket has been ended by the other party Oct 10 17:16:16 volumio volumio[1095]: info: sendMpdCommand playlistinfo took 146 milliseconds Oct 10 17:16:16 volumio volumio[1095]: verbose: ControllerMpd::parseTrackInfo Oct 10 17:16:16 volumio volumio[1095]: info: ControllerMpd::pushState Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::servicePushState Oct 10 17:16:16 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:16 volumio volumio[1095]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":25043,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"796 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 10 17:16:16 volumio volumio[1095]: verbose: CURRENT POSITION 0 Oct 10 17:16:16 volumio volumio[1095]: info: CoreStateMachine::syncState stateService pause Oct 10 17:16:16 volumio volumio[1095]: info: CoreStateMachine::syncState currentStatus pause Oct 10 17:16:16 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:16:16 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:16:16 volumio volumio[1095]: info: CoreStateMachine::stPlaybackTimer Oct 10 17:16:16 volumio volumio[1095]: info: ------------------------------ 503ms Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::volumioGetState Oct 10 17:16:16 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:16 volumio volumio[1095]: info: VolumeController:: Volume=100 Mute =false Oct 10 17:16:16 volumio volumio[1095]: info: CoreStateMachine::pushState Oct 10 17:16:16 volumio volumio[1095]: info: CorePlayQueue::getTrack 0 Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::volumioPushState Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:16:16 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 10 17:16:17 volumio volumio[1095]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 10 17:16:18 volumio volumio[1095]: info: MyVolumio status changed Oct 10 17:16:18 volumio volumio[1095]: info: Streaming services startup Oct 10 17:16:18 volumio volumio[1095]: info: Starting Streaming Daemon Oct 10 17:16:18 volumio jellyfin[1610]: [17:16:18] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Oct 10 17:16:19 volumio jellyfin[1610]: [17:16:19] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Oct 10 17:16:19 volumio jellyfin[1610]: [17:16:19] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Oct 10 17:16:19 volumio volumio[1095]: info: Removing browser output: myVolumio user plan is not superstar Oct 10 17:16:19 volumio volumio[1095]: info: Removing audio output: Oct 10 17:16:19 volumio volumio[1095]: info: Stoppping Tunnel 1 Oct 10 17:16:19 volumio sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 10 17:16:19 volumio sudo[2308]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:16:19 volumio volumio[1095]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 17:16:19 volumio volumio[1095]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 10 17:16:19 volumio volumio[1095]: at Object.readdirSync (fs.js:1048:3) Oct 10 17:16:19 volumio volumio[1095]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 10 17:16:19 volumio volumio[1095]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 10 17:16:19 volumio volumio[1095]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 10 17:16:19 volumio volumio[1095]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 10 17:16:19 volumio volumio[1095]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 10 17:16:19 volumio volumio[1095]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 10 17:16:19 volumio volumio[1095]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 10 17:16:19 volumio volumio[1095]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 10 17:16:19 volumio volumio[1095]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 10 17:16:19 volumio volumio[1095]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 10 17:16:19 volumio volumio[1095]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 10 17:16:19 volumio volumio[1095]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 10 17:16:19 volumio volumio[1095]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 10 17:16:19 volumio volumio[1095]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 10 17:16:19 volumio volumio[1095]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 10 17:16:19 volumio volumio[1095]: errno: -20, Oct 10 17:16:19 volumio volumio[1095]: syscall: 'scandir', Oct 10 17:16:19 volumio volumio[1095]: code: 'ENOTDIR', Oct 10 17:16:19 volumio volumio[1095]: path: '/tmp/peppyrunning' Oct 10 17:16:19 volumio volumio[1095]: } Oct 10 17:16:19 volumio volumio[1095]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 17:16:19 volumio sudo[2308]: pam_unix(sudo:session): session closed for user root Oct 10 17:16:19 volumio sudo[2310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 10 17:16:19 volumio sudo[2310]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 17:16:19 volumio jellyfin[1610]: [17:16:19] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Oct 10 17:16:19 volumio jellyfin[1610]: [17:16:19] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Oct 10 17:16:19 volumio sudo[2310]: pam_unix(sudo:session): session closed for user root Oct 10 17:16:20 volumio jellyfin[1610]: [17:16:20] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["DisableTranscodingThrottling", "CreateLoggingConfigHeirarchy", "MigrateActivityLogDatabase", "RemoveDuplicateExtras", "MigrateUserDatabase", "MigrateDisplayPreferencesDatabase", "RemoveDownloadImagesInAdvance", "MigrateAuthenticationDatabase", "FixPlaylistOwner", "MigrateRatingLevels", "FixAudioData"] Oct 10 17:16:20 volumio sudo[2328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 17:15 Oct 10 17:16:20 volumio sudo[2328]: 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"