Apr 24 13:35:48 pi5dev ntpd[1134]: CLOCK: time stepped by 67.632286 Apr 24 13:35:48 pi5dev ntpd[1134]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Apr 24 13:35:48 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:48 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:49 pi5dev sudo[1979]: volumio : unable to resolve host pi5dev: System error Apr 24 13:35:49 pi5dev sudo[1979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 24 13:35:49 pi5dev sudo[1979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:35:49 pi5dev 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. Apr 24 13:35:49 pi5dev 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. Apr 24 13:35:49 pi5dev systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 24 13:35:49 pi5dev sudo[1979]: pam_unix(sudo:session): session closed for user root Apr 24 13:35:49 pi5dev volumio[1349]: info: Remote SSH Started Apr 24 13:35:49 pi5dev autossh[1982]: port set to 0, monitoring disabled Apr 24 13:35:49 pi5dev autossh[1982]: starting ssh (count 1) Apr 24 13:35:49 pi5dev autossh[1982]: ssh child pid is 1985 Apr 24 13:35:49 pi5dev volumio[1349]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 84 Apr 24 13:35:49 pi5dev volumiossh-tunnel[1985]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Apr 24 13:35:49 pi5dev volumio[1349]: info: CoreCommandRouter::volumioGetState Apr 24 13:35:49 pi5dev volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:35:49 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:49 pi5dev volumio[1349]: info: MRS: Getting audio outputs on start Apr 24 13:35:49 pi5dev volumio[1349]: info: MRS: Requesting all other devices output Apr 24 13:35:49 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:50 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:50 pi5dev sshd[1942]: Accepted password for volumio from 192.168.30.10 port 39532 ssh2 Apr 24 13:35:50 pi5dev sshd[1942]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Apr 24 13:35:50 pi5dev systemd-logind[927]: New session 1 of user volumio. Apr 24 13:35:50 pi5dev systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Apr 24 13:35:50 pi5dev systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Apr 24 13:35:50 pi5dev systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Apr 24 13:35:50 pi5dev systemd[1]: Starting user@1000.service - User Manager for UID 1000... Apr 24 13:35:50 pi5dev (systemd)[1987]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Apr 24 13:35:50 pi5dev kernel: systemd[1987]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 24 13:35:50 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:51 pi5dev volumio[1349]: error: Unable to start TidalConnect service: Apr 24 13:35:51 pi5dev volumio[1349]: info: TidalConnect service started! Apr 24 13:35:51 pi5dev volumio[1349]: [Metrics] CommandRouter: 20s 235.70ms Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::volumiosetStartupVolume Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::Close All Modals sent Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::Close All Modals sent Apr 24 13:35:51 pi5dev systemd[1987]: Queued start job for default target default.target. Apr 24 13:35:51 pi5dev systemd[1987]: Created slice app.slice - User Application Slice. Apr 24 13:35:51 pi5dev systemd[1987]: Reached target paths.target - Paths. Apr 24 13:35:51 pi5dev systemd[1987]: Reached target timers.target - Timers. Apr 24 13:35:51 pi5dev systemd[1987]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Apr 24 13:35:51 pi5dev systemd[1987]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Apr 24 13:35:51 pi5dev systemd[1987]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Apr 24 13:35:51 pi5dev systemd[1987]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Apr 24 13:35:51 pi5dev systemd[1987]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Apr 24 13:35:51 pi5dev systemd[1987]: Reached target sockets.target - Sockets. Apr 24 13:35:51 pi5dev systemd[1987]: Reached target basic.target - Basic System. Apr 24 13:35:51 pi5dev systemd[1987]: Reached target default.target - Main User Target. Apr 24 13:35:51 pi5dev systemd[1987]: Startup finished in 208ms. Apr 24 13:35:51 pi5dev systemd[1]: Started user@1000.service - User Manager for UID 1000. Apr 24 13:35:51 pi5dev systemd[1]: Started session-1.scope - Session 1 of User volumio. Apr 24 13:35:51 pi5dev sshd[1942]: pam_env(sshd:session): deprecated reading of user environment enabled Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:51 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:52 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 24 13:35:52 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 24 13:35:52 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 24 13:35:52 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:53 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:53 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:54 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:54 pi5dev systemd[1]: systemd-fsckd.service: Deactivated successfully. Apr 24 13:35:54 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:55 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:55 pi5dev systemd[1]: systemd-hostnamed.service: Deactivated successfully. Apr 24 13:35:55 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:55 pi5dev sudo[2035]: volumio : unable to resolve host pi5dev: System error Apr 24 13:35:55 pi5dev sudo[2035]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Apr 24 13:35:55 pi5dev sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Apr 24 13:35:56 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:56 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 24 13:35:56 pi5dev upmpdcli[2039]: writing RSA key Apr 24 13:35:57 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 24 13:35:57 pi5dev volumio[1349]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 85 Apr 24 13:35:57 pi5dev volumio[1349]: info: CoreCommandRouter::volumioGetState Apr 24 13:35:58 pi5dev volumio[1349]: info: BOOT COMPLETED Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::volumioGetBrowseSources Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 13:36:02 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 24 13:36:02 pi5dev volumio-remote-updater[933]: No test mode Apr 24 13:36:02 pi5dev volumio-remote-updater[933]: Alpha test mode enabled Apr 24 13:36:02 pi5dev volumio-remote-updater[933]: curl_easy_perform() failed: Couldn't connect to server Apr 24 13:36:02 pi5dev volumio-remote-updater[933]: terminate called after throwing an instance of 'std::invalid_argument' Apr 24 13:36:02 pi5dev volumio-remote-updater[933]: what(): parse error - unexpected end of input Apr 24 13:36:02 pi5dev systemd[1]: volumio-remote-updater.service: Main process exited, code=killed, status=6/ABRT Apr 24 13:36:02 pi5dev systemd[1]: volumio-remote-updater.service: Failed with result 'signal'. Apr 24 13:36:02 pi5dev systemd[1]: volumio-remote-updater.service: Scheduled restart job, restart counter is at 1. Apr 24 13:36:02 pi5dev systemd[1]: Stopped volumio-remote-updater.service. Apr 24 13:36:02 pi5dev systemd[1]: Started volumio-remote-updater.service. Apr 24 13:36:02 pi5dev volumio-remote-updater[2061]: Error: No active session Apr 24 13:36:02 pi5dev volumio-remote-updater[2061]: [2025-04-24 13:36:02] [connect] Successful connection Apr 24 13:36:02 pi5dev volumio-remote-updater[2061]: [2025-04-24 13:36:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745498162 101 Apr 24 13:36:02 pi5dev volumio[1349]: 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: 85 Apr 24 13:36:13 pi5dev sudo[2035]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:38 pi5dev volumio[1349]: info: CoreCommandRouter::volumioGetState Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 24 13:36:40 pi5dev volumio[1349]: info: Received Get System Version Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 13:36:40 pi5dev volumio[1349]: info: Executing endpoint metavolumio Apr 24 13:36:40 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 24 13:36:44 pi5dev volumio[1349]: info: CALLMETHOD: system_controller system saveGeneralSettings [object Object] Apr 24 13:36:44 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Apr 24 13:36:44 pi5dev volumio[1349]: info: System name has changed, restarting Shairport Sync Apr 24 13:36:44 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 13:36:44 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:36:44 pi5dev volumio[1349]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 24 13:36:44 pi5dev volumio[1349]: info: Updating MyVolumio device info Apr 24 13:36:44 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:36:44 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:36:44 pi5dev volumio[1349]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 13:36:44 pi5dev vtcs[1957]: [2025-04-24 13:36:44.873] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 24 13:36:44 pi5dev vtcs[1957]: [2025-04-24 13:36:44.873] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 24 13:36:44 pi5dev volumio[1349]: info: Discovery: Restarting Advertising due to device name change Apr 24 13:36:44 pi5dev sudo[2147]: volumio : unable to resolve host pi5dev: System error Apr 24 13:36:44 pi5dev sudo[2149]: volumio : unable to resolve host pi5dev: System error Apr 24 13:36:44 pi5dev sudo[2147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 13:36:44 pi5dev sudo[2147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev sudo[2149]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 24 13:36:44 pi5dev sudo[2149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev volumio[1349]: info: Starting Shairport Sync Apr 24 13:36:44 pi5dev sudo[2151]: volumio : unable to resolve host pi5dev: System error Apr 24 13:36:44 pi5dev sudo[2151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 24 13:36:44 pi5dev sudo[2149]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 24 13:36:44 pi5dev sudo[2155]: volumio : unable to resolve host pi5dev: System error Apr 24 13:36:44 pi5dev systemd[1]: vtcs.service: Deactivated successfully. Apr 24 13:36:44 pi5dev systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 24 13:36:44 pi5dev sudo[2155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 24 13:36:44 pi5dev sudo[2155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev sudo[2155]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev sudo[2147]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev volumio[1349]: info: Permissions for /etc/hosts set Apr 24 13:36:44 pi5dev sudo[2157]: volumio : unable to resolve host pi5dev: System error Apr 24 13:36:44 pi5dev sudo[2157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 24 13:36:44 pi5dev sudo[2157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev1 volumio[1349]: info: Hostname now is pi5dev1 Apr 24 13:36:44 pi5dev1 sudo[2162]: volumio : unable to resolve host pi5dev: System error Apr 24 13:36:44 pi5dev1 sudo[2162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/hostname pi5dev1 Apr 24 13:36:44 pi5dev1 sudo[2162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev1 sudo[2162]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev1 volumio[1349]: info: New hostname set Apr 24 13:36:44 pi5dev1 sudo[2165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/avahi/services/ Apr 24 13:36:44 pi5dev1 sudo[2165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:44 pi5dev1 sudo[2165]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev1 volumio[1349]: info: Permissions for /etc/avahi/services/volumio.service Apr 24 13:36:44 pi5dev1 avahi-daemon[915]: Files changed, reloading. Apr 24 13:36:44 pi5dev1 avahi-daemon[915]: Service group file /services/volumio.service changed, reloading. Apr 24 13:36:44 pi5dev1 volumio[1349]: info: Avahi name changed to pi5dev1 Apr 24 13:36:44 pi5dev1 systemd[1]: Starting fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels... Apr 24 13:36:44 pi5dev1 sudo[2151]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev1 systemd[1]: fix-alpha-hosts.service: Deactivated successfully. Apr 24 13:36:44 pi5dev1 systemd[1]: Finished fix-alpha-hosts.service - Fix /etc/hosts and ensure test sentinels. Apr 24 13:36:44 pi5dev1 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 24 13:36:44 pi5dev1 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 24 13:36:44 pi5dev1 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 24 13:36:44 pi5dev1 systemd[1]: shairport-sync.service: Consumed 1.520s CPU time. Apr 24 13:36:44 pi5dev1 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 24 13:36:44 pi5dev1 sudo[2157]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:44 pi5dev1 volumio[1349]: info: Shairport-Sync Started Apr 24 13:36:45 pi5dev1 volumio[1349]: info: Successfully Updated MyVolumio device Apr 24 13:36:45 pi5dev1 avahi-daemon[915]: Service "pi5dev1" (/services/volumio.service) successfully established. Apr 24 13:36:45 pi5dev1 volumio[1349]: info: Discovery: A device disappeared from network Apr 24 13:36:45 pi5dev1 volumio[1349]: info: Discovery: Device pi5dev disappeared from network Apr 24 13:36:45 pi5dev1 volumio[1349]: info: Discovery: A device disappeared from network Apr 24 13:36:45 pi5dev1 volumio[1349]: info: Discovery: A device disappeared from network Apr 24 13:36:47 pi5dev1 volumio[1349]: error: Unable to stop TidalConnect service: Apr 24 13:36:47 pi5dev1 volumio[1349]: info: TidalConnect service stoped! Apr 24 13:36:47 pi5dev1 volumio[1349]: error: Unable to stop TidalConnect service: Apr 24 13:36:47 pi5dev1 volumio[1349]: info: TidalConnect service stoped! Apr 24 13:36:47 pi5dev1 volumio[1349]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 24 13:36:47 pi5dev1 volumio[1349]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 24 13:36:47 pi5dev1 sudo[2197]: volumio : unable to resolve host pi5dev1: System error Apr 24 13:36:47 pi5dev1 sudo[2197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 24 13:36:47 pi5dev1 sudo[2197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 24 13:36:48 pi5dev1 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 24 13:36:48 pi5dev1 sudo[2197]: pam_unix(sudo:session): session closed for user root Apr 24 13:36:48 pi5dev1 volumio[1349]: info: Executing endpoint tc_getconfig Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 24 13:36:48 pi5dev1 vtcs[2200]: STARTING TidalConnect services, version: 1.5.0.45 Apr 24 13:36:48 pi5dev1 vtcs[2200]: STARTED TidalConnect services. Apr 24 13:36:48 pi5dev1 volumio[1349]: info: Executing endpoint tc_connect Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 24 13:36:48 pi5dev1 volumio[1349]: info: Connecting to TidalConnect Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::servicePushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::pushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output update for this device Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 24 13:36:48 pi5dev1 volumio[1349]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 24 13:36:48 pi5dev1 volumio[1349]: verbose: CURRENT POSITION 0 Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::syncState stateService stop Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::syncState currentStatus stop Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::pushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output update for this device Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output Apr 24 13:36:48 pi5dev1 volumio[1349]: info: No code Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::pushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output update for this device Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::servicePushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::pushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output update for this device Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CorePlayQueue::getTrack 0 Apr 24 13:36:48 pi5dev1 volumio[1349]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 24 13:36:48 pi5dev1 volumio[1349]: verbose: CURRENT POSITION 0 Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::syncState stateService stop Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::syncState currentStatus stop Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::pushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output update for this device Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output Apr 24 13:36:48 pi5dev1 volumio[1349]: info: No code Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreStateMachine::pushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: CoreCommandRouter::volumioPushState Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output update for this device Apr 24 13:36:48 pi5dev1 volumio[1349]: info: MRS: Pushing multiroomSync output Apr 24 13:36:48 pi5dev1 volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:36:48 pi5dev1 volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:36:48 pi5dev1 volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:36:48 pi5dev1 volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:36:48 pi5dev1 volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:36:48 pi5dev1 volumio[1349]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 24 13:36:49 pi5dev1 volumio[1349]: info: Discovery: Started advertising with name: pi5dev1 Apr 24 13:36:50 pi5dev1 volumio[1349]: compat.c: read() failed: Resource temporarily unavailable Apr 24 13:36:50 pi5dev1 volumio[1349]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 13:36:50 pi5dev1 volumio[1349]: Error: dns service error: unknown Apr 24 13:36:50 pi5dev1 volumio[1349]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 24 13:36:50 pi5dev1 volumio[1349]: errorCode: -65537 Apr 24 13:36:50 pi5dev1 volumio[1349]: } Apr 24 13:36:50 pi5dev1 volumio[1349]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 13:36:50 pi5dev1 sudo[2232]: volumio : unable to resolve host pi5dev1: System error Apr 24 13:36:50 pi5dev1 sudo[2232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-24 13:35' Apr 24 13:36:50 pi5dev1 sudo[2232]: 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="d3349c8a5eb4a9e06f72733a6cfc52b5f79259e9" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Apr 24 11:43:48 UTC 2025" VOLUMIO_VERSION="0.059" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="063dde2b49cfefbd42cec51bb1fc8b3d"