-- Logs begin at Tue 2025-05-13 11:12:19 CST, end at Tue 2025-05-13 11:14:04 CST. --
May 13 11:13:00 volumio volumio[1084]: info: Loading i18n strings for locale zh
May 13 11:13:00 volumio volumio[1084]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 13 11:13:00 volumio volumio[1084]: Updating browse sources language
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::initPlayerControls
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:00 volumio volumio[1084]: Express server listening on port 3000
May 13 11:13:00 volumio volumio[1084]: [Metrics] WebUI: 16s 296.07ms
May 13 11:13:00 volumio volumio[1084]: info: Setting Device type: x86
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::resetVolumioState
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::getcurrentVolume
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioRetrievevolume
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:00 volumio volumio[1084]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
May 13 11:13:00 volumio volumio[1084]: info: Completed loading Core Plugins
May 13 11:13:00 volumio volumio[1084]: info: Preparing to generate the ALSA configuration file
May 13 11:13:00 volumio volumio[1084]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
May 13 11:13:00 volumio volumio[1084]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
May 13 11:13:00 volumio volumio[1084]: info: Reading ALSA contributions from plugins.
May 13 11:13:00 volumio volumio[1084]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:00 volumio volumio[1084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1
May 13 11:13:00 volumio volumio[1084]: info: Reloading queue from file
May 13 11:13:00 volumio volumio[1084]: info: VolumeController:: Volume=undefined Mute =false
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::updateTrackBlock
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrackBlock
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioRetrievevolume
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::setRepeat null single undefined
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::setRandom null
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:00 volumio volumio[1084]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0
May 13 11:13:00 volumio volumio[1084]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785
May 13 11:13:00 volumio volumio[1084]: info: Discovery: Found device Volumio
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:00 volumio volumio[1084]: info: VolumeController:: Volume=undefined Mute =false
May 13 11:13:00 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:00 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:00 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:00 volumio volumio[1084]: info: Asound.conf file unchanged, so no further update is needed
May 13 11:13:00 volumio volumio[1084]: info: Output device has changed, restarting MPD
May 13 11:13:01 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 13 11:13:01 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root
May 13 11:13:01 volumio volumio[1084]: info: Output device has changed, restarting Shairport Sync
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:01 volumio sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 13 11:13:01 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio systemd[1]: Listening on mpd.socket.
May 13 11:13:01 volumio systemd[1]: Starting Music Player Daemon...
May 13 11:13:01 volumio volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 13 11:13:01 volumio volumio[1084]: info: ___________ START PLUGINS ___________
May 13 11:13:01 volumio volumio[1084]: info: ControllerMpd::onStart: Initializing MPD
May 13 11:13:01 volumio volumio[1084]: info: Creating MPD Configuration file
May 13 11:13:01 volumio sudo[1549]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 13 11:13:01 volumio sudo[1549]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio sudo[1549]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
May 13 11:13:01 volumio sudo[1549]: pam_unix(sudo:session): session closed for user root
May 13 11:13:01 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 13 11:13:01 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:01 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 13 11:13:01 volumio volumio[1084]: info: [1747105981343] CoreMusicLibrary::Adding element 媒体服务器
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 11:13:01 volumio sudo[1556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 13 11:13:01 volumio sudo[1556]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
May 13 11:13:01 volumio systemd[1]: mpd.service: Succeeded.
May 13 11:13:01 volumio systemd[1]: Stopped Music Player Daemon.
May 13 11:13:01 volumio systemd[1]: Starting Music Player Daemon...
May 13 11:13:01 volumio volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:01 volumio volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 13 11:13:01 volumio volumio[1084]: info: [1747105981539] CoreMusicLibrary::Adding element Last_100
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 13 11:13:01 volumio volumio[1084]: info: [1747105981543] CoreMusicLibrary::Adding element Webradio
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 11:13:01 volumio volumio[1084]: info: Initializing BBC Radios
May 13 11:13:01 volumio sudo[1558]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 13 11:13:01 volumio sudo[1558]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio sudo[1558]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
May 13 11:13:01 volumio sudo[1558]: pam_unix(sudo:session): session closed for user root
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:01 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:01 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 13 11:13:01 volumio volumio[1084]: info: [1747105981784] CoreMusicLibrary::Adding element Jellyfin
May 13 11:13:01 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 11:13:01 volumio volumio[1084]: Cannot find translation for source Jellyfin
May 13 11:13:01 volumio volumio[1084]: info: [jellyfin] Initialized plugin with device info: {"id":"6576be8c-9ca3-4f6e-ad09-3e064e726785","host":"http://192.168.31.237","name":"Volumio","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":"","mute":false,"artist":"赵鹏","track":"乌兰巴托的夜晚","albumart":"http://192.168.31.237/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"}}
May 13 11:13:01 volumio volumio[1084]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl start jellyfin || true
May 13 11:13:01 volumio volumio[1084]: info: AutoStart - onStart
May 13 11:13:01 volumio volumio[1084]: info: Loading i18n strings for locale zh
May 13 11:13:01 volumio volumio[1084]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 13 11:13:01 volumio sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start jellyfin
May 13 11:13:01 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:01 volumio volumio[1084]: info: /tmp/myfifo created
May 13 11:13:01 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.
May 13 11:13:01 volumio volumio[1084]: info: /tmp/myfifosa created
May 13 11:13:01 volumio sudo[1585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0
May 13 11:13:01 volumio sudo[1585]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:02 volumio sudo[1585]: pam_unix(sudo:session): session closed for user root
May 13 11:13:02 volumio systemd[1]: Starting Jellyfin...
May 13 11:13:02 volumio volumio[1084]: info: snd-dummy loaded
May 13 11:13:02 volumio systemd[1]: Started Jellyfin.
May 13 11:13:02 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root
May 13 11:13:02 volumio volumio-remote-updater[700]: [2025-05-13 11:13:02] [connect] Successful connection
May 13 11:13:02 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:02 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:02 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:02 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync
May 13 11:13:02 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:02 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:02 volumio volumio[1084]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 13 11:13:02 volumio volumio[1084]: info: Loading i18n strings for locale zh
May 13 11:13:02 volumio volumio[1084]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found.
May 13 11:13:02 volumio sudo[1609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver
May 13 11:13:02 volumio sudo[1609]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:02 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service
May 13 11:13:02 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:02 volumio volumio[1084]: info: Volumio Calling Home
May 13 11:13:02 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.
May 13 11:13:02 volumio systemd[1]: Starting Logitech Media Server Daemon...
May 13 11:13:02 volumio systemd[1]: Started RoonBridge.
May 13 11:13:02 volumio systemd[1]: Started Logitech Media Server Daemon.
May 13 11:13:02 volumio sudo[1609]: pam_unix(sudo:session): session closed for user root
May 13 11:13:02 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root
May 13 11:13:02 volumio systemd[1624]: logitechmediaserver.service: Failed to execute command: No such file or directory
May 13 11:13:02 volumio systemd[1624]: logitechmediaserver.service: Failed at step EXEC spawning /usr/sbin/squeezeboxserver: No such file or directory
May 13 11:13:02 volumio systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=203/EXEC
May 13 11:13:02 volumio systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'.
May 13 11:13:03 volumio sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 13 11:13:03 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:03 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root
May 13 11:13:03 volumio volumio[1084]: info: logitechmediaserver started
May 13 11:13:03 volumio volumio[1084]: info: MPD Permissions set
May 13 11:13:03 volumio volumio[1084]: info: MPD Permissions set
May 13 11:13:03 volumio volumio-remote-updater[700]: [2025-05-13 11:13:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747105982 101
May 13 11:13:03 volumio volumio[1084]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2
May 13 11:13:03 volumio sudo[1636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 13 11:13:03 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:03 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:03 volumio volumio[1084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 13 11:13:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:03 volumio volumio[1084]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 13 11:13:03 volumio sudo[1656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 13 11:13:03 volumio sudo[1656]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:03 volumio sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl
May 13 11:13:03 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:04 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root
May 13 11:13:04 volumio volumio[1084]: info: minidlna: Found miniDLNA version 1.2.1
May 13 11:13:04 volumio volumio[1084]: info: minidlna: Starting minidlna.service
May 13 11:13:04 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.
May 13 11:13:04 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service
May 13 11:13:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile
May 13 11:13:04 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:04 volumio sudo[1656]: pam_unix(sudo:session): session closed for user root
May 13 11:13:04 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server.
May 13 11:13:04 volumio volumio[1084]: info: Volumio called home
May 13 11:13:04 volumio volumio[1084]: info: Starting Shairport Sync
May 13 11:13:04 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root
May 13 11:13:04 volumio sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
May 13 11:13:04 volumio volumio[1084]: info: Starting Shairport Sync
May 13 11:13:04 volumio sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:04 volumio sudo[1667]: pam_unix(sudo:session): session closed for user root
May 13 11:13:04 volumio volumio[1084]: info: Starting Shairport Sync
May 13 11:13:04 volumio sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 13 11:13:04 volumio sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:04 volumio volumio[1084]: info: Starting Shairport Sync
May 13 11:13:04 volumio sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 13 11:13:04 volumio sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 13 11:13:04 volumio systemd[1]: shairport-sync.service: Succeeded.
May 13 11:13:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:04 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 13 11:13:04 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:04 volumio sudo[1669]: pam_unix(sudo:session): session closed for user root
May 13 11:13:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 13 11:13:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
May 13 11:13:04 volumio systemd[1]: shairport-sync.service: Succeeded.
May 13 11:13:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:04 volumio sudo[1672]: pam_unix(sudo:session): session closed for user root
May 13 11:13:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 13 11:13:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
May 13 11:13:04 volumio systemd[1]: shairport-sync.service: Succeeded.
May 13 11:13:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:05 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 13 11:13:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:05 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root
May 13 11:13:05 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:05 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:05 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 13 11:13:05 volumio systemd[1]: shairport-sync.service: Succeeded.
May 13 11:13:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 13 11:13:05 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
May 13 11:13:05 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root
May 13 11:13:05 volumio sudo[1695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 13 11:13:05 volumio sudo[1695]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:05 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
May 13 11:13:05 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:05 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
May 13 11:13:05 volumio sudo[1695]: pam_unix(sudo:session): session closed for user root
May 13 11:13:05 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
May 13 11:13:05 volumio systemd[1]: mpd.service: Succeeded.
May 13 11:13:05 volumio systemd[1]: Stopped Music Player Daemon.
May 13 11:13:05 volumio systemd[1]: Starting Music Player Daemon...
May 13 11:13:05 volumio volumio[1084]: info: Shairport-Sync Started
May 13 11:13:05 volumio volumio[1084]: Error adding Membership: Error: addMembership EINVAL
May 13 11:13:05 volumio volumio[1084]: info: Shairport-Sync Started
May 13 11:13:05 volumio volumio[1084]: info: Shairport-Sync Started
May 13 11:13:05 volumio volumio[1084]: info: Shairport-Sync Started
May 13 11:13:05 volumio volumio[1084]: info: MPD Permissions set
May 13 11:13:05 volumio volumio[1084]: info: minidlna: systemctl start minidlna.service succeeded.
May 13 11:13:05 volumio volumio[1084]: info: Upmpdcli Daemon Started
May 13 11:13:05 volumio volumio[1084]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 13 11:13:05 volumio sudo[1701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
May 13 11:13:05 volumio sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:05 volumio sudo[1701]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory
May 13 11:13:05 volumio sudo[1701]: pam_unix(sudo:session): session closed for user root
May 13 11:13:05 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:05 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:05 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
May 13 11:13:05 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 13 11:13:05 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:06 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root
May 13 11:13:06 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:06 volumio RoonBridge[1623]: 00:00:00.026 Warn: get lock file path: /tmp/.rnbgem0-
May 13 11:13:06 volumio volumio[1084]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 13 11:13:06 volumio sudo[1715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 13 11:13:06 volumio sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:06 volumio sudo[1715]: pam_unix(sudo:session): session closed for user root
May 13 11:13:07 volumio volumio[1084]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 13 11:13:07 volumio sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 13 11:13:07 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:07 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root
May 13 11:13:07 volumio RoonBridge[1623]: 00:00:01.344 Trace: [childprocess] using unix child process
May 13 11:13:07 volumio RoonBridge[1623]: 00:00:01.591 Debug: PathForResource, filename: ../.update
May 13 11:13:08 volumio volumio[1084]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:01.889 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:01.921 Debug: PathForResource, filename: RAATServer
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:01.921 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:01.922 Debug: PathForResource, filename: RoonBridgeHelper
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:01.922 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
May 13 11:13:08 volumio RoonBridge[1623]: Initializing
May 13 11:13:08 volumio sudo[1727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin
May 13 11:13:08 volumio sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:08 volumio sudo[1727]: pam_unix(sudo:session): session closed for user root
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:02.097 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
May 13 11:13:08 volumio RoonBridge[1623]: 00:00:02.271 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 13 11:13:08 volumio RoonBridge[1623]: Not Running (.o)
May 13 11:13:10 volumio mpd[1704]: May 13 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
May 13 11:13:10 volumio systemd[1]: Started Music Player Daemon.
May 13 11:13:10 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root
May 13 11:13:10 volumio sudo[1556]: pam_unix(sudo:session): session closed for user root
May 13 11:13:10 volumio volumio[1084]: Upnp client error: Error: This socket has been ended by the other party
May 13 11:13:10 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root
May 13 11:13:10 volumio volumio[1084]: info: Completed starting Core Plugins
May 13 11:13:10 volumio volumio[1084]: info: -------------------------------------------
May 13 11:13:10 volumio volumio[1084]: info: ----- MyVolumio plugins startup ----
May 13 11:13:10 volumio volumio[1084]: info: -------------------------------------------
May 13 11:13:10 volumio volumio[1084]: info: [MyVolumio PluginManager] Fetching plans data....
May 13 11:13:10 volumio RoonBridge[1623]: Running
May 13 11:13:10 volumio volumio[1084]: error: MPD error: The expression evaluated to a falsy value:
May 13 11:13:10 volumio volumio[1084]: assert.ok(self.idling)
May 13 11:13:10 volumio volumio[1084]: error: The expression evaluated to a falsy value:
May 13 11:13:10 volumio volumio[1084]: assert.ok(self.idling)
May 13 11:13:10 volumio volumio[1084]: error: MPD error: The expression evaluated to a falsy value:
May 13 11:13:10 volumio volumio[1084]: assert.ok(self.idling)
May 13 11:13:10 volumio volumio[1084]: error: The expression evaluated to a falsy value:
May 13 11:13:10 volumio volumio[1084]: assert.ok(self.idling)
May 13 11:13:10 volumio volumio[1084]: info: MPD running with PID1704
May 13 11:13:10 volumio volumio[1084]: ,establishing connection
May 13 11:13:10 volumio volumio[1084]: error: MPD error: The expression evaluated to a falsy value:
May 13 11:13:10 volumio volumio[1084]: assert.ok(self.idling)
May 13 11:13:10 volumio volumio[1084]: error: The expression evaluated to a falsy value:
May 13 11:13:10 volumio volumio[1084]: assert.ok(self.idling)
May 13 11:13:10 volumio volumio[1084]: error: updateQueue error: null
May 13 11:13:10 volumio volumio[1700]: Generating RSA private key, 4096 bit long modulus (2 primes)
May 13 11:13:11 volumio jellyfin[1587]: [11:13:10] [INF] [1] Main: Jellyfin version: 10.9.6
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"]
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [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"]
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster)
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Architecture: X64
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: 64-Bit Process: True
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: User Interactive: True
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Processor count: 2
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Program data path: /opt/jellyfin/data
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Log directory path: /opt/jellyfin/log
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Config directory path: /opt/jellyfin/config
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Cache path: /opt/jellyfin/cache
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web
May 13 11:13:11 volumio jellyfin[1587]: [11:13:11] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/
May 13 11:13:11 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:11 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:12 volumio volumio[1084]: 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
May 13 11:13:12 volumio RoonBridge[1623]: 00:00:00.088 Warn: get lock file path: /tmp/.rnbhgem0-
May 13 11:13:13 volumio jellyfin[1587]: [11:13:13] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"]
May 13 11:13:14 volumio jellyfin[1587]: [11:13:14] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache
May 13 11:13:15 volumio jellyfin[1587]: [11:13:15] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [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"]
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: []
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [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"]
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.31.237"]
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"]
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist
May 13 11:13:16 volumio jellyfin[1587]: [11:13:16] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: []
May 13 11:13:17 volumio RoonBridge[1623]: 00:00:10.925 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 13 11:13:17 volumio RoonBridge[1623]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 13 11:13:17 volumio RoonBridge[1623]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0
May 13 11:13:17 volumio RoonBridge[1623]: Not Running (.o)
May 13 11:13:17 volumio RoonBridge[1623]: 00:00:11.039 Debug: PathForResource, filename: ../.update
May 13 11:13:17 volumio RoonBridge[1623]: 00:00:11.040 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 13 11:13:19 volumio RoonBridge[1623]: 00:00:13.040 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 13 11:13:19 volumio RoonBridge[1623]: Running
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
May 13 11:13:19 volumio volumio[1084]: info: Adding plugin bluetooth to MyMusic Plugins
May 13 11:13:19 volumio volumio[1084]: info: Adding plugin multiroom to MyMusic Plugins
May 13 11:13:19 volumio volumio[1084]: info: Adding plugin metavolumio to MyMusic Plugins
May 13 11:13:19 volumio volumio[1084]: info: Adding plugin cd_controller to MyMusic Plugins
May 13 11:13:19 volumio volumio[1084]: info: Adding plugin smart_inputs to MyMusic Plugins
May 13 11:13:19 volumio volumio[1084]: info: Adding plugin tidalconnect to MyMusic Plugins
May 13 11:13:19 volumio volumio[1084]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
May 13 11:13:22 volumio volumio[1084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
May 13 11:13:22 volumio volumio[1084]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
May 13 11:13:22 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:22 volumio volumio[1084]: info: Starting MyVolumio Remote Streaming Endpoints
May 13 11:13:22 volumio volumio[1084]: info: MyVolumio login type: Token
May 13 11:13:22 volumio volumio[1084]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
May 13 11:13:22 volumio volumio[1084]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
May 13 11:13:24 volumio volumio[1700]: .......................................................................................................................................................................................................................................................................++++
May 13 11:13:25 volumio RoonBridge[1623]: 00:00:19.072 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused
May 13 11:13:25 volumio RoonBridge[1623]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0
May 13 11:13:25 volumio RoonBridge[1623]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0
May 13 11:13:25 volumio RoonBridge[1623]: Not Running (.o)
May 13 11:13:25 volumio RoonBridge[1623]: 00:00:19.091 Debug: PathForResource, filename: ../.update
May 13 11:13:25 volumio RoonBridge[1623]: 00:00:19.092 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 13 11:13:25 volumio volumio[1084]: info: Starting Streaming Service Transparent Proxy
May 13 11:13:25 volumio volumio[1084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
May 13 11:13:25 volumio volumio[1084]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
May 13 11:13:25 volumio volumio[1084]: info: Streaming services startup
May 13 11:13:25 volumio volumio[1084]: info: Starting Streaming Daemon
May 13 11:13:26 volumio sudo[2036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 13 11:13:26 volumio sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:26 volumio volumio[1700]: ..........................................++++
May 13 11:13:26 volumio volumio[1700]: e is 65537 (0x010001)
May 13 11:13:26 volumio sudo[2036]: pam_unix(sudo:session): session closed for user root
May 13 11:13:26 volumio volumio[1700]: writing RSA key
May 13 11:13:26 volumio volumio[1084]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
May 13 11:13:26 volumio volumio[1084]: info: AutoStart - Plugin is starting
May 13 11:13:26 volumio volumio[1084]: info: CoreCommandRouter::volumioGetQueue
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::getQueue
May 13 11:13:26 volumio volumio[1084]: info: CorePlayQueue::getQueue
May 13 11:13:26 volumio volumio[1084]: info: AutoStart - start playing
May 13 11:13:26 volumio volumio[1084]: info: AutoStart - start playing with no specific position
May 13 11:13:26 volumio volumio[1084]: info: CoreCommandRouter::volumioPlay
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::play index 0
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::stop
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::play index undefined
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::setConsumeUpdateService undefined
May 13 11:13:26 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:26 volumio volumio[1084]: info: CoreStateMachine::startPlaybackTimer
May 13 11:13:26 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand stop
May 13 11:13:26 volumio volumio[1084]: info: sendMpdCommand stop took 42 milliseconds
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand clear
May 13 11:13:26 volumio volumio[1084]: error: Cannot start Volumio Streaming Daemon
May 13 11:13:26 volumio volumio[1084]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 13 11:13:26 volumio volumio[1084]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info: sendMpdCommand clear took 112 milliseconds
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac"
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:26 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:26 volumio volumio[1084]: error: updateQueue error: null
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 378ms
May 13 11:13:26 volumio volumio[1084]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 363 milliseconds
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 363ms
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 341ms
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 348ms
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand play
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces system playlist update
May 13 11:13:26 volumio volumio[1084]: info: Ignoring MPD Status Update
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 106ms
May 13 11:13:26 volumio volumio[1084]: info: sendMpdCommand play took 66 milliseconds
May 13 11:13:26 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 52ms
May 13 11:13:26 volumio volumio[1084]: info: ------------------------------ 44ms
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:26 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:26 volumio volumio[1084]: info:
May 13 11:13:26 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:26 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:26 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:27 volumio volumio[1084]: info:
May 13 11:13:27 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 177ms
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 150 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 124 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 110 milliseconds
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: info:
May 13 11:13:27 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:27 volumio volumio[1084]: info:
May 13 11:13:27 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:27 volumio volumio[1084]: info:
May 13 11:13:27 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:27 volumio volumio[1084]: 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
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 96 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 84 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 84 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 80 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 79 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 78 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 56 milliseconds
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"303 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus stop
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"691 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: 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":"691 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 352ms
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 373ms
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 358ms
May 13 11:13:27 volumio volumio[1084]: info:
May 13 11:13:27 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 162 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 152 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 151 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 153 milliseconds
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand status took 29 milliseconds
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: 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"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio RoonBridge[1623]: 00:00:21.092 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio RoonBridge[1623]: Running
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: 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"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: 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"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: 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"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 499ms
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 487ms
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 486ms
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 466ms
May 13 11:13:27 volumio volumio[1084]: error: MyVolumio Custom Token format not valid, refreshing it
May 13 11:13:27 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 528 milliseconds
May 13 11:13:27 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:13:27 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"528 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:13:27 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState stateService play
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus play
May 13 11:13:27 volumio volumio[1084]: info: Received an update from plugin. extracting info from payload
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:13:27 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:27 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:13:27 volumio volumio[1084]: info: ------------------------------ 641ms
May 13 11:13:28 volumio volumio[1084]: STREAMING PROXY: Starting server on port 3245
May 13 11:13:28 volumio volumio[1084]: Node JS runtime: 14
May 13 11:13:28 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetVisibleSources
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:29 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
May 13 11:13:29 volumio volumio[1084]: info: Received Get System Info
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:29 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:29 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:29 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:29 volumio volumio[1084]: info: Listing playlists
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 13 11:13:29 volumio volumio[1084]: info: Received Get System Info
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:29 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:29 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:29 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 13 11:13:29 volumio volumio[1084]: info: Received Get System Info
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:29 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:29 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
May 13 11:13:29 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 13 11:13:29 volumio volumio[1084]: info: MyVolumio login type: Token
May 13 11:13:30 volumio volumio[1084]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
May 13 11:13:32 volumio volumio[1084]: info: MyVolumio token set successfully
May 13 11:13:32 volumio volumio[1084]: info: MYVOLUMIO: Adding device
May 13 11:13:32 volumio volumio[1084]: info: MYVOLUMIO: Evaluating Server
May 13 11:13:32 volumio volumio[1084]: info: MyVolumio status changed
May 13 11:13:32 volumio volumio[1084]: info: Streaming services startup
May 13 11:13:32 volumio volumio[1084]: info: Starting Streaming Daemon
May 13 11:13:32 volumio volumio[1084]: info: Removing browser output: myVolumio user plan is not superstar
May 13 11:13:32 volumio volumio[1084]: info: Removing audio output:
May 13 11:13:32 volumio volumio[1084]: info: Stoppping Tunnel 1
May 13 11:13:32 volumio sudo[2106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 13 11:13:32 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:32 volumio sudo[2108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
May 13 11:13:32 volumio sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:32 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root
May 13 11:13:32 volumio sudo[2108]: pam_unix(sudo:session): session closed for user root
May 13 11:13:33 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 11:13:33 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:33 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
May 13 11:13:33 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 13 11:13:33 volumio volumio[1084]: info: MyVolumio login type: Token
May 13 11:13:33 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
May 13 11:13:33 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken
May 13 11:13:33 volumio volumio[1084]: info: MyVolumio login type: Token
May 13 11:13:33 volumio volumio[1084]: info: Remote SSH Stopped
May 13 11:13:33 volumio volumio[1084]: error: Cannot start Volumio Streaming Daemon
May 13 11:13:33 volumio volumio[1084]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
May 13 11:13:33 volumio volumio[1084]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
May 13 11:13:34 volumio volumio-remote-updater[700]: No test mode
May 13 11:13:34 volumio volumio-remote-updater[700]: No alpha test mode
May 13 11:13:34 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
May 13 11:13:35 volumio volumio[1084]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"NEW ADDITIONS
- QOBUZ Connect Ready
","title":"Update v3.807","updateavailable":true}
May 13 11:13:35 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0
May 13 11:13:36 volumio volumio[1084]: info: Setting Geolocation for MyVolumio to us2
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [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"]
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
May 13 11:13:36 volumio jellyfin[1587]: [11:13:36] [INF] [1] Main: Kestrel is listening on 0.0.0.0
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 13 11:13:36 volumio volumio[1084]: info: Received Get System Info
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:36 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:36 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:36 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:37 volumio jellyfin[1587]: [11:13:37] [WRN] [1] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager: No XML encryptor configured. Key {47d4a458-17cb-42b1-86d7-6a1e9e53ac5d} may be persisted to storage in unencrypted form.
May 13 11:13:37 volumio volumio[1084]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
May 13 11:13:37 volumio volumio[1084]: info: Received Get System Info
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:37 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:37 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:37 volumio volumio[1084]: info: Updating MyVolumio device info
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 13 11:13:37 volumio volumio[1084]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:37 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:37 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:37 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:37 volumio sudo[2154]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py
May 13 11:13:37 volumio sudo[2154]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:13:38 volumio volumio[1084]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 13 11:13:41 volumio volumio[1084]: info: MYVOLUMIO: Adding device
May 13 11:13:41 volumio volumio[1084]: info: MYVOLUMIO: Evaluating Server
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
May 13 11:13:41 volumio volumio[1084]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
May 13 11:13:41 volumio volumio[1084]: info: Received Get System Version
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 11:13:41 volumio volumio[1084]: info: Received Get System Info
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
May 13 11:13:41 volumio volumio[1084]: info: Discovery: Getting this device information
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:41 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:41 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
May 13 11:13:41 volumio jellyfin[1587]: [11:13:41] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable.
May 13 11:13:42 volumio volumio[1084]: 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
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:42 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:42 volumio jellyfin[1587]: [11:13:42] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::volumioGetBrowseSources
May 13 11:13:42 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 13 11:13:42 volumio jellyfin[1587]: [11:13:42] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2025-05-14 03:00:00.000 +08:00, which is 15:46:17.2978071 from now.
May 13 11:13:42 volumio jellyfin[1587]: [11:13:42] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2025-05-14 02:00:00.000 +08:00, which is 14:46:17.2766753 from now.
May 13 11:13:43 volumio volumio[1084]: info: Setting Geolocation for MyVolumio to us2
May 13 11:13:43 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:43 volumio volumio[1084]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
May 13 11:13:44 volumio jellyfin[1587]: [11:13:44] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1
May 13 11:13:44 volumio volumio[1084]: 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
May 13 11:13:44 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:13:44 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:13:44 volumio volumio[1084]: info: Updating MyVolumio device info
May 13 11:13:44 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:44 volumio jellyfin[1587]: [11:13:44] [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"]
May 13 11:13:44 volumio jellyfin[1587]: [11:13:44] [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"]
May 13 11:13:44 volumio jellyfin[1587]: [11:13:44] [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"]
May 13 11:13:45 volumio volumio[1084]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
May 13 11:13:46 volumio jellyfin[1587]: [11:13:46] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds
May 13 11:13:46 volumio jellyfin[1587]: [11:13:46] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"]
May 13 11:13:46 volumio volumio[1084]: error: MyVolumio Plugin failed to authenticate in a timely fashion
May 13 11:13:46 volumio volumio[1084]: info: Completed starting MyVolumio Plugin
May 13 11:13:46 volumio volumio[1084]: info: BOOT COMPLETED
May 13 11:13:46 volumio volumio[1084]: [Metrics] CommandRouter: 60s 701.30ms
May 13 11:13:46 volumio volumio[1084]: info: CoreCommandRouter::volumiosetStartupVolume
May 13 11:13:46 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 13 11:13:46 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:46 volumio volumio[1084]: info: CoreCommandRouter::Close All Modals sent
May 13 11:13:46 volumio volumio[1084]: info: CoreCommandRouter::Close All Modals sent
May 13 11:13:46 volumio volumio[1084]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav
May 13 11:13:46 volumio volumio[1084]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card
May 13 11:13:46 volumio volumio[1084]: aplay: main:828: audio open error: No such device
May 13 11:13:47 volumio ntpd[1115]: Soliciting pool server 123.204.232.128
May 13 11:13:47 volumio ntpd[1115]: Soliciting pool server 183.177.72.202
May 13 11:13:47 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 13 11:13:47 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 13 11:13:47 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 13 11:13:47 volumio jellyfin[1587]: [11:13:47] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg
May 13 11:13:47 volumio jellyfin[1587]: [11:13:47] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae
May 13 11:13:48 volumio jellyfin[1587]: [11:13:47] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete
May 13 11:13:48 volumio jellyfin[1587]: [11:13:48] [INF] [1] Main: Startup complete 0:00:41.1702805
May 13 11:13:48 volumio ntpd[1115]: Soliciting pool server 117.80.231.60
May 13 11:13:49 volumio ntpd[1115]: Soliciting pool server 193.182.111.143
May 13 11:13:49 volumio jellyfin[1587]: [11:13:49] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 3 seconds
May 13 11:13:50 volumio sudo[2154]: pam_unix(sudo:session): session closed for user root
May 13 11:13:51 volumio ntpd[1115]: Soliciting pool server 114.34.171.136
May 13 11:13:55 volumio volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
May 13 11:13:56 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 13 11:14:01 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:14:01 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:01 volumio sudo[2321]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py
May 13 11:14:01 volumio sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 13 11:14:03 volumio volumio[1084]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
May 13 11:14:03 volumio volumio[1084]: info: Preparing to save Alsa Options, stopping services first
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioGetState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioPause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::stPlaybackTimer
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::servicePause
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::servicePause
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::pause
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand pause
May 13 11:14:03 volumio volumio[1084]: info: Saving Audio Output to: {"output_device":{"value":"1,1","label":"SPDIF"}}
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 13 11:14:03 volumio volumio[1084]: info: Found match in Cards Database: setting mixer for card SPDIF
May 13 11:14:03 volumio volumio[1084]: info: Setting mixer Master for card SPDIF
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioUpdateVolumeSettings
May 13 11:14:03 volumio volumio[1084]: info: Updating Volume Controller Parameters: Device: 1,1 Name: SPDIF Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
May 13 11:14:03 volumio volumio[1084]: info: Disabling external Volume Control
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::getUIConfigOnPlugin
May 13 11:14:03 volumio volumio[1084]: info: Preparing to generate the ALSA configuration file
May 13 11:14:03 volumio volumio[1084]: info:
May 13 11:14:03 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand pause took 321 milliseconds
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:14:03 volumio volumio[1084]: info:
May 13 11:14:03 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:14:03 volumio volumio[1084]: info:
May 13 11:14:03 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:14:03 volumio volumio[1084]: info:
May 13 11:14:03 volumio volumio[1084]: ---------------------------- MPD announces state update: player
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::getState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand status
May 13 11:14:03 volumio volumio[1084]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf
May 13 11:14:03 volumio volumio[1084]: info: Reading ALSA contributions from plugins.
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand status took 9 milliseconds
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand status took 8 milliseconds
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand status took 7 milliseconds
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand status took 6 milliseconds
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseState
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 27 milliseconds
May 13 11:14:03 volumio volumio[1084]: info: sendMpdCommand playlistinfo took 28 milliseconds
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:14:03 volumio volumio[1084]: verbose: ControllerMpd::parseTrackInfo
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37496,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:14:03 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState stateService pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::stPlaybackTimer
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37496,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:14:03 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState stateService pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::stPlaybackTimer
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37496,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:14:03 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState stateService pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::stPlaybackTimer
May 13 11:14:03 volumio volumio[1084]: info: ControllerMpd::pushState
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::servicePushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37496,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"895 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"}
May 13 11:14:03 volumio volumio[1084]: verbose: CURRENT POSITION 0
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState stateService pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::syncState currentStatus pause
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::pushState
May 13 11:14:03 volumio volumio[1084]: info: CorePlayQueue::getTrack 0
May 13 11:14:03 volumio volumio[1084]: info: CoreCommandRouter::volumioPushState
May 13 11:14:03 volumio volumio[1084]: info: CoreStateMachine::stPlaybackTimer
May 13 11:14:03 volumio volumio[1084]: info: ------------------------------ 115ms
May 13 11:14:03 volumio volumio[1084]: info: ------------------------------ 113ms
May 13 11:14:03 volumio volumio[1084]: info: ------------------------------ 112ms
May 13 11:14:03 volumio volumio[1084]: info: ------------------------------ 111ms
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
May 13 11:14:04 volumio volumio[1084]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
May 13 11:14:04 volumio volumio[1084]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 13 11:14:04 volumio volumio[1084]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning'
May 13 11:14:04 volumio volumio[1084]: at Object.readdirSync (fs.js:1048:3)
May 13 11:14:04 volumio volumio[1084]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11)
May 13 11:14:04 volumio volumio[1084]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7)
May 13 11:14:04 volumio volumio[1084]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62)
May 13 11:14:04 volumio volumio[1084]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17)
May 13 11:14:04 volumio volumio[1084]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48)
May 13 11:14:04 volumio volumio[1084]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 13 11:14:04 volumio volumio[1084]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10)
May 13 11:14:04 volumio volumio[1084]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12)
May 13 11:14:04 volumio volumio[1084]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
May 13 11:14:04 volumio volumio[1084]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 13 11:14:04 volumio volumio[1084]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8)
May 13 11:14:04 volumio volumio[1084]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15)
May 13 11:14:04 volumio volumio[1084]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20)
May 13 11:14:04 volumio volumio[1084]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12)
May 13 11:14:04 volumio volumio[1084]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) {
May 13 11:14:04 volumio volumio[1084]: errno: -20,
May 13 11:14:04 volumio volumio[1084]: syscall: 'scandir',
May 13 11:14:04 volumio volumio[1084]: code: 'ENOTDIR',
May 13 11:14:04 volumio volumio[1084]: path: '/tmp/peppyrunning'
May 13 11:14:04 volumio volumio[1084]: }
May 13 11:14:04 volumio volumio[1084]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 13 11:14:04 volumio sudo[2369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-13 11:13
May 13 11:14:04 volumio sudo[2369]: 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"