Jun 14 23:23:04 volumiosony ntpd[1031]: CLOCK: time stepped by 30.101012 Jun 14 23:23:04 volumiosony ntpd[1031]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 14 23:23:04 volumiosony volumio[1328]: info: Loading i18n strings for locale hu Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 188.156.114.7 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 80.249.164.126 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 82.141.152.3 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 91.82.109.180 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 2001:4c48:2::1 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 2001:ac8:26:62:0:1:0:14 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 2001:ac8:26:62:0:1:0:21 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: Pool taking: 2001:4c48:1::1 Jun 14 23:23:04 volumiosony ntpd[1031]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Jun 14 23:23:04 volumiosony volumio[1328]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 14 23:23:04 volumiosony volumio[1328]: Updating browse sources language Jun 14 23:23:04 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::initPlayerControls Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: Express server listening on port 3000 Jun 14 23:23:05 volumiosony volumio[1328]: [Metrics] WebUI: 9s 964.91ms Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::resetVolumioState Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::getcurrentVolume Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::getcurrentVolume Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:23:05 volumiosony volumio[1328]: info: Volumio Network Manager: Network status updated: 2 Jun 14 23:23:05 volumiosony volumio[1328]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jun 14 23:23:05 volumiosony ntpd[1031]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 14 23:23:05 volumiosony volumio[1328]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jun 14 23:23:05 volumiosony volumio[1328]: info: VolumeController:: Volume=undefined Mute =false Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::updateTrackBlock Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrackBlock Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:23:05 volumiosony volumio[1328]: info: VolumeController:: Volume=undefined Mute =false Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::updateTrackBlock Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrackBlock Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:23:05 volumiosony volumio[1328]: info: Discovery: Getting this device information Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:23:05 volumiosony volumio[1328]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jun 14 23:23:05 volumiosony volumio[1328]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:05 volumiosony volumio[1328]: info: Reloading queue from file Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio-remote-updater[703]: [2025-06-14 23:23:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1749936152 101 Jun 14 23:23:05 volumiosony volumio[1328]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 1 Jun 14 23:23:05 volumiosony volumio[1328]: info: VolumeController:: Volume=undefined Mute =false Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::setRepeat null single undefined Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::setRandom null Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:05 volumiosony volumio[1328]: info: Setting Device type: Raspberry PI Jun 14 23:23:05 volumiosony volumio[1328]: info: Completed loading Core Plugins Jun 14 23:23:05 volumiosony volumio[1328]: info: Preparing to generate the ALSA configuration file Jun 14 23:23:05 volumiosony volumio[1328]: info: VolumeController:: Volume=undefined Mute =false Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:05 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:05 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:05 volumiosony volumio[1328]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 14 23:23:05 volumiosony volumio[1328]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Jun 14 23:23:05 volumiosony volumio[1328]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 14 23:23:05 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:05 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:05 volumiosony volumio[1328]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 14 23:23:05 volumiosony volumio[1328]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 14 23:23:05 volumiosony volumio[1328]: info: Reading ALSA contributions from plugins. Jun 14 23:23:05 volumiosony volumio[1328]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: adding 3fad2826-4ce6-47f1-94d3-e1e2f13e1591 Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: Found device Volumiosony Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:06 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: this is already registered, 3fad2826-4ce6-47f1-94d3-e1e2f13e1591 Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: Found device Volumiosony Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:06 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: adding 4d3e631f-3fd2-4886-af7a-0a628a4a909d Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: Found device Volumio Jun 14 23:23:06 volumiosony volumio[1328]: info: Discovery: Connecting to remote: 192.168.1.2 Jun 14 23:23:06 volumiosony volumio[1328]: info: Asound.conf file unchanged, so no further update is needed Jun 14 23:23:06 volumiosony volumio[1328]: info: Output device has changed, restarting MPD Jun 14 23:23:06 volumiosony volumio[1328]: info: ___________ START PLUGINS ___________ Jun 14 23:23:06 volumiosony volumio[1328]: info: ControllerMpd::onStart: Initializing MPD Jun 14 23:23:06 volumiosony volumio[1328]: info: Creating MPD Configuration file Jun 14 23:23:06 volumiosony sudo[1566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:23:06 volumiosony sudo[1566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony sudo[1566]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:23:06 volumiosony volumio[1328]: info: [1749936186315] CoreMusicLibrary::Adding element Last_100 Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:23:06 volumiosony volumio[1328]: info: [1749936186323] CoreMusicLibrary::Adding element Webradio Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:23:06 volumiosony volumio[1328]: info: Initializing BBC Radios Jun 14 23:23:06 volumiosony sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:23:06 volumiosony sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony sudo[1573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:23:06 volumiosony sudo[1573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony sudo[1571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:23:06 volumiosony sudo[1571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony sudo[1571]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:23:06 volumiosony volumio[1328]: info: [1749936186473] CoreMusicLibrary::Adding element 80s80s Radio Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:23:06 volumiosony volumio[1328]: Cannot find translation for source 80s80s Radio Jun 14 23:23:06 volumiosony systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 14 23:23:06 volumiosony systemd[1]: Starting mpd.service - Music Player Daemon... Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 23:23:06 volumiosony volumio[1328]: info: [1749936186487] CoreMusicLibrary::Adding element Radio Paradise Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:23:06 volumiosony volumio[1328]: Cannot find translation for source 80s80s Radio Jun 14 23:23:06 volumiosony volumio[1328]: Cannot find translation for source Radio Paradise Jun 14 23:23:06 volumiosony systemd[1]: mpd.service: Deactivated successfully. Jun 14 23:23:06 volumiosony systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 14 23:23:06 volumiosony systemd[1]: mpd.socket: Deactivated successfully. Jun 14 23:23:06 volumiosony systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 14 23:23:06 volumiosony systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 14 23:23:06 volumiosony systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 14 23:23:06 volumiosony systemd[1]: Starting mpd.service - Music Player Daemon... Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 14 23:23:06 volumiosony volumio[1328]: info: Loading i18n strings for locale hu Jun 14 23:23:06 volumiosony volumio[1328]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jun 14 23:23:06 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:23:06 volumiosony volumio[1328]: info: Volumio Calling Home Jun 14 23:23:06 volumiosony volumio[1328]: info: Preparing to generate the ALSA configuration file Jun 14 23:23:06 volumiosony sudo[1600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 14 23:23:06 volumiosony sudo[1598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 14 23:23:06 volumiosony sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony sudo[1602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 14 23:23:06 volumiosony sudo[1602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony sudo[1600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:06 volumiosony systemd[1]: Reloading. Jun 14 23:23:06 volumiosony sudo[1593]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 14 23:23:06 volumiosony sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 14 23:23:07 volumiosony sudo[1606]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 14 23:23:07 volumiosony sudo[1593]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:07 volumiosony systemd[1]: /etc/systemd/system/dsi_wakeup.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 14 23:23:07 volumiosony systemd[1]: /etc/systemd/system/dsi_wakeup.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:23:07 volumiosony volumio[1328]: info: Discovery: Getting this device information Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:07 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:23:07 volumiosony volumio[1328]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 14 23:23:07 volumiosony volumio[1328]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Jun 14 23:23:07 volumiosony volumio[1328]: info: Reading ALSA contributions from plugins. Jun 14 23:23:07 volumiosony volumio[1328]: info: touch_display: Backlight interface detected. Jun 14 23:23:07 volumiosony volumio[1328]: info: Discovery: Connected to remote: 192.168.1.2 Jun 14 23:23:07 volumiosony volumio[1328]: info: MPD Permissions set Jun 14 23:23:07 volumiosony volumio[1328]: info: MPD Permissions set Jun 14 23:23:07 volumiosony volumio[1328]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony sudo[1602]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:07 volumiosony systemd[1]: Reloading. Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:07 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:08 volumiosony volumio[1328]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 14 23:23:08 volumiosony volumio[1328]: info: touch_display: systemctl daemon-reload succeeded. Jun 14 23:23:08 volumiosony sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 14 23:23:08 volumiosony sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:08 volumiosony sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/10-0045/brightness Jun 14 23:23:08 volumiosony sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:08 volumiosony sudo[1673]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:08 volumiosony sudo[1676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 14 23:23:08 volumiosony sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:08 volumiosony volumio[1328]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 14 23:23:08 volumiosony sudo[1676]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:08 volumiosony sudo[1679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 14 23:23:08 volumiosony sudo[1679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:08 volumiosony sudo[1679]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:08 volumiosony systemd[1]: /etc/systemd/system/dsi_wakeup.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 14 23:23:08 volumiosony systemd[1]: /etc/systemd/system/dsi_wakeup.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 14 23:23:08 volumiosony volumio[1328]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 14 23:23:08 volumiosony volumio[1328]: info: touch_display: File permissions for backlight brightness control set. Jun 14 23:23:09 volumiosony sudo[1600]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 14 23:23:09 volumiosony volumio[1328]: info: Asound.conf file unchanged, so no further update is needed Jun 14 23:23:09 volumiosony volumio[1328]: info: Output device has changed, restarting MPD Jun 14 23:23:09 volumiosony systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jun 14 23:23:09 volumiosony sudo[1670]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:09 volumiosony sudo[1598]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:09 volumiosony sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 23:23:09 volumiosony sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 14 23:23:09 volumiosony sudo[1685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 23:23:09 volumiosony sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:09 volumiosony sudo[1685]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:09 volumiosony systemd[1]: mpd.service: Deactivated successfully. Jun 14 23:23:09 volumiosony systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 14 23:23:09 volumiosony systemd[1]: mpd.socket: Deactivated successfully. Jun 14 23:23:09 volumiosony systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 14 23:23:09 volumiosony systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 14 23:23:09 volumiosony systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 14 23:23:09 volumiosony systemd[1]: Starting mpd.service - Music Player Daemon... Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: Volumio Kiosk started. Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:09 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:09 volumiosony startx[1716]: X.Org X Server 1.21.1.7 Jun 14 23:23:09 volumiosony startx[1716]: X Protocol Version 11, Revision 0 Jun 14 23:23:09 volumiosony startx[1716]: Current Operating System: Linux volumiosony 6.12.27-v7l+ #1876 SMP Tue May 6 15:10:05 BST 2025 armv7l Jun 14 23:23:09 volumiosony startx[1716]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:640x480M@60D smsc95xx.macaddr=E4:5F:01:29:1F:18 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9aae75e3-71dc-4cd8-81c2-e432641843a7 imgfile=/volumio_current.sqsh bootpart=UUID=0F99-EC7C datapart=UUID=6fee81e4-7bf1-40e5-aaed-c3a77319b96c uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jun 14 23:23:09 volumiosony startx[1716]: xorg-server 2:21.1.7-3+rpt3+deb12u9 (https://www.debian.org/support) Jun 14 23:23:09 volumiosony startx[1716]: Current version of pixman: 0.44.0 Jun 14 23:23:09 volumiosony startx[1716]: Before reporting problems, check http://wiki.x.org Jun 14 23:23:09 volumiosony startx[1716]: to make sure that you have the latest version. Jun 14 23:23:09 volumiosony startx[1716]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 14 23:23:09 volumiosony startx[1716]: (++) from command line, (!!) notice, (II) informational, Jun 14 23:23:09 volumiosony startx[1716]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 14 23:23:09 volumiosony startx[1716]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jun 14 23:23:09 2025 Jun 14 23:23:09 volumiosony startx[1716]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 14 23:23:09 volumiosony startx[1716]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:09 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:09 volumiosony volumio[1328]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.2 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 14 23:23:09 volumiosony volumio[1328]: info: MPD Permissions set Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: X display number found: 0 Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:09 volumiosony volumio[1328]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.2 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 14 23:23:09 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:09 volumiosony sudo[1717]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 14 23:23:09 volumiosony sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 14 23:23:09 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 14 23:23:09 volumiosony sudo[1728]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 14 23:23:09 volumiosony sudo[1717]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:09 volumiosony volumio[1328]: info: touch_display: X display number found: 0 Jun 14 23:23:10 volumiosony sudo[1398]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:10 volumiosony ntpd[1031]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Jun 14 23:23:10 volumiosony ntpd[1031]: DNS: Pool taking: 193.224.163.77 Jun 14 23:23:10 volumiosony ntpd[1031]: DNS: Pool taking: 195.228.75.149 Jun 14 23:23:10 volumiosony ntpd[1031]: DNS: Pool taking: 81.0.124.253 Jun 14 23:23:10 volumiosony ntpd[1031]: DNS: Pool taking: 81.0.116.13 Jun 14 23:23:10 volumiosony ntpd[1031]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Jun 14 23:23:11 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jun 14 23:23:11 volumiosony ntpd[1031]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Jun 14 23:23:11 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:11 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:12 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:12 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:12 volumiosony volumio[1328]: info: Volumio called home Jun 14 23:23:14 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:14 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:14 volumiosony volumio[1328]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 14 23:23:16 volumiosony ntpd[1031]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Jun 14 23:23:16 volumiosony ntpd[1031]: DNS: Pool taking: 194.38.104.150 Jun 14 23:23:16 volumiosony ntpd[1031]: DNS: Pool taking: 195.111.92.55 Jun 14 23:23:16 volumiosony ntpd[1031]: DNS: Pool taking: 80.249.164.129 Jun 14 23:23:16 volumiosony ntpd[1031]: DNS: Pool taking: 62.112.193.9 Jun 14 23:23:16 volumiosony ntpd[1031]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Jun 14 23:23:17 volumiosony mpd[1729]: 2025-06-14T23:23:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 14 23:23:17 volumiosony systemd[1]: Started mpd.service - Music Player Daemon. Jun 14 23:23:17 volumiosony sudo[1688]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:17 volumiosony sudo[1573]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:17 volumiosony sudo[1568]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:17 volumiosony volumio[1328]: info: Completed starting Core Plugins Jun 14 23:23:17 volumiosony volumio[1328]: info: ------------------------------------------- Jun 14 23:23:17 volumiosony volumio[1328]: info: ----- MyVolumio plugins startup ---- Jun 14 23:23:17 volumiosony volumio[1328]: info: ------------------------------------------- Jun 14 23:23:17 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 14 23:23:17 volumiosony volumio[1328]: info: MPD running with PID1729 Jun 14 23:23:17 volumiosony volumio[1328]: ,establishing connection Jun 14 23:23:18 volumiosony volumio[1328]: error: MPD error: The expression evaluated to a falsy value: Jun 14 23:23:18 volumiosony volumio[1328]: assert.ok(self.idling) Jun 14 23:23:18 volumiosony volumio[1328]: error: The expression evaluated to a falsy value: Jun 14 23:23:18 volumiosony volumio[1328]: assert.ok(self.idling) Jun 14 23:23:18 volumiosony volumio[1328]: error: MPD error: The expression evaluated to a falsy value: Jun 14 23:23:18 volumiosony volumio[1328]: assert.ok(self.idling) Jun 14 23:23:18 volumiosony volumio[1328]: error: The expression evaluated to a falsy value: Jun 14 23:23:18 volumiosony volumio[1328]: assert.ok(self.idling) Jun 14 23:23:18 volumiosony volumio[1328]: error: MPD error: The expression evaluated to a falsy value: Jun 14 23:23:18 volumiosony volumio[1328]: assert.ok(self.idling) Jun 14 23:23:18 volumiosony volumio[1328]: error: The expression evaluated to a falsy value: Jun 14 23:23:18 volumiosony volumio[1328]: assert.ok(self.idling) Jun 14 23:23:18 volumiosony volumio[1328]: error: updateQueue error: null Jun 14 23:23:21 volumiosony systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 14 23:23:22 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:22 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:22 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:22 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:22 volumiosony volumio[1328]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 14 23:23:26 volumiosony volumio[1328]: info: Adding plugin bluetooth to MyMusic Plugins Jun 14 23:23:26 volumiosony volumio[1328]: info: Adding plugin multiroom to MyMusic Plugins Jun 14 23:23:26 volumiosony volumio[1328]: info: Adding plugin metavolumio to MyMusic Plugins Jun 14 23:23:26 volumiosony volumio[1328]: info: Adding plugin cd_controller to MyMusic Plugins Jun 14 23:23:26 volumiosony volumio[1328]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 14 23:23:26 volumiosony volumio[1328]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 14 23:23:26 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 14 23:23:28 volumiosony volumio[1328]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 14 23:23:28 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 14 23:23:28 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:28 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:28 volumiosony volumio[1328]: info: Starting MyVolumio Remote Streaming Endpoints Jun 14 23:23:28 volumiosony volumio[1328]: info: MyVolumio login type: Token Jun 14 23:23:28 volumiosony volumio[1328]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 14 23:23:28 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 14 23:23:30 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 14 23:23:30 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 14 23:23:30 volumiosony volumio[1328]: info: Streaming services startup Jun 14 23:23:30 volumiosony volumio[1328]: info: Starting Streaming Daemon Jun 14 23:23:30 volumiosony sudo[1982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:23:30 volumiosony sudo[1982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:30 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 14 23:23:30 volumiosony sudo[1982]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:30 volumiosony volumio[1328]: error: Cannot start Volumio Streaming Daemon Jun 14 23:23:30 volumiosony volumio[1328]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:23:30 volumiosony volumio[1328]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:23:31 volumiosony volumio[1328]: error: MyVolumio Custom Token format not valid, refreshing it Jun 14 23:23:32 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:32 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:33 volumiosony volumio[1328]: info: MyVolumio login type: Token Jun 14 23:23:33 volumiosony volumio[1328]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 14 23:23:36 volumiosony volumio[1328]: (node:1328) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Jun 14 23:23:36 volumiosony volumio[1328]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 14 23:23:36 volumiosony volumio[1328]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 14 23:23:36 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:23:36 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:36 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetVisibleSources Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:37 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 14 23:23:37 volumiosony volumio[1328]: info: Received Get System Info Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:23:37 volumiosony volumio[1328]: info: Discovery: Getting this device information Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:37 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:37 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:37 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:23:37 volumiosony volumio[1328]: info: Received Get System Info Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:23:37 volumiosony volumio[1328]: info: Discovery: Getting this device information Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:37 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:37 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:23:39 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 14 23:23:41 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 14 23:23:42 volumiosony volumio-remote-updater[703]: Test mode disabled Jun 14 23:23:42 volumiosony volumio-remote-updater[703]: Alpha mode enabled Jun 14 23:23:42 volumiosony volumio-remote-updater[703]: Alpha legacy test mode disabled Jun 14 23:23:42 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 14 23:23:43 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 14 23:23:43 volumiosony volumio[1328]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 14 23:23:43 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:43 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:46 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 14 23:23:47 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:47 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 23:23:48 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 23:23:50 volumiosony volumio[1328]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 14 23:23:50 volumiosony volumio[1328]: info: Completed starting MyVolumio Plugin Jun 14 23:23:50 volumiosony volumio[1328]: [Metrics] CommandRouter: 54s 938.22ms Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::volumiosetStartupVolume Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 23:23:50 volumiosony volumio[1328]: info: VolumeController:: Setting startup Volume 30 Jun 14 23:23:50 volumiosony volumio[1328]: info: VolumeController::SetAlsaVolume30 Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::Close All Modals sent Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::Close All Modals sent Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:23:50 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 23:23:50 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:23:50 volumiosony volumio[1328]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 14 23:23:50 volumiosony sudo[2026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 14 23:23:50 volumiosony sudo[2026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:50 volumiosony volumio[1328]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 14 23:23:50 volumiosony sudo[2026]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:51 volumiosony volumio[1328]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 14 23:23:51 volumiosony volumio[1328]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for sndrpihifiberry Jun 14 23:23:51 volumiosony volumio[1328]: aplay: main:831: audio open error: No such device Jun 14 23:23:51 volumiosony volumio[1328]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number '2'. Jun 14 23:23:51 volumiosony volumio[1328]: info: peppymeterbasic Daemon Stop Jun 14 23:23:51 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:51 volumiosony volumio[1328]: info: Listing playlists Jun 14 23:23:51 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 14 23:23:51 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 14 23:23:51 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 14 23:23:53 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:53 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:53 volumiosony systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 14 23:23:53 volumiosony dbus-daemon[687]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.32' (uid=0 pid=2029 comm="timedatectl show --property=NTPSynchronized --valu") Jun 14 23:23:53 volumiosony systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 14 23:23:53 volumiosony volumio[1328]: (node:1328) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Jun 14 23:23:54 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:23:54 volumiosony dbus-daemon[687]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 14 23:23:54 volumiosony systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 14 23:23:54 volumiosony setdatetime-helper.sh[2028]: Time is not synchronized. Attempting to sync... Jun 14 23:23:54 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:23:54 volumiosony volumio[1328]: info: Discovery: Getting this device information Jun 14 23:23:54 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:54 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:54 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:23:55 volumiosony volumio[1328]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 14 23:23:55 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 14 23:23:55 volumiosony volumio[1328]: info: MyVolumio token set successfully Jun 14 23:23:55 volumiosony volumio[1328]: info: MYVOLUMIO: Adding device Jun 14 23:23:55 volumiosony volumio[1328]: info: MYVOLUMIO: Evaluating Server Jun 14 23:23:56 volumiosony volumio[1328]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.81 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Jun 14 23:23:57 volumiosony volumio[1328]: info: MyVolumio Plan changed: virtuoso Jun 14 23:23:57 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso Jun 14 23:23:57 volumiosony volumio[1328]: info: Removing browser output: myVolumio user plan is not superstar Jun 14 23:23:57 volumiosony volumio[1328]: info: Removing audio output: Jun 14 23:23:57 volumiosony volumio[1328]: info: MYVOLUMIO: Adding device Jun 14 23:23:57 volumiosony volumio[1328]: info: MYVOLUMIO: Evaluating Server Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:57 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:57 volumiosony volumio[1328]: info: Remote config written successfully Jun 14 23:23:57 volumiosony volumio[1328]: info: Starting Tunnel 1 Jun 14 23:23:57 volumiosony volumio[1328]: info: Starting Tunnel Connection Checker Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:57 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 14 23:23:57 volumiosony volumio[1328]: info: Received Get System Info Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 23:23:57 volumiosony volumio[1328]: info: Discovery: Getting this device information Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:23:57 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:23:57 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 23:23:57 volumiosony volumio[1328]: info: BOOT COMPLETED Jun 14 23:23:59 volumiosony volumio[1328]: info: MYVolumio Device enabled Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 14 23:23:59 volumiosony volumio[1328]: info: MyVolumio status changed Jun 14 23:23:59 volumiosony volumio[1328]: info: Streaming services startup Jun 14 23:23:59 volumiosony volumio[1328]: info: Starting Streaming Daemon Jun 14 23:23:59 volumiosony sudo[2084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 14 23:23:59 volumiosony sudo[2084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:59 volumiosony sudo[2084]: pam_unix(sudo:session): session closed for user root Jun 14 23:23:59 volumiosony volumio[1328]: error: Cannot start Volumio Streaming Daemon Jun 14 23:23:59 volumiosony volumio[1328]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 14 23:23:59 volumiosony volumio[1328]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 14 23:23:59 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 14 23:23:59 volumiosony volumio[1328]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jun 14 23:23:59 volumiosony volumio[1328]: info: Refreshing TIDAL token Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jun 14 23:23:59 volumiosony volumio[1328]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jun 14 23:23:59 volumiosony volumio[1328]: info: Stopping AccessToken refresher cron for QOBUZ Jun 14 23:23:59 volumiosony volumio[1328]: info: AccessToken refresher cron started for QOBUZ Jun 14 23:23:59 volumiosony volumio[1328]: info: Adding QOBUZ REST API Endpoints Jun 14 23:23:59 volumiosony sudo[2088]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 14 23:23:59 volumiosony sudo[2088]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:23:59 volumiosony sudo[2088]: pam_unix(sudo:session): session closed for user root Jun 14 23:24:02 volumiosony sudo[2098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jun 14 23:24:02 volumiosony sudo[2098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:24:02 volumiosony systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 14 23:24:02 volumiosony systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 14 23:24:02 volumiosony systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jun 14 23:24:02 volumiosony sudo[2098]: pam_unix(sudo:session): session closed for user root Jun 14 23:24:02 volumiosony volumio[1328]: info: Remote SSH Started Jun 14 23:24:02 volumiosony autossh[2101]: port set to 0, monitoring disabled Jun 14 23:24:02 volumiosony autossh[2101]: starting ssh (count 1) Jun 14 23:24:02 volumiosony autossh[2101]: ssh child pid is 2104 Jun 14 23:24:03 volumiosony volumio[1328]: info: TidalConnect service stoped! Jun 14 23:24:03 volumiosony volumio[1328]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jun 14 23:24:03 volumiosony volumio[1328]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 14 23:24:03 volumiosony volumio[1328]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 14 23:24:03 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:24:03 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:24:03 volumiosony sudo[2114]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 14 23:24:03 volumiosony sudo[2114]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:24:03 volumiosony systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jun 14 23:24:03 volumiosony sudo[2114]: pam_unix(sudo:session): session closed for user root Jun 14 23:24:03 volumiosony volumio[1328]: info: CoreCommandRouter::volumioGetState Jun 14 23:24:03 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:24:04 volumiosony volumio[1328]: info: Executing endpoint tc_getconfig Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 14 23:24:04 volumiosony vtcs[2118]: OpenSSL version mismatch. Built against 101010ef, you have 30000100 Jun 14 23:24:04 volumiosony vtcs[2118]: STARTING TidalConnect services, version: 1.5.0.45 Jun 14 23:24:04 volumiosony vtcs[2118]: STARTED TidalConnect services. Jun 14 23:24:04 volumiosony volumio[1328]: info: Executing endpoint tc_connect Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 14 23:24:04 volumiosony volumio[1328]: info: Connecting to TidalConnect Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreCommandRouter::servicePushState Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:24:04 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:24:04 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:24:04 volumiosony volumio[1328]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreCommandRouter::servicePushState Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreStateMachine::pushState Jun 14 23:24:04 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:24:04 volumiosony volumio[1328]: info: CoreCommandRouter::volumioPushState Jun 14 23:24:04 volumiosony volumio[1328]: info: CorePlayQueue::getTrack 0 Jun 14 23:24:04 volumiosony volumio[1328]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 14 23:24:04 volumiosony vtcs[2118]: [2025-06-14 23:24:04.259] [tisoc] [error] [WebsocketServerTls.cpp:90] [WebsocketServerTls onTlsInit Error] use_certificate_chain: no start line Jun 14 23:24:04 volumiosony volumio[1328]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 14 23:24:04 volumiosony volumio[1328]: info: PeppyMeterBasic ---peppymeterbasic status stop Jun 14 23:24:04 volumiosony sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 14 23:24:04 volumiosony sudo[2134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:24:04 volumiosony sudo[2136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 14 23:24:04 volumiosony sudo[2136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 14 23:24:04 volumiosony sudo[2136]: pam_unix(sudo:session): session closed for user root Jun 14 23:24:04 volumiosony volumio[1328]: info: peppymeterbasic Daemon Stop Jun 14 23:24:04 volumiosony sudo[2134]: pam_unix(sudo:session): session closed for user root Jun 14 23:24:04 volumiosony volumio[1328]: info: peppymeterbasic Daemon Stop Jun 14 23:24:04 volumiosony sudo[2139]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 14 Jun 2025 21:24:03 GMT#015' Jun 14 23:24:04 volumiosony sudo[2139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 14 23:24:03 volumiosony setdatetime-helper.sh[2140]: Sat Jun 14 23:24:03 CEST 2025 Jun 14 23:24:03 volumiosony systemd-journald[346]: Time jumped backwards, rotating. Jun 14 23:24:03 volumiosony systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 14 23:24:03 volumiosony sudo[2139]: pam_unix(sudo:session): session closed for user root Jun 14 23:24:03 volumiosony setdatetime-helper.sh[2028]: Time synchronized successfully. Jun 14 23:24:03 volumiosony systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 14 23:24:03 volumiosony volumio[1328]: error: Failed to ping endpoint eu4.myvolumio.org : unknown error Jun 14 23:24:03 volumiosony volumio[1328]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:24:03 volumiosony volumio[1328]: Error: Unable to resolve or reject the same promise twice Jun 14 23:24:03 volumiosony volumio[1328]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 14 23:24:03 volumiosony volumio[1328]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 14 23:24:03 volumiosony volumio[1328]: at Socket.emit (node:events:514:28) Jun 14 23:24:03 volumiosony volumio[1328]: at addChunk (node:internal/streams/readable:343:12) Jun 14 23:24:03 volumiosony volumio[1328]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 14 23:24:03 volumiosony volumio[1328]: at Readable.push (node:internal/streams/readable:253:10) Jun 14 23:24:03 volumiosony volumio[1328]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 14 23:24:03 volumiosony volumio[1328]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 23:24:04 volumiosony sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-14 23:23' Jun 14 23:24:04 volumiosony sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 2 12:57:40 UTC 2025" VOLUMIO_VERSION="4.008" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4256517ba770cec8e56a1795de23dd16"