-- Logs begin at Thu 2019-02-14 19:11:58 KST, end at Thu 2025-03-06 21:34:41 KST. -- Mar 06 21:33:06 volumio systemd-timedated[989]: Changed local time to Thu Mar 6 21:33:06 2025 Mar 06 21:33:06 volumio sudo[987]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:06 volumio volumio-time-update[631]: volumio-time-update-util: System time updated successfully. Mar 06 21:33:06 volumio systemd[1]: Started Volumio Time Update Utility. Mar 06 21:33:06 volumio volumio[878]: info: Loading plugin "ytcr"... Mar 06 21:33:06 volumio nmbd[819]: [2025/03/06 21:33:06.575980, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 06 21:33:06 volumio nmbd[819]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 06 21:33:06 volumio systemd[1]: Started Samba NMB Daemon. Mar 06 21:33:06 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 06 21:33:06 volumio winbindd[990]: [2025/03/06 21:33:06.840483, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 06 21:33:06 volumio winbindd[990]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 06 21:33:06 volumio winbindd[990]: [2025/03/06 21:33:06.859804, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 06 21:33:06 volumio systemd[1]: Started Samba Winbind Daemon. Mar 06 21:33:06 volumio winbindd[990]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 06 21:33:06 volumio systemd[1]: Starting Samba SMB Daemon... Mar 06 21:33:07 volumio winbindd[990]: [2025/03/06 21:33:07.223869, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Mar 06 21:33:07 volumio winbindd[990]: res_names->count = 3, expected 4 Mar 06 21:33:07 volumio smbd[995]: [2025/03/06 21:33:07.275815, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 06 21:33:07 volumio systemd[1]: Started Samba SMB Daemon. Mar 06 21:33:07 volumio smbd[995]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 06 21:33:07 volumio systemd[1]: Reached target Multi-User System. Mar 06 21:33:07 volumio systemd[1]: Reached target Graphical Interface. Mar 06 21:33:07 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 06 21:33:07 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 06 21:33:07 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 06 21:33:07 volumio systemd[1]: Startup finished in 12.523s (kernel) + 16.836s (userspace) = 29.360s. Mar 06 21:33:09 volumio volumio[878]: info: Loading plugin "ytmusic"... Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "autostart"... Mar 06 21:33:12 volumio volumio-remote-updater[611]: [2025-03-06 21:33:12] [connect] Successful connection Mar 06 21:33:12 volumio volumio[878]: info: Applying required configuration parameters for plugin autostart Mar 06 21:33:12 volumio volumio[878]: info: AutoStart - onVolumioStart - read config.json Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "outputs"... Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "albumart"... Mar 06 21:33:12 volumio volumio[878]: info: Plugin example_plugin is not enabled Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "inputs"... Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "updater_comm"... Mar 06 21:33:12 volumio volumio[878]: info: Plugin mpdemulation is not enabled Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "rest_api"... Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "websocket"... Mar 06 21:33:12 volumio volumio[878]: info: Starting Socket.io Server version 2.3.0 Mar 06 21:33:12 volumio volumio[878]: info: Plugin squeezelite is not enabled Mar 06 21:33:12 volumio volumio[878]: info: Loading plugin "Systeminfo"... Mar 06 21:33:12 volumio volumio[878]: Forking 3 albumart workers Mar 06 21:33:13 volumio volumio[878]: info: Loading i18n strings for locale ko Mar 06 21:33:13 volumio volumio[878]: Updating browse sources language Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:13 volumio volumio[878]: Starting albumart workers Mar 06 21:33:13 volumio volumio[878]: Starting albumart workers Mar 06 21:33:13 volumio volumio[878]: Starting albumart workers Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::initPlayerControls Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 21:33:13 volumio volumio[878]: Express server listening on port 3000 Mar 06 21:33:13 volumio volumio[878]: [Metrics] WebUI: 14s 612.05ms Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::resetVolumioState Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::getcurrentVolume Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::volumioRetrievevolume Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:13 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:13 volumio sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 06 21:33:13 volumio sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:13 volumio sudo[1051]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:13 volumio sudo[1052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 21:33:13 volumio sudo[1052]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:13 volumio sudo[1052]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:13 volumio volumio[878]: info: Volumio Network Manager: Network status updated: 0 Mar 06 21:33:13 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Mar 06 21:33:13 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Mar 06 21:33:13 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 3 Mar 06 21:33:13 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Mar 06 21:33:13 volumio volumio-remote-updater[611]: [2025-03-06 21:33:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741264392 101 Mar 06 21:33:13 volumio volumio[878]: 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: 5 Mar 06 21:33:13 volumio volumio[878]: info: Reloading queue from file Mar 06 21:33:13 volumio volumio[878]: info: Setting Device type: Raspberry PI Mar 06 21:33:13 volumio volumio[878]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 06 21:33:13 volumio volumio[878]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Mar 06 21:33:13 volumio volumio[878]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::setRepeat true single undefined Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:13 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::setRandom null Mar 06 21:33:13 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:13 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:13 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:14 volumio volumio[878]: info: Completed loading Core Plugins Mar 06 21:33:14 volumio volumio[878]: info: Preparing to generate the ALSA configuration file Mar 06 21:33:14 volumio sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 06 21:33:14 volumio sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio volumio[878]: info: Asound.conf file unchanged, so no further update is needed Mar 06 21:33:14 volumio volumio[878]: info: Output device has changed, restarting MPD Mar 06 21:33:14 volumio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 06 21:33:14 volumio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio volumio[878]: info: Output device has changed, restarting Shairport Sync Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:14 volumio sudo[1089]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 06 21:33:14 volumio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 06 21:33:14 volumio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 06 21:33:14 volumio volumio[878]: info: ___________ START PLUGINS ___________ Mar 06 21:33:14 volumio volumio[878]: info: ControllerMpd::onStart: Initializing MPD Mar 06 21:33:14 volumio sudo[1085]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio volumio[878]: info: Creating MPD Configuration file Mar 06 21:33:14 volumio systemd[1]: Stopping Music Player Daemon... Mar 06 21:33:14 volumio sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 06 21:33:14 volumio volumio[878]: info: [1741264394153] CoreMusicLibrary::Adding element 미디어 서버 Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:14 volumio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 06 21:33:14 volumio sudo[1098]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:14 volumio systemd[1]: mpd.service: Succeeded. Mar 06 21:33:14 volumio systemd[1]: Stopped Music Player Daemon. Mar 06 21:33:14 volumio volumio[878]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 06 21:33:14 volumio volumio[878]: info: [1741264394259] CoreMusicLibrary::Adding element Last_100 Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 06 21:33:14 volumio volumio[878]: info: [1741264394261] CoreMusicLibrary::Adding element Webradio Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 21:33:14 volumio volumio[878]: info: Initializing BBC Radios Mar 06 21:33:14 volumio systemd[1]: Starting Music Player Daemon... Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: Creating Spotify config file Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio sudo[1109]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 06 21:33:14 volumio sudo[1109]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio sudo[1109]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 06 21:33:14 volumio volumio[878]: info: [1741264394421] CoreMusicLibrary::Adding element YouTube2 Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:14 volumio volumio[878]: Cannot find translation for source YouTube2 Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 06 21:33:14 volumio volumio[878]: info: [1741264394487] CoreMusicLibrary::Adding element YouTube Music Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:14 volumio volumio[878]: Cannot find translation for source YouTube2 Mar 06 21:33:14 volumio volumio[878]: Cannot find translation for source YouTube Music Mar 06 21:33:14 volumio volumio[878]: info: AutoStart - onStart Mar 06 21:33:14 volumio volumio[878]: info: Volumio Calling Home Mar 06 21:33:14 volumio volumio[878]: info: Discovery: adding 8de028a0-07b1-46d1-9fbd-eabc799de14b Mar 06 21:33:14 volumio volumio[878]: info: Discovery: Found device Volumio Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:14 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:14 volumio volumio[878]: info: MPD Permissions set Mar 06 21:33:14 volumio volumio[878]: info: MPD Permissions set Mar 06 21:33:14 volumio volumio[878]: info: Upmpdcli Daemon Started Mar 06 21:33:14 volumio volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 06 21:33:14 volumio volumio[878]: info: Spotify config file written Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 06 21:33:14 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:14 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:14 volumio systemd[1]: Started go-librespot Daemon. Mar 06 21:33:14 volumio volumio[878]: info: No need to fix Spotify hosts Mar 06 21:33:14 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio volumio[878]: info: Starting Shairport Sync Mar 06 21:33:14 volumio go-librespot[1133]: Librespot-go daemon starting... Mar 06 21:33:14 volumio volumio[878]: info: Starting Shairport Sync Mar 06 21:33:14 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 06 21:33:14 volumio volumio[878]: info: Starting Shairport Sync Mar 06 21:33:14 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 06 21:33:14 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 06 21:33:14 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 06 21:33:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 06 21:33:14 volumio sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 06 21:33:14 volumio sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 06 21:33:14 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio volumio[878]: info: Shairport-Sync Started Mar 06 21:33:14 volumio volumio[878]: Error adding Membership: Error: addMembership EINVAL Mar 06 21:33:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 06 21:33:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 06 21:33:14 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 06 21:33:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 06 21:33:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 06 21:33:14 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio sudo[1145]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:14 volumio volumio[878]: info: Shairport-Sync Started Mar 06 21:33:15 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:15 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:15 volumio mpd[1121]: Mar 06 21:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 06 21:33:15 volumio systemd[1]: Started Music Player Daemon. Mar 06 21:33:15 volumio sudo[1100]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:15 volumio sudo[1092]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=info msg="generated new device id: cdceb6d7372ee4ca91a335b0a07a58c42cdc3100" Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="stored credentials found for 314nvqajtxutoiwn4kfsovj2bo4i" Mar 06 21:33:15 volumio volumio[878]: info: Volumio called home Mar 06 21:33:15 volumio volumio[878]: error: MPD error: The expression evaluated to a falsy value: Mar 06 21:33:15 volumio volumio[878]: assert.ok(self.idling) Mar 06 21:33:15 volumio volumio[878]: error: The expression evaluated to a falsy value: Mar 06 21:33:15 volumio volumio[878]: assert.ok(self.idling) Mar 06 21:33:15 volumio volumio[878]: info: MPD running with PID1121 Mar 06 21:33:15 volumio volumio[878]: ,establishing connection Mar 06 21:33:15 volumio volumio[878]: error: updateQueue error: null Mar 06 21:33:15 volumio volumio[878]: error: updateQueue error: null Mar 06 21:33:15 volumio volumio[1087]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="zeroconf server listening on port 46333" Mar 06 21:33:15 volumio volumio[878]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 06 21:33:15 volumio volumio[878]: SPOTIFY: BQAdgJEALvxn1t1emZgPXmsXC6ExHEwUHDxszfo3kXkL4AAxGt4_nskEvKKoEkqbEGVZVjvd4KJW27NfdPoxvQviqX2II1Qtx9Mn7n7jAZJYwL6W9JT4mHMW477OI6Pl721DNO1u0s8aESBwEiBNmWHTGQ5K3wDjdTPOq1ZVgSXTVokw3GYSk8NoL1e3wIfoUKJrTpWiAK5m-Nc5v-11G2Bekj18DJBnGuO0P4eqbLBai7klU0kPCj1hqSWexOxhKpFJ1TFPJQ4XutXaWECcjQz3xlAvN-cqmlC7xik Mar 06 21:33:15 volumio volumio[878]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 06 21:33:15 volumio volumio[878]: info: New Spotify access token = BQAdgJEALvxn1t1emZgPXmsXC6ExHEwUHDxszfo3kXkL4AAxGt4_nskEvKKoEkqbEGVZVjvd4KJW27NfdPoxvQviqX2II1Qtx9Mn7n7jAZJYwL6W9JT4mHMW477OI6Pl721DNO1u0s8aESBwEiBNmWHTGQ5K3wDjdTPOq1ZVgSXTVokw3GYSk8NoL1e3wIfoUKJrTpWiAK5m-Nc5v-11G2Bekj18DJBnGuO0P4eqbLBai7klU0kPCj1hqSWexOxhKpFJ1TFPJQ4XutXaWECcjQz3xlAvN-cqmlC7xik Mar 06 21:33:15 volumio volumio[878]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="obtained new client token: AABh27Cmq4AM58XqdVUW+k3BfrKdmdiGU5vQxKK4MLgWRk7F7NdZTjlOmBZ2Tdp1HK9IvALLGIaFAVLirLTFU81FFDhM9nF5Q2EuuDuA/9cV2q7XbG+lKeR+Rl+J8ZEouVECGgEMI29iqUuWEvevWRbtXpm2n5r9tKsYi/gXh1HLDHxhfl24FGnq1lVae6p9pyYI2RUkLGNJ9qhkdzot5MIpe6UHMEocf21L10QGQwMlcepDhTBQEqF45o4L0g==" Mar 06 21:33:15 volumio volumio[878]: SPOTIFY: User informations: {"country":"KR","display_name":"Charles","email":"tubulin1974@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/314nvqajtxutoiwn4kfsovj2bo4i"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/314nvqajtxutoiwn4kfsovj2bo4i","id":"314nvqajtxutoiwn4kfsovj2bo4i","images":[],"product":"free","type":"user","uri":"spotify:user:314nvqajtxutoiwn4kfsovj2bo4i"} Mar 06 21:33:15 volumio volumio[878]: info: Spotify Successfully logged in Mar 06 21:33:15 volumio volumio[878]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 06 21:33:15 volumio volumio[878]: info: [1741264395769] CoreMusicLibrary::Adding element Spotify Mar 06 21:33:15 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:15 volumio volumio[878]: Cannot find translation for source YouTube2 Mar 06 21:33:15 volumio volumio[878]: Cannot find translation for source YouTube Music Mar 06 21:33:15 volumio volumio[878]: Cannot find translation for source Spotify Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=info msg="connected to ap-gae2.spotify.com:4070" Mar 06 21:33:15 volumio go-librespot[1133]: time="2025-03-06T21:33:15+09:00" level=debug msg="completed keyexchange" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="completed challenge" Mar 06 21:33:16 volumio volumio[878]: info: [yt-cast-receiver] DIAL server listening on port 8098 Mar 06 21:33:16 volumio volumio[878]: info: CoreCommandRouter::volumioRetrievevolume Mar 06 21:33:16 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:16 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:16 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:16 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:16 volumio volumio[878]: error: [ytcr] VolumeControl failed to obtain volume from Volumio: Mar 06 21:33:16 volumio volumio[878]: (TypeError) Cannot read property 'vol' of undefined Mar 06 21:33:16 volumio volumio[878]: TypeError: Cannot read property 'vol' of undefined Mar 06 21:33:16 volumio volumio[878]: at VolumeControl.getVolume (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:56:42) Mar 06 21:33:16 volumio volumio[878]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 06 21:33:16 volumio volumio[878]: at async VolumeControl.init (/data/plugins/music_service/ytcr/dist/lib/VolumeControl.js:28:68) Mar 06 21:33:16 volumio volumio[878]: at async /data/plugins/music_service/ytcr/dist/index.js:326:13 Mar 06 21:33:16 volumio volumio[878]: info: AutoStart - Plugin is starting Mar 06 21:33:16 volumio volumio[878]: info: CoreCommandRouter::volumioGetQueue Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::getQueue Mar 06 21:33:16 volumio volumio[878]: info: CorePlayQueue::getQueue Mar 06 21:33:16 volumio volumio[878]: info: AutoStart - start playing Mar 06 21:33:16 volumio volumio[878]: info: AutoStart - start playing with no specific position Mar 06 21:33:16 volumio volumio[878]: info: CoreCommandRouter::volumioPlay Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::play index 0 Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::stop Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::play index undefined Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 21:33:16 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:16 volumio volumio[878]: info: CoreStateMachine::startPlaybackTimer Mar 06 21:33:16 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:16 volumio volumio[878]: info: [youtube2-play] clearAddPlayTrack: youtube2/video@endpoint:o=%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%221pRIMrcJcHc%22%7D%7D@explodeTrackData:o=%7B%22title%22%3A%22HI-RES%20MUSIC%20AUDIOPHILE%20VOCAL%20SOUND%20TEST%20-%2024BIT%20%7C%20SOUND%20HD%22%2C%22artist%22%3A%22SOUND%20HD%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2F1pRIMrcJcHc%2Fhqdefault.jpg%3Fsqp%3D-oaymwEcCNACELwBSFXyq4qpAw4IARUAAIhCGAFwAcABBg%3D%3D%26rs%3DAOn4CLAjJHLHBfJleMe51NIe90dYndSUwA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%221pRIMrcJcHc%22%7D%7D%7D Mar 06 21:33:16 volumio volumio[878]: info: [youtube2] InnertubeLoader: creating Innertube instance... Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="authenticated as 314nvqajtxutoiwn4kfsovj2bo4i" Mar 06 21:33:16 volumio volumio[878]: info: Completed starting Core Plugins Mar 06 21:33:16 volumio volumio[878]: info: ------------------------------------------- Mar 06 21:33:16 volumio volumio[878]: info: ----- MyVolumio plugins startup ---- Mar 06 21:33:16 volumio volumio[878]: info: ------------------------------------------- Mar 06 21:33:16 volumio volumio[878]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="authenticated as 314nvqajtxutoiwn4kfsovj2bo4i" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="dealer connection opened" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="initializing zeroconf session, username: 314nvqajtxutoiwn4kfsovj2bo4i" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="skipping packet PacketType(66), len: 207" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="autoplay enabled: false" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="received connection id: YTYyYzVlOWItNjBjZS00Y2ZjLThhOTEtZjQzMzE5OTI5M2MxK2RlYWxlcit0Y3A6Ly8wYWM5NDhhNC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMTYwOUI4RjY5OEM1OTE0NTE2REZGQzBCMkMwQkVEM0E3QkM0Qjc5MjFGMzY0MjE2ODhCMTZCN0VDQzJFQkIzOA==" Mar 06 21:33:16 volumio go-librespot[1133]: time="2025-03-06T21:33:16+09:00" level=debug msg="put connect state because NEW_DEVICE" Mar 06 21:33:17 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: Listing playlists Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: Listing playlists Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: Listing playlists Mar 06 21:33:17 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:17 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:17 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:17 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:17 volumio volumio[878]: info: go-librespot daemon successfully initialized Mar 06 21:33:23 volumio volumio[878]: info: Initializing connection to go-librespot Websocket Mar 06 21:33:23 volumio volumio[878]: info: Volumio Network Manager: Network status updated: 1 Mar 06 21:33:23 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 06 21:33:23 volumio go-librespot[1133]: time="2025-03-06T21:33:23+09:00" level=debug msg="new websocket client" Mar 06 21:33:23 volumio volumio[878]: info: Connection to go-librespot Websocket established Mar 06 21:33:23 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 06 21:33:24 volumio volumio[878]: info: [youtube2] InnertubeLoader: obtaining po_token by datasyncIdToken... Mar 06 21:33:25 volumio volumio[878]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Mar 06 21:33:25 volumio volumio[878]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Mar 06 21:33:25 volumio volumio[878]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 06 21:33:25 volumio volumio[878]: info: Adding plugin bluetooth to MyMusic Plugins Mar 06 21:33:25 volumio volumio[878]: info: Adding plugin multiroom to MyMusic Plugins Mar 06 21:33:25 volumio volumio[878]: info: Adding plugin metavolumio to MyMusic Plugins Mar 06 21:33:25 volumio volumio[878]: info: Adding plugin cd_controller to MyMusic Plugins Mar 06 21:33:25 volumio volumio[878]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 06 21:33:25 volumio volumio[878]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 06 21:33:25 volumio volumio[878]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 06 21:33:26 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 06 21:33:26 volumio volumio[878]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 06 21:33:26 volumio volumio[878]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 06 21:33:26 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:26 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:26 volumio volumio[878]: info: Starting MyVolumio Remote Streaming Endpoints Mar 06 21:33:26 volumio volumio[878]: info: MyVolumio login type: Token Mar 06 21:33:26 volumio volumio[878]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 06 21:33:26 volumio volumio[878]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 06 21:33:27 volumio volumio[1087]: ..................................................................................................................................++++ Mar 06 21:33:27 volumio volumio[878]: info: Starting Streaming Service Transparent Proxy Mar 06 21:33:27 volumio volumio[878]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 06 21:33:27 volumio volumio[878]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 06 21:33:27 volumio volumio[878]: info: Streaming services startup Mar 06 21:33:27 volumio volumio[878]: info: Starting Streaming Daemon Mar 06 21:33:27 volumio sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 06 21:33:27 volumio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:27 volumio sudo[1206]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:27 volumio volumio[878]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 06 21:33:27 volumio volumio[878]: info: Getting Spotify volume Mar 06 21:33:27 volumio volumio[878]: error: Cannot start Volumio Streaming Daemon Mar 06 21:33:27 volumio volumio[878]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 06 21:33:27 volumio volumio[878]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 06 21:33:27 volumio volumio[878]: info: Spotify volume: 100 Mar 06 21:33:27 volumio volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 06 21:33:27 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:27 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:28 volumio volumio[878]: STREAMING PROXY: Starting server on port 3245 Mar 06 21:33:28 volumio volumio[878]: Node JS runtime: 14 Mar 06 21:33:32 volumio volumio[878]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Mar 06 21:33:32 volumio volumio[878]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 06 21:33:32 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 06 21:33:32 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:32 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:33 volumio sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 21:33:33 volumio sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:33 volumio sudo[1231]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:33 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 06 21:33:33 volumio sudo[1234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 06 21:33:33 volumio sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:33 volumio sudo[1234]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 21:33:33 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:33 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: Listing playlists Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: Listing playlists Mar 06 21:33:33 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:33 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:33 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand stop Mar 06 21:33:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 21:33:33 volumio volumio[878]: info: sendMpdCommand stop took 17 milliseconds Mar 06 21:33:33 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand clear Mar 06 21:33:33 volumio volumio[878]: info: sendMpdCommand clear took 1 milliseconds Mar 06 21:33:33 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube" Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: sendMpdCommand addid "https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube" took 3 milliseconds Mar 06 21:33:33 volumio volumio[878]: verbose: MPD COMMAND [object Object] Mar 06 21:33:33 volumio volumio[878]: verbose: MPD COMMAND [object Object] Mar 06 21:33:33 volumio volumio[878]: verbose: MPD COMMAND [object Object] Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 10ms Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 8ms Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 3ms Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 4ms Mar 06 21:33:33 volumio volumio[878]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 06 21:33:33 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand play Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: Mar 06 21:33:33 volumio volumio[878]: ---------------------------- MPD announces system playlist update Mar 06 21:33:33 volumio volumio[878]: info: Ignoring MPD Status Update Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 24ms Mar 06 21:33:33 volumio volumio[878]: info: sendMpdCommand play took 22 milliseconds Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 21ms Mar 06 21:33:33 volumio volumio[878]: info: ------------------------------ 20ms Mar 06 21:33:34 volumio volumio[878]: info: MyVolumio token set successfully Mar 06 21:33:34 volumio volumio[878]: info: MYVOLUMIO: Adding device Mar 06 21:33:34 volumio volumio[878]: info: MYVOLUMIO: Evaluating Server Mar 06 21:33:34 volumio volumio[878]: info: MyVolumio status changed Mar 06 21:33:34 volumio volumio[878]: info: Streaming services startup Mar 06 21:33:34 volumio volumio[878]: info: Starting Streaming Daemon Mar 06 21:33:34 volumio volumio[878]: info: Removing browser output: myVolumio user plan is not superstar Mar 06 21:33:34 volumio volumio[878]: info: Removing audio output: Mar 06 21:33:34 volumio volumio[878]: info: Stoppping Tunnel 1 Mar 06 21:33:34 volumio sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 06 21:33:34 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:34 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:34 volumio volumio[878]: info: Mar 06 21:33:34 volumio volumio[878]: ---------------------------- MPD announces state update: player Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::getState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status Mar 06 21:33:34 volumio volumio[878]: info: Mar 06 21:33:34 volumio volumio[878]: ---------------------------- MPD announces state update: player Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::getState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status Mar 06 21:33:34 volumio volumio[878]: info: Mar 06 21:33:34 volumio volumio[878]: ---------------------------- MPD announces state update: player Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::getState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status Mar 06 21:33:34 volumio volumio[878]: info: Mar 06 21:33:34 volumio volumio[878]: ---------------------------- MPD announces state update: player Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::getState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand status took 11 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand status took 9 milliseconds Mar 06 21:33:34 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 06 21:33:34 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand status took 9 milliseconds Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 21:33:34 volumio volumio[878]: info: Mar 06 21:33:34 volumio volumio[878]: ---------------------------- MPD announces state update: player Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::getState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status Mar 06 21:33:34 volumio volumio[878]: info: Mar 06 21:33:34 volumio volumio[878]: ---------------------------- MPD announces state update: player Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::getState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand status Mar 06 21:33:34 volumio volumio[878]: error: Cannot start Volumio Streaming Daemon Mar 06 21:33:34 volumio volumio[878]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 06 21:33:34 volumio volumio[878]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand status took 20 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand status took 11 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand status took 10 milliseconds Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseState Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::pushState Mar 06 21:33:34 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::servicePushState Mar 06 21:33:34 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:34 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":1376,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HI-RES MUSIC AUDIOPHILE VOCAL SOUND TEST - 24BIT | SOUND HD","artist":"SOUND HD","album":"YouTube","uri":"https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube","trackType":"YouTube"} Mar 06 21:33:34 volumio volumio[878]: verbose: CURRENT POSITION 0 Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState stateService play Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus stop Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::servicePushState Mar 06 21:33:34 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:34 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":1376,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HI-RES MUSIC AUDIOPHILE VOCAL SOUND TEST - 24BIT | SOUND HD","artist":"SOUND HD","album":"YouTube","uri":"https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube","trackType":"YouTube"} Mar 06 21:33:34 volumio volumio[878]: verbose: CURRENT POSITION 0 Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState stateService play Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus play Mar 06 21:33:34 volumio volumio[878]: info: Received an update from plugin. extracting info from payload Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::servicePushState Mar 06 21:33:34 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:34 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":1376,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HI-RES MUSIC AUDIOPHILE VOCAL SOUND TEST - 24BIT | SOUND HD","artist":"SOUND HD","album":"YouTube","uri":"https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube","trackType":"YouTube"} Mar 06 21:33:34 volumio volumio[878]: verbose: CURRENT POSITION 0 Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState stateService play Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus play Mar 06 21:33:34 volumio volumio[878]: info: Received an update from plugin. extracting info from payload Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: ------------------------------ 67ms Mar 06 21:33:34 volumio volumio[878]: info: ------------------------------ 72ms Mar 06 21:33:34 volumio volumio[878]: info: ------------------------------ 71ms Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand playlistinfo took 42 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 06 21:33:34 volumio volumio[878]: info: sendMpdCommand playlistinfo took 38 milliseconds Mar 06 21:33:34 volumio volumio[878]: verbose: ControllerMpd::parseTrackInfo Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::servicePushState Mar 06 21:33:34 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:34 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":1376,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HI-RES MUSIC AUDIOPHILE VOCAL SOUND TEST - 24BIT | SOUND HD","artist":"SOUND HD","album":"YouTube","uri":"https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube","trackType":"YouTube"} Mar 06 21:33:34 volumio volumio[878]: verbose: CURRENT POSITION 0 Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState stateService play Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus play Mar 06 21:33:34 volumio volumio[878]: info: Received an update from plugin. extracting info from payload Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::servicePushState Mar 06 21:33:34 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:34 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":1376,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HI-RES MUSIC AUDIOPHILE VOCAL SOUND TEST - 24BIT | SOUND HD","artist":"SOUND HD","album":"YouTube","uri":"https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube","trackType":"YouTube"} Mar 06 21:33:34 volumio volumio[878]: verbose: CURRENT POSITION 0 Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState stateService play Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus play Mar 06 21:33:34 volumio volumio[878]: info: Received an update from plugin. extracting info from payload Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: ControllerMpd::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::servicePushState Mar 06 21:33:34 volumio volumio[878]: info: CorePlayQueue::getTrack 0 Mar 06 21:33:34 volumio volumio[878]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":1376,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HI-RES MUSIC AUDIOPHILE VOCAL SOUND TEST - 24BIT | SOUND HD","artist":"SOUND HD","album":"YouTube","uri":"https://rr3---sn-n3cgv5qc5oq-bh2sk.googlevideo.com/videoplayback?expire=1741286013&ei=HZbJZ7HRLufD0-kPyfOwmQ0&ip=175.119.85.97&id=o-AHaWREwqih14d2q_OzyrTmqRZSda4Q_TmiFjLYbAJXZr&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1741264413%2C&mh=gO&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh2sk%2Csn-oguesn6r&ms=au%2Conr&mv=m&mvi=3&pl=16&rms=au%2Cau&initcwndbps=3737500&siu=1&bui=AUWDL3y5te69ORQjz-3ecc-_P5N7FVgyslPS3WPp3si1FlLaY9by2KA_AHo8dca30ks5bbxVFQ&spc=RjZbSa_UromObefxR6VDBKK2ulF2URJDs052sywJTAZrb0datjncEsokl2pWx6hol_DFkMItD63Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=BYYC-07-nlk3qUwwSHv1RR0Q&rqh=1&gir=yes&clen=24574113&dur=1375.961&lmt=1722043467203576&mt=1741263889&fvip=2&keepalive=yes&fexp=51326932%2C51355912%2C51411871&c=WEB&sefc=1&txp=6308224&n=smZvETKBcCY97w&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AFVRHeAwRAIgPpfNho7FDPzEck_JLX3gNriN1Kv-dBJIAZ9PtUwCS6kCIBGRq0A1srHfRMywREgYBCTjRRfQQkdqsuzYP8C-wKtK&sig=AJfQdSswRgIhAKp2KSyRACDOou75kgxI_ZPOkeAqduS2Ve5DumwzuiDdAiEA4zbuWrkG6TCVicIiDOnjAW4y9NxURS1OaUpto9CApeo%3D&pot=Ml9DMmMheUVNlkMOx_gmHlV5vQLmT8JLW6WGoSmMG_tCyav6VBFGQeA5O15Pl2Mk4jh7LddGDnoE06HuAVxtovPCcIrlbyUGhdaUfe-57f2a4XXn_mMWA2PTH95LhXsNUw%3D%3D&cver=2.20241121.01.00&t.YouTube","trackType":"YouTube"} Mar 06 21:33:34 volumio volumio[878]: verbose: CURRENT POSITION 0 Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState stateService play Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::syncState currentStatus play Mar 06 21:33:34 volumio volumio[878]: info: Received an update from plugin. extracting info from payload Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:34 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:34 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:35 volumio volumio[878]: info: ------------------------------ 102ms Mar 06 21:33:35 volumio volumio[878]: info: ------------------------------ 91ms Mar 06 21:33:35 volumio volumio[878]: info: ------------------------------ 90ms Mar 06 21:33:35 volumio volumio[878]: info: Remote SSH Stopped Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:35 volumio volumio[878]: info: Setting Geolocation for MyVolumio to as1 Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 06 21:33:35 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:35 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:35 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:35 volumio volumio[878]: info: Successfully Added MyVolumio device Mar 06 21:33:35 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 06 21:33:35 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:35 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:35 volumio sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 06 21:33:35 volumio sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:35 volumio sudo[1280]: pam_unix(sudo:session): session closed for user root Mar 06 21:33:35 volumio volumio[878]: verbose: New Socket.io Connection to 192.168.0.55 from 192.168.0.42 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio systemd[1]: systemd-timedated.service: Succeeded. Mar 06 21:33:36 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:36 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:36 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetVisibleSources Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 06 21:33:36 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:36 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: Listing playlists Mar 06 21:33:36 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:36 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: Received Get System Info Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 06 21:33:36 volumio volumio[878]: info: Discovery: Getting this device information Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: Updating MyVolumio device info Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 06 21:33:36 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:36 volumio volumio[878]: info: Successfully Updated MyVolumio device Mar 06 21:33:38 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 06 21:33:38 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 06 21:33:41 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 06 21:33:41 volumio volumio[878]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 06 21:33:41 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:43 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 06 21:33:43 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 06 21:33:44 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 06 21:33:44 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 21:33:44 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:44 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 06 21:33:44 volumio volumio-remote-updater[611]: Test mode enabled Mar 06 21:33:44 volumio volumio-remote-updater[611]: No alpha test mode Mar 06 21:33:44 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 06 21:33:45 volumio volumio[878]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 06 21:33:45 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 06 21:33:45 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::volumioGetBrowseSources Mar 06 21:33:46 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 21:33:47 volumio volumio[878]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 06 21:33:47 volumio volumio[878]: info: Completed starting MyVolumio Plugin Mar 06 21:33:47 volumio volumio[878]: [Metrics] CommandRouter: 48s 248.15ms Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::volumiosetStartupVolume Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 06 21:33:47 volumio volumio[878]: info: VolumeController:: Setting startup Volume 100 Mar 06 21:33:47 volumio volumio[878]: info: VolumeController::SetAlsaVolume100 Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::Close All Modals sent Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::Close All Modals sent Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::volumioGetState Mar 06 21:33:47 volumio volumio[878]: info: CoreStateMachine::pushState Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 21:33:47 volumio volumio[878]: info: CoreCommandRouter::volumioPushState Mar 06 21:33:47 volumio volumio[878]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 06 21:33:47 volumio volumio[878]: aplay: main:828: audio open error: Device or resource busy Mar 06 21:33:48 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 06 21:33:48 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 06 21:33:48 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 06 21:33:49 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 06 21:33:53 volumio volumio[878]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.4.0/volumio/buster/armhf Mar 06 21:33:54 volumio volumio[878]: info: BOOT COMPLETED Mar 06 21:33:55 volumio volumio[878]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.4.0/volumio/buster/armhf Mar 06 21:33:55 volumio volumio[878]: info: Folder /tmp/plugins removed Mar 06 21:33:55 volumio volumio[878]: info: Check plugin dependencies Mar 06 21:33:55 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 21:33:55 volumio volumio[878]: info: Checking if plugin already exists Mar 06 21:33:55 volumio volumio[878]: info: Rename folder Mar 06 21:33:55 volumio volumio[878]: info: Folder /tmp/downloaded_plugin.zip removed Mar 06 21:33:55 volumio volumio[878]: info: Move to category Mar 06 21:33:56 volumio volumio[878]: info: Checking if install.sh is present Mar 06 21:33:56 volumio volumio[878]: info: Executing install.sh Mar 06 21:33:56 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/install.sh Mar 06 21:33:56 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:33:56 volumio volumio[878]: info: Re-synchronizing package index files from their sources Mar 06 21:33:56 volumio volumio[878]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease Mar 06 21:33:57 volumio volumio[878]: info: Hit:2 http://raspbian.raspberrypi.org/raspbian buster InRelease Mar 06 21:33:57 volumio volumio[878]: info: Hit:3 http://archive.raspberrypi.org/debian buster InRelease Mar 06 21:33:59 volumio volumio[1087]: ............................................................................................................................................................................................................................................................................................................................................................................++++ Mar 06 21:33:59 volumio volumio[1087]: e is 65537 (0x010001) Mar 06 21:33:59 volumio volumio[1087]: writing RSA key Mar 06 21:34:00 volumio ntpd[843]: Soliciting pool server 141.164.43.237 Mar 06 21:34:01 volumio ntpd[843]: Soliciting pool server 106.247.248.106 Mar 06 21:34:02 volumio volumio[878]: info: Reading package lists... Mar 06 21:34:02 volumio ntpd[843]: Soliciting pool server 221.151.118.78 Mar 06 21:34:02 volumio ntpd[843]: Soliciting pool server 193.123.243.2 Mar 06 21:34:03 volumio volumio[878]: info: Running apt-get -y install failed Mar 06 21:34:03 volumio volumio[878]: info: Plugin failed to install! Mar 06 21:34:03 volumio volumio[878]: info: Cleaning up... Mar 06 21:34:03 volumio ntpd[843]: Soliciting pool server 175.195.167.194 Mar 06 21:34:03 volumio ntpd[843]: Soliciting pool server 121.174.142.82 Mar 06 21:34:04 volumio systemd[1]: Reloading. Mar 06 21:34:04 volumio ntpd[843]: Soliciting pool server 2603:c022:8004:7800:65bf:c600:a739:1bee Mar 06 21:34:04 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 06 21:34:04 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 06 21:34:05 volumio volumio[878]: info: Removing dependencies Mar 06 21:34:05 volumio volumio[878]: info: Deleting /opt/volumiokiosk.sh Mar 06 21:34:05 volumio volumio[878]: info: Deleting /data/volumiokiosk Mar 06 21:34:05 volumio volumio[878]: info: Deleting /lib/systemd/system/volumio-kiosk.service Mar 06 21:34:05 volumio volumio[878]: info: Enabling login prompt Mar 06 21:34:05 volumio volumio[878]: info: Done Mar 06 21:34:05 volumio volumio[878]: info: Removing plugin directory /data/plugins/user_interface/touch_display Mar 06 21:34:05 volumio volumio[878]: info: Removing temporary directory /tmp/touch_display-GH4YhFEe5o Mar 06 21:34:05 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Mar 06 21:34:05 volumio volumio[878]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/user_interface/touch_display/install.sh > /tmp/installog Mar 06 21:34:05 volumio volumio[878]: [sudo] password for volumio: E: dpkg was interrupted, you must manually run 'sudo dpkg --configure -a' to correct the problem. Mar 06 21:34:05 volumio volumio[878]: E: dpkg was interrupted, you must manually run 'sudo dpkg --configure -a' to correct the problem. Mar 06 21:34:05 volumio volumio[878]: rm: cannot remove '/opt/volumiokiosk.sh': No such file or directory Mar 06 21:34:05 volumio volumio[878]: rm: cannot remove '/lib/systemd/system/volumio-kiosk.service': No such file or directory Mar 06 21:34:05 volumio volumio[878]: info: An error occurred installing the plugin. Rolling back config Mar 06 21:34:05 volumio volumio[878]: info: Plugin folders cleanup Mar 06 21:34:05 volumio volumio[878]: info: Scanning into folder /volumio/app/plugins/ Mar 06 21:34:05 volumio volumio[878]: info: Scanning category audio_interface Mar 06 21:34:05 volumio volumio[878]: info: Scanning category miscellanea Mar 06 21:34:05 volumio volumio[878]: info: Scanning category music_service Mar 06 21:34:05 volumio volumio[878]: info: Scanning category plugins.json Mar 06 21:34:05 volumio volumio[878]: info: Scanning category system_controller Mar 06 21:34:05 volumio volumio[878]: info: Scanning category user_interface Mar 06 21:34:05 volumio volumio[878]: info: Scanning into folder /data/plugins/ Mar 06 21:34:05 volumio volumio[878]: info: Scanning category music_service Mar 06 21:34:05 volumio volumio[878]: info: Scanning category system_controller Mar 06 21:34:05 volumio volumio[878]: info: Scanning category user_interface Mar 06 21:34:05 volumio volumio[878]: info: Plugin folders cleanup completed Mar 06 21:34:05 volumio volumio[878]: info: Error: Error Mar 06 21:34:05 volumio volumio[878]: Plugin install end detected on script Mar 06 21:34:05 volumio volumio[878]: info: Folder /tmp/plugins removed Mar 06 21:34:05 volumio volumio[878]: info: Folder /tmp/downloaded_plugin.zip removed Mar 06 21:34:05 volumio volumio[878]: info: Folder /data/temp removed Mar 06 21:34:05 volumio ntpd[843]: Soliciting pool server 194.0.5.123 Mar 06 21:34:05 volumio ntpd[843]: Soliciting pool server 121.174.142.81 Mar 06 21:34:08 volumio ntpd[843]: receive: Unexpected origin timestamp 0xeb7414bf.ae5dd64d does not match aorg 0000000000.00000000 from server@221.151.118.78 xmt 0xeb7414c0.51295f54 Mar 06 21:34:28 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 06 21:34:30 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 06 21:34:32 volumio volumio[878]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.1/volumio/buster/armhf Mar 06 21:34:33 volumio volumio[878]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.1/volumio/buster/armhf Mar 06 21:34:33 volumio volumio[878]: info: Folder /tmp/plugins removed Mar 06 21:34:33 volumio volumio[878]: info: Check plugin dependencies Mar 06 21:34:33 volumio volumio[878]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 06 21:34:33 volumio volumio[878]: info: Checking if plugin already exists Mar 06 21:34:33 volumio volumio[878]: info: Rename folder Mar 06 21:34:34 volumio volumio[878]: info: Folder /tmp/downloaded_plugin.zip removed Mar 06 21:34:34 volumio volumio[878]: info: Move to category Mar 06 21:34:34 volumio volumio[878]: info: Checking if install.sh is present Mar 06 21:34:34 volumio volumio[878]: info: Executing install.sh Mar 06 21:34:34 volumio sudo[1829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/touch_display/install.sh Mar 06 21:34:34 volumio sudo[1829]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 06 21:34:34 volumio volumio[878]: info: Re-synchronizing package index files from their sources Mar 06 21:34:34 volumio volumio[878]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease Mar 06 21:34:36 volumio volumio[878]: info: Hit:2 http://raspbian.raspberrypi.org/raspbian buster InRelease Mar 06 21:34:36 volumio volumio[878]: info: Hit:3 http://archive.raspberrypi.org/debian buster InRelease Mar 06 21:34:38 volumio volumio[878]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.1/volumio/buster/armhf Mar 06 21:34:39 volumio volumio[878]: info: Reading package lists... Mar 06 21:34:39 volumio volumio[878]: info: Running apt-get -y install failed Mar 06 21:34:39 volumio volumio[878]: info: Plugin failed to install! Mar 06 21:34:39 volumio volumio[878]: info: Cleaning up... Mar 06 21:34:39 volumio volumio[878]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/touch_display/3.5.1/volumio/buster/armhf Mar 06 21:34:39 volumio volumio[878]: info: Folder /tmp/plugins removed Mar 06 21:34:39 volumio systemd[1]: Reloading. Mar 06 21:34:40 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 06 21:34:40 volumio volumio[878]: info: Removing dependencies Mar 06 21:34:40 volumio volumio[878]: info: Deleting /opt/volumiokiosk.sh Mar 06 21:34:40 volumio volumio[878]: info: Deleting /data/volumiokiosk Mar 06 21:34:40 volumio volumio[878]: info: Deleting /data/volumiokioskextensions Mar 06 21:34:40 volumio volumio[878]: info: Deleting /lib/systemd/system/volumio-kiosk.service Mar 06 21:34:40 volumio volumio[878]: info: Enabling login prompt Mar 06 21:34:40 volumio volumio[878]: info: Done Mar 06 21:34:40 volumio volumio[878]: info: Removing plugin directory /data/plugins/user_interface/touch_display Mar 06 21:34:40 volumio volumio[878]: info: Removing temporary directory /tmp/touch_display-kjgDxlZMRy Mar 06 21:34:40 volumio sudo[1829]: pam_unix(sudo:session): session closed for user root Mar 06 21:34:40 volumio volumio[878]: info: Install script return the error Error: Command failed: echo volumio | sudo -S sh /data/plugins/user_interface/touch_display/install.sh > /tmp/installog Mar 06 21:34:40 volumio volumio[878]: [sudo] password for volumio: E: dpkg was interrupted, you must manually run 'sudo dpkg --configure -a' to correct the problem. Mar 06 21:34:40 volumio volumio[878]: E: dpkg was interrupted, you must manually run 'sudo dpkg --configure -a' to correct the problem. Mar 06 21:34:40 volumio volumio[878]: rm: cannot remove '/opt/volumiokiosk.sh': No such file or directory Mar 06 21:34:40 volumio volumio[878]: rm: cannot remove '/lib/systemd/system/volumio-kiosk.service': No such file or directory Mar 06 21:34:40 volumio volumio[878]: info: An error occurred installing the plugin. Rolling back config Mar 06 21:34:40 volumio volumio[878]: info: Plugin folders cleanup Mar 06 21:34:40 volumio volumio[878]: info: Scanning into folder /volumio/app/plugins/ Mar 06 21:34:40 volumio volumio[878]: info: Scanning category audio_interface Mar 06 21:34:40 volumio volumio[878]: info: Scanning category miscellanea Mar 06 21:34:40 volumio volumio[878]: info: Scanning category music_service Mar 06 21:34:40 volumio volumio[878]: info: Scanning category plugins.json Mar 06 21:34:40 volumio volumio[878]: info: Scanning category system_controller Mar 06 21:34:40 volumio volumio[878]: info: Scanning category user_interface Mar 06 21:34:40 volumio volumio[878]: info: Scanning into folder /data/plugins/ Mar 06 21:34:40 volumio volumio[878]: info: Scanning category music_service Mar 06 21:34:40 volumio volumio[878]: info: Scanning category system_controller Mar 06 21:34:40 volumio volumio[878]: info: Scanning category user_interface Mar 06 21:34:40 volumio volumio[878]: info: Plugin folders cleanup completed Mar 06 21:34:40 volumio volumio[878]: info: Error: Error Mar 06 21:34:40 volumio volumio[878]: Plugin install end detected on script Mar 06 21:34:40 volumio volumio[878]: info: Folder /tmp/plugins removed Mar 06 21:34:40 volumio volumio[878]: info: Folder /tmp/downloaded_plugin.zip removed Mar 06 21:34:41 volumio volumio[878]: info: Folder /data/temp removed Mar 06 21:34:41 volumio volumio[878]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 21:34:41 volumio volumio[878]: Error: Unable to resolve or reject the same promise twice Mar 06 21:34:41 volumio volumio[878]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Mar 06 21:34:41 volumio volumio[878]: at /volumio/app/pluginmanager.js:1087:11 Mar 06 21:34:41 volumio volumio[878]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Mar 06 21:34:41 volumio volumio[878]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Mar 06 21:34:41 volumio volumio[878]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Mar 06 21:34:41 volumio volumio[878]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Mar 06 21:34:41 volumio volumio[878]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Mar 06 21:34:41 volumio volumio[878]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Mar 06 21:34:41 volumio volumio[878]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Mar 06 21:34:41 volumio volumio[878]: at FSReqCallback.oncomplete (fs.js:171:23) Mar 06 21:34:41 volumio volumio[878]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 21:34:41 volumio sudo[2116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-06 21:33 Mar 06 21:34:41 volumio sudo[2116]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"