-- Logs begin at Thu 2024-10-17 10:18:39 CST, end at Thu 2024-10-17 10:20:35 CST. -- Oct 17 10:19:00 volumio ntpd[1252]: Soliciting pool server 103.147.22.149 Oct 17 10:19:00 volumio ntpd[1252]: Soliciting pool server 2406:2000:fc:437::2000 Oct 17 10:19:00 volumio ntpd[1252]: Soliciting pool server 183.177.72.202 Oct 17 10:19:00 volumio volumio[922]: info: Applying required configuration parameters for plugin peppy_screensaver Oct 17 10:19:00 volumio volumio[922]: info: Loading plugin "outputs"... Oct 17 10:19:00 volumio volumio[922]: info: Loading plugin "albumart"... Oct 17 10:19:00 volumio volumio[922]: info: Plugin example_plugin is not enabled Oct 17 10:19:00 volumio volumio[922]: info: Loading plugin "inputs"... Oct 17 10:19:00 volumio volumio[922]: info: Loading plugin "updater_comm"... Oct 17 10:19:00 volumio volumio[922]: Forking 1 albumart workers Oct 17 10:19:01 volumio ntpd[1252]: Soliciting pool server 112.104.189.124 Oct 17 10:19:01 volumio volumio[922]: info: Plugin mpdemulation is not enabled Oct 17 10:19:01 volumio volumio[922]: info: Loading plugin "rest_api"... Oct 17 10:19:01 volumio volumio[922]: info: Loading plugin "websocket"... Oct 17 10:19:01 volumio volumio[922]: info: Starting Socket.io Server version 2.3.0 Oct 17 10:19:01 volumio volumio[922]: info: Loading plugin "RoonBridge"... Oct 17 10:19:01 volumio volumio[922]: info: Applying required configuration parameters for plugin RoonBridge Oct 17 10:19:01 volumio volumio[922]: info: Loading plugin "lms"... Oct 17 10:19:01 volumio volumio[922]: Starting albumart workers Oct 17 10:19:01 volumio volumio[922]: info: Loading plugin "minidlna"... Oct 17 10:19:01 volumio volumio[922]: info: Applying required configuration parameters for plugin minidlna Oct 17 10:19:01 volumio volumio-remote-updater[673]: [2024-10-17 10:19:01] [connect] Successful connection Oct 17 10:19:01 volumio volumio[922]: info: Loading i18n strings for locale zh Oct 17 10:19:01 volumio volumio[922]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 10:19:01 volumio volumio[922]: Updating browse sources language Oct 17 10:19:01 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1c0d8c04 does not match aorg 0000000000.00000000 from server@220.134.230.37 xmt 0xeabaf2a2.f521a568 Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1c04dd6f does not match aorg 0000000000.00000000 from server@125.229.106.76 xmt 0xeabaf2a2.f5392ef9 Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1c032100 does not match aorg 0000000000.00000000 from server@210.243.152.152 xmt 0xeabaf2a2.f559d987 Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1c08cd61 does not match aorg 0000000000.00000000 from server@114.33.15.129 xmt 0xeabaf2a2.f56c1ecc Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1c0f3746 does not match aorg 0000000000.00000000 from server@118.163.81.63 xmt 0xeabaf2a2.f5ef1117 Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1c000029 does not match aorg 0000000000.00000000 from server@114.35.131.27 xmt 0xeabaf2a2.f5cc1c3c Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::initPlayerControls Oct 17 10:19:14 volumio ntpd[1252]: receive: Unexpected origin timestamp 0xeabaf296.1bfa98a1 does not match aorg 0000000000.00000000 from server@122.117.253.246 xmt 0xeabaf2a2.f6556f17 Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:14 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:19:14 volumio volumio[922]: Express server listening on port 3000 Oct 17 10:19:14 volumio volumio[922]: [Metrics] WebUI: 13s 548.50ms Oct 17 10:19:14 volumio volumio[922]: info: Setting Device type: x86 Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::resetVolumioState Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::getcurrentVolume Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:15 volumio volumio[922]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 17 10:19:15 volumio volumio[922]: info: Completed loading Core Plugins Oct 17 10:19:15 volumio volumio[922]: info: Preparing to generate the ALSA configuration file Oct 17 10:19:15 volumio volumio[922]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 17 10:19:15 volumio volumio[922]: wlan0 Interface doesn't support scanning. Oct 17 10:19:15 volumio volumio[922]: info: Cannot use regular scanning, forcing with ap-force Oct 17 10:19:15 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 17 10:19:15 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:15 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:15 volumio volumio[922]: command failed: No such device (-19) Oct 17 10:19:15 volumio volumio[922]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 17 10:19:15 volumio volumio[922]: command failed: No such device (-19) Oct 17 10:19:15 volumio volumio[922]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 17 10:19:15 volumio volumio[922]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 17 10:19:15 volumio volumio[922]: info: Reading ALSA contributions from plugins. Oct 17 10:19:15 volumio volumio-remote-updater[673]: [2024-10-17 10:19:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729131541 101 Oct 17 10:19:15 volumio volumio[922]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:15 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:15 volumio volumio[922]: 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 17 10:19:15 volumio volumio[922]: info: Reloading queue from file Oct 17 10:19:15 volumio volumio[922]: info: VolumeController:: Volume=39 Mute =false Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:15 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::updateTrackBlock Oct 17 10:19:15 volumio volumio[922]: info: CorePlayQueue::getTrackBlock Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::setRepeat null single undefined Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:15 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::setRandom null Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:15 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:15 volumio volumio[922]: info: VolumeController:: Volume=39 Mute =false Oct 17 10:19:15 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:15 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:15 volumio volumio[922]: info: Asound.conf file unchanged, so no further update is needed Oct 17 10:19:15 volumio volumio[922]: info: Output device has changed, restarting MPD Oct 17 10:19:15 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 10:19:15 volumio volumio[922]: info: Output device has changed, restarting Shairport Sync Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:15 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:15 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:15 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:16 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 10:19:16 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 volumio systemd[1]: Listening on mpd.socket. Oct 17 10:19:16 volumio volumio[922]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 10:19:16 volumio volumio[922]: info: ___________ START PLUGINS ___________ Oct 17 10:19:16 volumio volumio[922]: info: ControllerMpd::onStart: Initializing MPD Oct 17 10:19:16 volumio volumio[922]: info: Creating MPD Configuration file Oct 17 10:19:16 volumio systemd[1]: Starting Music Player Daemon... Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 10:19:16 volumio volumio[922]: info: [1729131556155] CoreMusicLibrary::Adding element 媒体服务器 Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:19:16 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 10:19:16 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:16 volumio sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 10:19:16 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:16 volumio sudo[1422]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 10:19:16 volumio sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 volumio sudo[1422]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:16 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 17 10:19:16 volumio systemd[1]: mpd.service: Succeeded. Oct 17 10:19:16 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 10:19:16 volumio systemd[1]: Starting Music Player Daemon... Oct 17 10:19:16 volumio volumio[922]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:16 volumio volumio[922]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 10:19:16 volumio volumio[922]: info: [1729131556375] CoreMusicLibrary::Adding element Last_100 Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 10:19:16 volumio volumio[922]: info: [1729131556378] CoreMusicLibrary::Adding element Webradio Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 10:19:16 volumio volumio[922]: info: Initializing BBC Radios Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:19:16 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:16 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:19:16 volumio sudo[1432]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 10:19:16 volumio volumio[922]: info: [1729131556496] CoreMusicLibrary::Adding element Jellyfin Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:19:16 volumio volumio[922]: Cannot find translation for source Jellyfin Oct 17 10:19:16 volumio volumio[922]: info: [jellyfin] Initialized plugin with device info: {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://192.168.10.112","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":39,"mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://192.168.10.112/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 17 10:19:16 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 volumio sudo[1432]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 17 10:19:16 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:16 volumio volumio[922]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true Oct 17 10:19:16 volumio volumio[922]: info: AutoStart - onStart Oct 17 10:19:16 volumio volumio[922]: info: Loading i18n strings for locale zh Oct 17 10:19:16 volumio volumio[922]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 10:19:16 volumio sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin Oct 17 10:19:16 volumio sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 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 17 10:19:16 volumio volumio[922]: info: /tmp/myfifo created Oct 17 10:19:16 volumio systemd[1]: Starting Jellyfin... Oct 17 10:19:16 volumio systemd[1]: Started Jellyfin. Oct 17 10:19:16 volumio sudo[1449]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:16 volumio volumio[922]: info: /tmp/myfifosa created Oct 17 10:19:16 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Oct 17 10:19:16 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:16 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:16 volumio volumio[922]: info: snd-dummy loaded Oct 17 10:19:16 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:17 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:17 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Oct 17 10:19:17 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:17 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:17 volumio volumio[922]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 10:19:17 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Oct 17 10:19:17 volumio sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:17 volumio volumio[922]: info: Loading i18n strings for locale zh Oct 17 10:19:17 volumio volumio[922]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 17 10:19:17 volumio sudo[1486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Oct 17 10:19:17 volumio sudo[1486]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:17 volumio systemd[1]: Started RoonBridge. Oct 17 10:19:17 volumio sudo[1483]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:17 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 17 10:19:17 volumio volumio[922]: info: Volumio Calling Home Oct 17 10:19:17 volumio systemd[1]: Starting Logitech Media Server Daemon... Oct 17 10:19:17 volumio systemd[1]: Started Logitech Media Server Daemon. Oct 17 10:19:17 volumio systemd[1503]: logitechmediaserver.service: Failed to execute command: No such file or directory Oct 17 10:19:17 volumio systemd[1503]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory Oct 17 10:19:17 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC Oct 17 10:19:17 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Oct 17 10:19:17 volumio sudo[1486]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:18 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 10:19:18 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:18 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:18 volumio volumio[922]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 17 10:19:18 volumio volumio[922]: info: Discovery: Found device Volumio Oct 17 10:19:18 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:18 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:18 volumio sudo[1511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 10:19:18 volumio sudo[1511]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:18 volumio sudo[1511]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:18 volumio volumio[922]: info: logitechmediaserver started Oct 17 10:19:18 volumio volumio[922]: info: MPD Permissions set Oct 17 10:19:18 volumio volumio[922]: info: MPD Permissions set Oct 17 10:19:18 volumio volumio[922]: info: minidlna: Found miniDLNA version 1.2.1 Oct 17 10:19:18 volumio volumio[922]: info: minidlna: Starting minidlna.service Oct 17 10:19:18 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 17 10:19:19 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:19 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 17 10:19:19 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:19 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Oct 17 10:19:19 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:19 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:19 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:19 volumio volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 17 10:19:19 volumio volumio[922]: info: Volumio called home Oct 17 10:19:19 volumio volumio[922]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 10:19:19 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 10:19:19 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 17 10:19:19 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:19 volumio systemd[1]: minidlna.service: Succeeded. Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:20 volumio RoonBridge[1488]: 00:00:00.040 Warn: get lock file path: /tmp/.rnbgem0- Oct 17 10:19:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 10:19:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Oct 17 10:19:20 volumio volumio[922]: info: minidlna: systemctl start minidlna.service succeeded. Oct 17 10:19:20 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 10:19:20 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:20 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:20 volumio volumio[922]: info: MPD Permissions set Oct 17 10:19:20 volumio volumio[922]: info: Starting Shairport Sync Oct 17 10:19:20 volumio volumio[922]: info: Starting Shairport Sync Oct 17 10:19:20 volumio volumio[922]: info: Starting Shairport Sync Oct 17 10:19:20 volumio volumio[922]: info: Starting Shairport Sync Oct 17 10:19:20 volumio sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 10:19:20 volumio sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:20 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 10:19:20 volumio sudo[1549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 10:19:20 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:20 volumio sudo[1553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 10:19:20 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:20 volumio sudo[1553]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:20 volumio RoonBridge[1488]: 00:00:01.325 Trace: [childprocess] using unix child process Oct 17 10:19:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 10:19:20 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 10:19:20 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:20 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 10:19:21 volumio sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 10:19:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 10:19:21 volumio sudo[1547]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 17 10:19:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 10:19:21 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 17 10:19:21 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 17 10:19:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 17 10:19:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 10:19:21 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.577 Debug: PathForResource, filename: ../.update Oct 17 10:19:21 volumio sudo[1553]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio mpd[1447]: Oct 17 10:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 10:19:21 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 17 10:19:21 volumio volumio[922]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 10:19:21 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio systemd[1]: Started Music Player Daemon. Oct 17 10:19:21 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.707 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.737 Debug: PathForResource, filename: RAATServer Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.737 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 10:19:21 volumio sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 10:19:21 volumio sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.754 Debug: PathForResource, filename: RoonBridgeHelper Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.754 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 17 10:19:21 volumio RoonBridge[1488]: Initializing Oct 17 10:19:21 volumio systemd[1]: Stopping Music Player Daemon... Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio systemd[1]: mpd.service: Succeeded. Oct 17 10:19:21 volumio systemd[1]: Stopped Music Player Daemon. Oct 17 10:19:21 volumio systemd[1]: Starting Music Player Daemon... Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:01.911 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Oct 17 10:19:21 volumio sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 10:19:21 volumio sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:21 volumio RoonBridge[1488]: 00:00:02.036 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 10:19:21 volumio sudo[1576]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:21 volumio sudo[1582]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 10:19:21 volumio sudo[1582]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:21 volumio sudo[1582]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:21 volumio volumio[922]: info: Upmpdcli Daemon Started Oct 17 10:19:21 volumio volumio[922]: info: Shairport-Sync Started Oct 17 10:19:21 volumio volumio[922]: Error adding Membership: Error: addMembership EINVAL Oct 17 10:19:21 volumio volumio[922]: info: Shairport-Sync Started Oct 17 10:19:21 volumio volumio[922]: info: Shairport-Sync Started Oct 17 10:19:21 volumio RoonBridge[1488]: Not Running (.o) Oct 17 10:19:21 volumio volumio[922]: info: Shairport-Sync Started Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:21] [INF] [1] Main: Jellyfin version: 10.9.6 Oct 17 10:19:22 volumio volumio[922]: info: MPD running with PID1588 Oct 17 10:19:22 volumio volumio[922]: ,establishing connection Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [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 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Architecture: X64 Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: 64-Bit Process: True Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: User Interactive: True Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Processor count: 2 Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Program data path: /opt/jellyfin/data Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Log directory path: /opt/jellyfin/log Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Config directory path: /opt/jellyfin/config Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Cache path: /opt/jellyfin/cache Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Oct 17 10:19:22 volumio jellyfin[1453]: [10:19:22] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Oct 17 10:19:22 volumio volumio[922]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 10:19:22 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 10:19:22 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:22 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:23 volumio RoonBridge[1488]: Running Oct 17 10:19:23 volumio volumio[922]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 10:19:23 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 10:19:23 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:23 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:24 volumio volumio[922]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 17 10:19:24 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 17 10:19:24 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:24 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:24 volumio jellyfin[1453]: [10:19:24] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"] Oct 17 10:19:24 volumio volumio[922]: info: Completed starting Core Plugins Oct 17 10:19:24 volumio volumio[922]: info: ------------------------------------------- Oct 17 10:19:24 volumio volumio[922]: info: ----- MyVolumio plugins startup ---- Oct 17 10:19:24 volumio volumio[922]: info: ------------------------------------------- Oct 17 10:19:24 volumio volumio[922]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 10:19:24 volumio mpd[1588]: Oct 17 10:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 10:19:24 volumio systemd[1]: Started Music Player Daemon. Oct 17 10:19:24 volumio sudo[1569]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:24 volumio volumio[922]: error: MPD error: The expression evaluated to a falsy value: Oct 17 10:19:24 volumio volumio[922]: assert.ok(self.idling) Oct 17 10:19:24 volumio volumio[922]: error: The expression evaluated to a falsy value: Oct 17 10:19:24 volumio volumio[922]: assert.ok(self.idling) Oct 17 10:19:24 volumio volumio[922]: Upnp client error: Error: This socket has been ended by the other party Oct 17 10:19:24 volumio volumio[922]: error: MPD error: The expression evaluated to a falsy value: Oct 17 10:19:24 volumio volumio[922]: assert.ok(self.idling) Oct 17 10:19:24 volumio volumio[922]: error: The expression evaluated to a falsy value: Oct 17 10:19:24 volumio volumio[922]: assert.ok(self.idling) Oct 17 10:19:24 volumio volumio[922]: error: updateQueue error: null Oct 17 10:19:24 volumio volumio[922]: error: updateQueue error: null Oct 17 10:19:24 volumio jellyfin[1453]: [10:19:24] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Oct 17 10:19:25 volumio RoonBridge[1488]: 00:00:00.178 Warn: get lock file path: /tmp/.rnbhgem0- Oct 17 10:19:25 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 17 10:19:25 volumio jellyfin[1453]: [10:19:25] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Oct 17 10:19:25 volumio volumio[1565]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 17 10:19:25 volumio volumio[922]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Oct 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [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 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Oct 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [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 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.10.112"] Oct 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Oct 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Oct 17 10:19:26 volumio jellyfin[1453]: [10:19:26] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Oct 17 10:19:26 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:26 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:27 volumio volumio[1565]: ........................++++ Oct 17 10:19:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 10:19:30 volumio RoonBridge[1488]: 00:00:10.443 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 17 10:19:30 volumio RoonBridge[1488]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 17 10:19:30 volumio RoonBridge[1488]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 17 10:19:30 volumio RoonBridge[1488]: Not Running (.o) Oct 17 10:19:30 volumio RoonBridge[1488]: 00:00:10.552 Debug: PathForResource, filename: ../.update Oct 17 10:19:30 volumio RoonBridge[1488]: 00:00:10.552 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 17 10:19:32 volumio RoonBridge[1488]: 00:00:12.566 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 10:19:32 volumio RoonBridge[1488]: Running Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 17 10:19:33 volumio volumio[922]: info: Adding plugin bluetooth to MyMusic Plugins Oct 17 10:19:33 volumio volumio[922]: info: Adding plugin multiroom to MyMusic Plugins Oct 17 10:19:33 volumio volumio[922]: info: Adding plugin metavolumio to MyMusic Plugins Oct 17 10:19:33 volumio volumio[922]: info: Adding plugin cd_controller to MyMusic Plugins Oct 17 10:19:33 volumio volumio[922]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 17 10:19:33 volumio volumio[922]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 17 10:19:33 volumio volumio[922]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 17 10:19:33 volumio volumio[1565]: ..............................................................................................++++ Oct 17 10:19:33 volumio volumio[1565]: e is 65537 (0x010001) Oct 17 10:19:33 volumio volumio[1565]: writing RSA key Oct 17 10:19:35 volumio volumio[922]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 17 10:19:35 volumio volumio[922]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 17 10:19:35 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:35 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:35 volumio volumio[922]: info: Starting MyVolumio Remote Streaming Endpoints Oct 17 10:19:35 volumio volumio[922]: info: MyVolumio login type: Token Oct 17 10:19:35 volumio volumio[922]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 17 10:19:35 volumio volumio[922]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 17 10:19:37 volumio volumio[922]: info: Starting Streaming Service Transparent Proxy Oct 17 10:19:37 volumio volumio[922]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 17 10:19:37 volumio volumio[922]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 17 10:19:37 volumio volumio[922]: info: Streaming services startup Oct 17 10:19:37 volumio volumio[922]: info: Starting Streaming Daemon Oct 17 10:19:37 volumio sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 10:19:37 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:37 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:37 volumio volumio[922]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 17 10:19:37 volumio volumio[922]: info: AutoStart - Plugin is starting Oct 17 10:19:37 volumio volumio[922]: info: CoreCommandRouter::volumioGetQueue Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::getQueue Oct 17 10:19:37 volumio volumio[922]: info: CorePlayQueue::getQueue Oct 17 10:19:37 volumio volumio[922]: info: AutoStart - start playing Oct 17 10:19:37 volumio volumio[922]: info: AutoStart - start playing with no specific position Oct 17 10:19:37 volumio volumio[922]: info: CoreCommandRouter::volumioPlay Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::play index 0 Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::stop Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::play index undefined Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 17 10:19:37 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:37 volumio volumio[922]: info: CoreStateMachine::startPlaybackTimer Oct 17 10:19:37 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:37 volumio volumio[922]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Oct 17 10:19:37 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand stop Oct 17 10:19:37 volumio volumio[922]: info: sendMpdCommand stop took 158 milliseconds Oct 17 10:19:37 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand clear Oct 17 10:19:37 volumio volumio[922]: error: Cannot start Volumio Streaming Daemon Oct 17 10:19:37 volumio volumio[922]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 10:19:37 volumio volumio[922]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: info: sendMpdCommand clear took 27 milliseconds Oct 17 10:19:37 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: error: updateQueue error: null Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: info: ------------------------------ 103ms Oct 17 10:19:37 volumio volumio[922]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 94 milliseconds Oct 17 10:19:37 volumio volumio[922]: info: ------------------------------ 85ms Oct 17 10:19:37 volumio volumio[922]: info: ------------------------------ 81ms Oct 17 10:19:37 volumio volumio[922]: info: ------------------------------ 70ms Oct 17 10:19:37 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand play Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:37 volumio volumio[922]: info: Oct 17 10:19:37 volumio volumio[922]: ---------------------------- MPD announces system playlist update Oct 17 10:19:37 volumio volumio[922]: info: Ignoring MPD Status Update Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 151ms Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand play took 114 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 113ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 112ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 118ms Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 21 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 20 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 16 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 17 milliseconds Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio volumio[922]: info: Oct 17 10:19:38 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:19:38 volumio RoonBridge[1488]: 00:00:18.572 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 17 10:19:38 volumio RoonBridge[1488]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 17 10:19:38 volumio RoonBridge[1488]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:19:38 volumio RoonBridge[1488]: 00:00:18.574 Debug: PathForResource, filename: ../.update Oct 17 10:19:38 volumio RoonBridge[1488]: 00:00:18.574 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 17 10:19:38 volumio RoonBridge[1488]: Not Running (.o) Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 108 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 80 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 86 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 77 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 67 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand status took 63 milliseconds Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: 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 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus stop Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: 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 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: 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 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: 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 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 288ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 331ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 333ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 327ms Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 209 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 191 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 193 milliseconds Oct 17 10:19:38 volumio volumio[922]: info: sendMpdCommand playlistinfo took 192 milliseconds Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: 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 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:19:38 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState stateService play Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus play Oct 17 10:19:38 volumio volumio[922]: info: Received an update from plugin. extracting info from payload Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:19:38 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:38 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 522ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 480ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 470ms Oct 17 10:19:38 volumio volumio[922]: info: ------------------------------ 473ms Oct 17 10:19:38 volumio volumio[922]: error: MyVolumio Custom Token format not valid, refreshing it Oct 17 10:19:38 volumio volumio[922]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 17 10:19:39 volumio volumio[922]: STREAMING PROXY: Starting server on port 3245 Oct 17 10:19:39 volumio volumio[922]: Node JS runtime: 14 Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:39 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 10:19:39 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 10:19:40 volumio volumio[922]: info: Received Get System Info Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:19:40 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:40 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:40 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:40 volumio volumio[922]: info: Listing playlists Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 10:19:40 volumio volumio[922]: info: Received Get System Info Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:19:40 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:40 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:40 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 10:19:40 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 10:19:40 volumio volumio[922]: info: MyVolumio login type: Token Oct 17 10:19:40 volumio RoonBridge[1488]: 00:00:20.576 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 17 10:19:40 volumio RoonBridge[1488]: Running Oct 17 10:19:40 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 17 10:19:40 volumio volumio[922]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 17 10:19:41 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 10:19:41 volumio volumio[922]: info: MyVolumio login type: Token Oct 17 10:19:41 volumio volumio[922]: info: MyVolumio token set successfully Oct 17 10:19:41 volumio volumio[922]: info: MYVOLUMIO: Adding device Oct 17 10:19:41 volumio volumio[922]: info: MYVOLUMIO: Evaluating Server Oct 17 10:19:41 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 17 10:19:41 volumio volumio[922]: info: MyVolumio login type: Token Oct 17 10:19:43 volumio volumio[922]: info: MyVolumio status changed Oct 17 10:19:43 volumio volumio[922]: info: Streaming services startup Oct 17 10:19:43 volumio volumio[922]: info: Starting Streaming Daemon Oct 17 10:19:43 volumio volumio[922]: info: Removing browser output: myVolumio user plan is not superstar Oct 17 10:19:43 volumio volumio[922]: info: Removing audio output: Oct 17 10:19:43 volumio volumio[922]: info: Stoppping Tunnel 1 Oct 17 10:19:43 volumio sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 10:19:43 volumio sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:43 volumio sudo[1945]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:43 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 10:19:43 volumio sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 17 10:19:43 volumio sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:43 volumio sudo[1948]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:43 volumio volumio[922]: info: Setting Geolocation for MyVolumio to as1 Oct 17 10:19:43 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:43 volumio volumio[922]: error: Cannot start Volumio Streaming Daemon Oct 17 10:19:43 volumio volumio[922]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 17 10:19:43 volumio volumio[922]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 17 10:19:43 volumio volumio[922]: info: Remote SSH Stopped Oct 17 10:19:43 volumio jellyfin[1453]: [10:19:43] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Oct 17 10:19:43 volumio jellyfin[1453]: [10:19:43] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Oct 17 10:19:43 volumio jellyfin[1453]: [10:19:43] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Oct 17 10:19:44 volumio jellyfin[1453]: [10:19:44] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Oct 17 10:19:44 volumio jellyfin[1453]: [10:19:44] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Oct 17 10:19:44 volumio volumio[922]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 17 10:19:44 volumio jellyfin[1453]: [10:19:44] [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 17 10:19:44 volumio volumio[922]: info: Updating MyVolumio device info Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:44 volumio jellyfin[1453]: [10:19:44] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 10:19:44 volumio volumio[922]: info: Received Get System Info Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:19:44 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:44 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 10:19:44 volumio volumio[922]: info: Received Get System Info Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:19:44 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:44 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:44 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:19:45 volumio volumio[922]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 17 10:19:47 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 10:19:47 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:47 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 10:19:47 volumio volumio-remote-updater[673]: No test mode Oct 17 10:19:47 volumio volumio-remote-updater[673]: No alpha test mode Oct 17 10:19:47 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 17 10:19:48 volumio volumio[922]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 17 10:19:48 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 17 10:19:48 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 10:19:48 volumio volumio[922]: info: MYVOLUMIO: Adding device Oct 17 10:19:48 volumio volumio[922]: info: MYVOLUMIO: Evaluating Server Oct 17 10:19:49 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:49 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:49 volumio sudo[1999]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 17 10:19:49 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:19:49 volumio volumio[922]: info: Setting Geolocation for MyVolumio to as1 Oct 17 10:19:49 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:49 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:49 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 10:19:50 volumio volumio[922]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 17 10:19:50 volumio volumio[922]: info: Received Get System Version Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 10:19:50 volumio volumio[922]: info: Received Get System Info Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:19:50 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:50 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 10:19:50 volumio volumio[922]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 17 10:19:50 volumio volumio[922]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:50 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:50 volumio jellyfin[1453]: [10:19:50] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Oct 17 10:19:50 volumio volumio[922]: info: Updating MyVolumio device info Oct 17 10:19:50 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:50 volumio jellyfin[1453]: [10:19:50] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Oct 17 10:19:51 volumio jellyfin[1453]: [10:19:51] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2024-10-18 03:00:00.000 +08:00, which is 16:40:08.8741946 from now. Oct 17 10:19:51 volumio jellyfin[1453]: [10:19:51] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2024-10-18 02:00:00.000 +08:00, which is 15:40:08.8514175 from now. Oct 17 10:19:51 volumio volumio[922]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 17 10:19:51 volumio jellyfin[1453]: [10:19:51] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Oct 17 10:19:52 volumio kernel: logitech-hidpp-device 0003:046D:4024.0004: HID++ 2.0 device connected. Oct 17 10:19:52 volumio jellyfin[1453]: [10:19:52] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Oct 17 10:19:52 volumio jellyfin[1453]: [10:19:52] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mjpeg_qsv", "mjpeg_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Oct 17 10:19:53 volumio jellyfin[1453]: [10:19:53] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] Oct 17 10:19:53 volumio volumio[922]: 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: 7 Oct 17 10:19:53 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:19:53 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:19:54 volumio jellyfin[1453]: [10:19:54] [INF] [14] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 10:19:55 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 10:19:55 volumio jellyfin[1453]: [10:19:55] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Oct 17 10:19:55 volumio sudo[1999]: pam_unix(sudo:session): session closed for user root Oct 17 10:19:57 volumio volumio[922]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 17 10:19:57 volumio volumio[922]: info: Completed starting MyVolumio Plugin Oct 17 10:19:57 volumio volumio[922]: info: BOOT COMPLETED Oct 17 10:19:57 volumio volumio[922]: [Metrics] CommandRouter: 54s 809.82ms Oct 17 10:19:57 volumio volumio[922]: info: CoreCommandRouter::volumiosetStartupVolume Oct 17 10:19:57 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 10:19:57 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:19:57 volumio volumio[922]: info: CoreCommandRouter::Close All Modals sent Oct 17 10:19:57 volumio volumio[922]: info: CoreCommandRouter::Close All Modals sent Oct 17 10:19:57 volumio volumio[922]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 17 10:19:57 volumio volumio[922]: aplay: main:828: audio open error: Device or resource busy Oct 17 10:19:58 volumio jellyfin[1453]: [10:19:58] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Oct 17 10:19:58 volumio jellyfin[1453]: [10:19:58] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae Oct 17 10:19:58 volumio jellyfin[1453]: [10:19:58] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Oct 17 10:19:58 volumio jellyfin[1453]: [10:19:58] [INF] [1] Main: Startup complete 0:00:39.3607816 Oct 17 10:19:58 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 17 10:19:58 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 10:19:58 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 17 10:19:59 volumio jellyfin[1453]: [10:19:59] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 4 seconds Oct 17 10:20:03 volumio volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 10:20:03 volumio volumio[922]: /bin/cat: /sys/class/net/wlan0/flags: No such file or directory Oct 17 10:20:03 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 10:20:04 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 17 10:20:04 volumio sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 17 10:20:04 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:04 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 10:20:05 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 10:20:05 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio sudo[2156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 17 10:20:05 volumio sudo[2156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 17 10:20:05 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 17 10:20:05 volumio sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 10:20:05 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio sudo[2156]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 10:20:05 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 17 10:20:05 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio volumio[922]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 17 10:20:05 volumio volumio[922]: wlan0 Interface doesn't support scanning. Oct 17 10:20:05 volumio volumio[922]: info: Cannot use regular scanning, forcing with ap-force Oct 17 10:20:05 volumio sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 17 10:20:05 volumio sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:05 volumio sudo[2185]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:05 volumio volumio[922]: command failed: No such device (-19) Oct 17 10:20:05 volumio volumio[922]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 17 10:20:05 volumio volumio[922]: command failed: No such device (-19) Oct 17 10:20:06 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:06 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:06 volumio sudo[2195]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 17 10:20:06 volumio sudo[2195]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:12 volumio volumio[922]: 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: 6 Oct 17 10:20:12 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:12 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:14 volumio volumio[922]: 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: 7 Oct 17 10:20:14 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:14 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:19 volumio sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 10:20:19 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:19 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:19 volumio sudo[2275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 10:20:19 volumio sudo[2275]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:19 volumio sudo[2275]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:19 volumio volumio[922]: verbose: New Socket.io Connection to 192.168.10.112 from 192.168.10.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:19 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 10:20:19 volumio volumio[922]: info: Received Get System Info Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:20:19 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:19 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:19 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:19 volumio volumio[922]: info: Listing playlists Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 10:20:19 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 10:20:20 volumio sudo[2282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 10:20:20 volumio sudo[2282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:20 volumio sudo[2282]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:20 volumio sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 10:20:20 volumio sudo[2285]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 10:20:20 volumio sudo[2285]: pam_unix(sudo:session): session closed for user root Oct 17 10:20:20 volumio volumio[922]: verbose: New Socket.io Connection to 192.168.10.112 from 192.168.10.137 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/129.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:20 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 10:20:20 volumio volumio[922]: info: Received Get System Info Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:20:20 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:20 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:20 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:20 volumio volumio[922]: info: Listing playlists Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 17 10:20:20 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:21 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 10:20:21 volumio volumio[922]: info: Received Get System Info Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:20:21 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:21 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:20:21 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 17 10:20:25 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 17 10:20:25 volumio volumio[922]: info: Received Get System Info Oct 17 10:20:25 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 17 10:20:25 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 17 10:20:25 volumio volumio[922]: info: Discovery: Getting this device information Oct 17 10:20:25 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:25 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:25 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 10:20:28 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 10:20:30 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 17 10:20:33 volumio volumio[922]: info: CoreCommandRouter::volumioGetState Oct 17 10:20:33 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:34 volumio volumio[922]: info: CoreCommandRouter::volumioPause Oct 17 10:20:34 volumio volumio[922]: info: CoreStateMachine::pause Oct 17 10:20:34 volumio volumio[922]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:20:34 volumio volumio[922]: info: CoreStateMachine::servicePause Oct 17 10:20:34 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:34 volumio volumio[922]: info: CoreCommandRouter::servicePause Oct 17 10:20:34 volumio volumio[922]: info: ControllerMpd::pause Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand pause Oct 17 10:20:34 volumio volumio[922]: info: Oct 17 10:20:34 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand pause took 3 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:20:34 volumio volumio[922]: info: Oct 17 10:20:34 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:20:34 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:20:34 volumio volumio[922]: info: Oct 17 10:20:34 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:20:34 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:20:34 volumio volumio[922]: info: Oct 17 10:20:34 volumio volumio[922]: ---------------------------- MPD announces state update: player Oct 17 10:20:34 volumio volumio[922]: info: ControllerMpd::getState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand status Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand status took 8 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand status took 7 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand status took 5 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand status took 5 milliseconds Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseState Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 10:20:34 volumio volumio[922]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:20:34 volumio volumio[922]: verbose: ControllerMpd::parseTrackInfo Oct 17 10:20:34 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:20:34 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:20:34 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:34 volumio volumio[922]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57553,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:20:34 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:20:34 volumio volumio[922]: info: CoreStateMachine::syncState stateService pause Oct 17 10:20:34 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus pause Oct 17 10:20:34 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:20:34 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:34 volumio volumio[922]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 10:20:34 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:20:35 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:20:35 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:20:35 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:35 volumio volumio[922]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57553,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:20:35 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::syncState stateService pause Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus pause Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:20:35 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:35 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:20:35 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:20:35 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:20:35 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:35 volumio volumio[922]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57553,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:20:35 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::syncState stateService pause Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus pause Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:20:35 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:35 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:20:35 volumio volumio[922]: info: ControllerMpd::pushState Oct 17 10:20:35 volumio volumio[922]: info: CoreCommandRouter::servicePushState Oct 17 10:20:35 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:35 volumio volumio[922]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":57553,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"827 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 17 10:20:35 volumio volumio[922]: verbose: CURRENT POSITION 0 Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::syncState stateService pause Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::syncState currentStatus pause Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::pushState Oct 17 10:20:35 volumio volumio[922]: info: CorePlayQueue::getTrack 0 Oct 17 10:20:35 volumio volumio[922]: info: CoreCommandRouter::volumioPushState Oct 17 10:20:35 volumio volumio[922]: info: CoreStateMachine::stPlaybackTimer Oct 17 10:20:35 volumio volumio[922]: info: ------------------------------ 105ms Oct 17 10:20:35 volumio volumio[922]: info: ------------------------------ 102ms Oct 17 10:20:35 volumio volumio[922]: info: ------------------------------ 100ms Oct 17 10:20:35 volumio volumio[922]: info: ------------------------------ 100ms Oct 17 10:20:35 volumio volumio[922]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 10:20:35 volumio volumio[922]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 17 10:20:35 volumio volumio[922]: at Object.readdirSync (fs.js:1048:3) Oct 17 10:20:35 volumio volumio[922]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 17 10:20:35 volumio volumio[922]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 17 10:20:35 volumio volumio[922]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 17 10:20:35 volumio volumio[922]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 17 10:20:35 volumio volumio[922]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 17 10:20:35 volumio volumio[922]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 10:20:35 volumio volumio[922]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 17 10:20:35 volumio volumio[922]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 17 10:20:35 volumio volumio[922]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 17 10:20:35 volumio volumio[922]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 10:20:35 volumio volumio[922]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 17 10:20:35 volumio volumio[922]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 17 10:20:35 volumio volumio[922]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 17 10:20:35 volumio volumio[922]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 17 10:20:35 volumio volumio[922]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 17 10:20:35 volumio volumio[922]: errno: -20, Oct 17 10:20:35 volumio volumio[922]: syscall: 'scandir', Oct 17 10:20:35 volumio volumio[922]: code: 'ENOTDIR', Oct 17 10:20:35 volumio volumio[922]: path: '/tmp/peppyrunning' Oct 17 10:20:35 volumio volumio[922]: } Oct 17 10:20:35 volumio volumio[922]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 10:20:35 volumio sudo[2351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-17 10:19 Oct 17 10:20:35 volumio sudo[2351]: 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"