-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Sun 2024-08-25 17:07:42 UTC. -- Aug 25 17:06:00 volumio-garage wireless.js[630]: trying... Aug 25 17:06:00 volumio-garage sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 17:06:00 volumio-garage sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:00 volumio-garage sudo[1369]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:00 volumio-garage wireless.js[630]: Connected to: ----vinz-iwifi Aug 25 17:06:00 volumio-garage wireless.js[630]: ---- Aug 25 17:06:00 volumio-garage sudo[1372]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 17:06:00 volumio-garage sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:00 volumio-garage sudo[1372]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:00 volumio-garage wireless.js[630]: ... joined AP, wlan0 IPv4 is undefined, ipV6 is undefined Aug 25 17:06:00 volumio-garage systemd[1]: systemd-fsckd.service: Succeeded. Aug 25 17:06:01 volumio-garage wireless.js[630]: Overtime, starting plan B Aug 25 17:06:01 volumio-garage systemd[1]: Started Wireless Services. Aug 25 17:06:01 volumio-garage systemd[1]: Started Volumio Backend Module. Aug 25 17:06:01 volumio-garage systemd[1]: Started Volumio Cpu Tweaker. Aug 25 17:06:01 volumio-garage systemd[1]: Reached target Multi-User System. Aug 25 17:06:01 volumio-garage systemd[1]: Reached target Graphical Interface. Aug 25 17:06:01 volumio-garage systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: Setting RT Priority for mpd Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: pid 35's current scheduling policy: SCHED_OTHER Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: pid 35's current scheduling priority: 0 Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: Setting MPD Affinity Aug 25 17:06:01 volumio-garage systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 25 17:06:01 volumio-garage systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: pid 3's current affinity mask: f Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 25 17:06:01 volumio-garage volumio-cpu-tweak[1378]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 25 17:06:01 volumio-garage systemd[1]: Startup finished in 18.594s (kernel) + 36.005s (userspace) = 54.600s. Aug 25 17:06:01 volumio-garage systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 25 17:06:01 volumio-garage dhcpcd[1220]: wlan0: using IPv4LL address 169.254.72.83 Aug 25 17:06:01 volumio-garage dhcpcd[1220]: wlan0: adding route to 169.254.0.0/16 Aug 25 17:06:01 volumio-garage avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.72.83. Aug 25 17:06:01 volumio-garage avahi-daemon[631]: New relevant interface wlan0.IPv4 for mDNS. Aug 25 17:06:01 volumio-garage avahi-daemon[631]: Registering new address record for 169.254.72.83 on wlan0.IPv4. Aug 25 17:06:02 volumio-garage dhcpcd[1220]: wlan0: leased 10.20.1.224 for 86400 seconds Aug 25 17:06:02 volumio-garage avahi-daemon[631]: Registering new address record for 10.20.1.224 on wlan0.IPv4. Aug 25 17:06:02 volumio-garage dhcpcd[1220]: wlan0: adding route to 10.20.1.0/24 Aug 25 17:06:02 volumio-garage dhcpcd[1220]: wlan0: adding default route via 10.20.1.1 Aug 25 17:06:02 volumio-garage avahi-daemon[631]: Withdrawing address record for 169.254.72.83 on wlan0. Aug 25 17:06:02 volumio-garage avahi-daemon[631]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.72.83. Aug 25 17:06:02 volumio-garage avahi-daemon[631]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.20.1.224. Aug 25 17:06:02 volumio-garage dhcpcd[1220]: wlan0: deleting route to 169.254.0.0/16 Aug 25 17:06:03 volumio-garage sh[542]: timed out Aug 25 17:06:03 volumio-garage dhcpcd[573]: timed out Aug 25 17:06:03 volumio-garage sh[542]: dhcpcd exited Aug 25 17:06:03 volumio-garage dhcpcd[573]: dhcpcd exited Aug 25 17:06:03 volumio-garage sh[542]: ifup: failed to bring up eth0 Aug 25 17:06:03 volumio-garage systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 25 17:06:03 volumio-garage systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 25 17:06:03 volumio-garage ntpd[852]: Listen normally on 4 wlan0 10.20.1.224:123 Aug 25 17:06:03 volumio-garage ntpd[852]: new interface(s) found: waking up resolver Aug 25 17:06:04 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:04 volumio-garage volumio[1377]: info: ----- Volumio3 ---- Aug 25 17:06:04 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:04 volumio-garage volumio[1377]: info: ----- System startup ---- Aug 25 17:06:04 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:05 volumio-garage volumio[1377]: info: MYVOLUMIO Environment detected Aug 25 17:06:05 volumio-garage volumio[1377]: info: Plugin folders cleanup Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning into folder /volumio/app/plugins/ Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category audio_interface Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category miscellanea Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category music_service Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category plugins.json Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category system_controller Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category user_interface Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning into folder /data/plugins/ Aug 25 17:06:05 volumio-garage volumio[1377]: info: Scanning category music_service Aug 25 17:06:05 volumio-garage volumio[1377]: info: Plugin folders cleanup completed Aug 25 17:06:05 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:05 volumio-garage volumio[1377]: info: ----- Core plugins startup ---- Aug 25 17:06:05 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:05 volumio-garage volumio[1377]: info: Loading plugins from folder /volumio/app/plugins/ Aug 25 17:06:05 volumio-garage volumio[1377]: info: Adding plugin upnp to MyMusic Plugins Aug 25 17:06:05 volumio-garage volumio[1377]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 25 17:06:05 volumio-garage volumio[1377]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 25 17:06:05 volumio-garage volumio[1377]: info: Loading plugins from folder /data/plugins/ Aug 25 17:06:05 volumio-garage volumio[1377]: info: Loading plugin "system"... Aug 25 17:06:05 volumio-garage volumio[1377]: info: Loading plugin "appearance"... Aug 25 17:06:06 volumio-garage systemd[1]: systemd-hostnamed.service: Succeeded. Aug 25 17:06:07 volumio-garage volumio-remote-updater[629]: [2024-08-25 17:06:07] [connect] Successful connection Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "network"... Aug 25 17:06:07 volumio-garage volumio[1377]: info: Refreshing Cached IP Addresses Aug 25 17:06:07 volumio-garage sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 17:06:07 volumio-garage sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "services"... Aug 25 17:06:07 volumio-garage sudo[1471]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:07 volumio-garage sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 17:06:07 volumio-garage sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:07 volumio-garage sudo[1473]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "alsa_controller"... Aug 25 17:06:07 volumio-garage sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 25 17:06:07 volumio-garage sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:07 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "wizard"... Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "networkfs"... Aug 25 17:06:07 volumio-garage volumio[1377]: info: Starting Udev Watcher for removable devices Aug 25 17:06:07 volumio-garage sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=vinz,password=Aubrey@121,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //vinz-win10/DSD /mnt/NAS/DSD Aug 25 17:06:07 volumio-garage sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:07 volumio-garage volumio[1377]: info: Ignoring mount for partition: boot Aug 25 17:06:07 volumio-garage volumio[1377]: info: Ignoring mount for partition: volumio Aug 25 17:06:07 volumio-garage volumio[1377]: info: Ignoring mount for partition: volumio_data Aug 25 17:06:07 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "volumio_command_line_client"... Aug 25 17:06:07 volumio-garage volumio[1377]: info: Loading plugin "upnp"... Aug 25 17:06:07 volumio-garage volumio[1377]: info: [1724605567999] Starting Upmpd Daemon Aug 25 17:06:08 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 17:06:08 volumio-garage volumio[1377]: info: Loading plugin "my_music"... Aug 25 17:06:08 volumio-garage volumio[1377]: info: Loading plugin "mpd"... Aug 25 17:06:08 volumio-garage volumio[1377]: info: Loading plugin "upnp_browser"... Aug 25 17:06:08 volumio-garage volumio[1377]: info: Loading plugin "alarm-clock"... Aug 25 17:06:09 volumio-garage volumio[1377]: info: Loading plugin "airplay_emulation"... Aug 25 17:06:09 volumio-garage volumio[1377]: info: Starting Shairport Sync Aug 25 17:06:09 volumio-garage volumio[1377]: info: Loading plugin "last_100"... Aug 25 17:06:09 volumio-garage volumio[1377]: info: Loading plugin "webradio"... Aug 25 17:06:09 volumio-garage volumio[1377]: info: Loading plugin "i2s_dacs"... Aug 25 17:06:09 volumio-garage volumio[1377]: info: Loading plugin "volumiodiscovery"... Aug 25 17:06:09 volumio-garage volumio[1377]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 17:06:09 volumio-garage volumio[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 17:06:09 volumio-garage volumio[1377]: *** WARNING *** For more information see Aug 25 17:06:09 volumio-garage volumio[1377]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 17:06:09 volumio-garage node[1377]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 17:06:09 volumio-garage volumio[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 17:06:09 volumio-garage volumio[1377]: *** WARNING *** For more information see Aug 25 17:06:09 volumio-garage node[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 17:06:09 volumio-garage node[1377]: *** WARNING *** For more information see Aug 25 17:06:09 volumio-garage node[1377]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 17:06:09 volumio-garage node[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 17:06:09 volumio-garage node[1377]: *** WARNING *** For more information see Aug 25 17:06:09 volumio-garage volumio[1377]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 25 17:06:09 volumio-garage volumio[1377]: info: Discovery: Started advertising with name: volumio-garage Aug 25 17:06:09 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 17:06:09 volumio-garage volumio[1377]: info: Loading plugin "spop"... Aug 25 17:06:11 volumio-garage volumio[1377]: info: Loading plugin "outputs"... Aug 25 17:06:11 volumio-garage volumio[1377]: info: Loading plugin "albumart"... Aug 25 17:06:11 volumio-garage volumio[1377]: info: Plugin example_plugin is not enabled Aug 25 17:06:11 volumio-garage volumio[1377]: info: Loading plugin "inputs"... Aug 25 17:06:11 volumio-garage volumio[1377]: info: Loading plugin "updater_comm"... Aug 25 17:06:12 volumio-garage volumio[1377]: info: Plugin mpdemulation is not enabled Aug 25 17:06:12 volumio-garage volumio[1377]: info: Loading plugin "rest_api"... Aug 25 17:06:12 volumio-garage volumio[1377]: info: Loading plugin "websocket"... Aug 25 17:06:12 volumio-garage volumio[1377]: info: Starting Socket.io Server version 2.3.0 Aug 25 17:06:12 volumio-garage volumio[1377]: info: Loading i18n strings for locale en Aug 25 17:06:12 volumio-garage volumio[1377]: Updating browse sources language Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 17:06:12 volumio-garage volumio[1377]: Forking 3 albumart workers Aug 25 17:06:12 volumio-garage wpa_supplicant[1183]: nl80211: send_and_recv->nl_recvmsgs failed: -33 Aug 25 17:06:12 volumio-garage sudo[1476]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::initPlayerControls Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 17:06:12 volumio-garage volumio[1377]: Express server listening on port 3000 Aug 25 17:06:12 volumio-garage volumio[1377]: [Metrics] WebUI: 9s 303.50ms Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreStateMachine::resetVolumioState Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreStateMachine::getcurrentVolume Aug 25 17:06:12 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:13 volumio-garage volumio[1377]: info: Setting Device type: Raspberry PI Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::setRepeat null single undefined Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::pushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioPushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::setRandom null Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::pushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioPushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: VolumeController:: Volume=100 Mute =false Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::pushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioPushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::updateTrackBlock Aug 25 17:06:13 volumio-garage volumio[1377]: info: CorePlayQueue::getTrackBlock Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 17:06:13 volumio-garage volumio[1377]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 25 17:06:13 volumio-garage volumio[1377]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Aug 25 17:06:13 volumio-garage volumio[1377]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 25 17:06:13 volumio-garage volumio[1377]: info: Reloading queue from file Aug 25 17:06:13 volumio-garage volumio[1377]: info: VolumeController:: Volume=100 Mute =false Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreStateMachine::pushState Aug 25 17:06:13 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 17:06:13 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioPushState Aug 25 17:06:13 volumio-garage volumio[1377]: Starting albumart workers Aug 25 17:06:13 volumio-garage volumio[1377]: Starting albumart workers Aug 25 17:06:13 volumio-garage volumio[1377]: Starting albumart workers Aug 25 17:06:18 volumio-garage sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 17:06:18 volumio-garage sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:18 volumio-garage sudo[1560]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:18 volumio-garage sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 17:06:18 volumio-garage sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:18 volumio-garage sudo[1562]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:18 volumio-garage sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 25 17:06:18 volumio-garage sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:18 volumio-garage systemd[1]: Started UPnP Renderer front-end to MPD. Aug 25 17:06:18 volumio-garage sudo[1566]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:18 volumio-garage volumio[1377]: info: Upmpdcli Daemon Started Aug 25 17:06:18 volumio-garage volumio[1377]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 17:06:21 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:06:22 volumio-garage volumio-remote-updater[629]: [2024-08-25 17:06:22] [connect] Successful connection Aug 25 17:06:22 volumio-garage volumio-remote-updater[629]: [2024-08-25 17:06:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724605582 101 Aug 25 17:06:22 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Aug 25 17:06:22 volumio-garage volumio[1377]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 17:06:22 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 25 17:06:22 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.174 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 25 17:06:27 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.174 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 25 17:06:27 volumio-garage volumio[1377]: info: Discovery: adding ac11d2ef-7e16-47ce-84af-c236502bcc91 Aug 25 17:06:27 volumio-garage volumio[1377]: info: Discovery: Found device volumio-garage Aug 25 17:06:27 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:27 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:27 volumio-garage volumio[1377]: info: Discovery: this is already registered, ac11d2ef-7e16-47ce-84af-c236502bcc91 Aug 25 17:06:27 volumio-garage volumio[1377]: info: Discovery: Found device volumio-garage Aug 25 17:06:27 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:27 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:27 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 25 17:06:27 volumio-garage kernel: Key type cifs.spnego registered Aug 25 17:06:27 volumio-garage kernel: Key type cifs.idmap registered Aug 25 17:06:27 volumio-garage kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Aug 25 17:06:27 volumio-garage kernel: CIFS: Attempting to mount \\vinz-win10\DSD Aug 25 17:06:28 volumio-garage volumio[1377]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 17:06:28 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.113 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 25 17:06:29 volumio-garage volumio[1377]: info: Discovery: adding d135c327-88b0-430e-86f5-0273789ec211 Aug 25 17:06:29 volumio-garage volumio[1377]: info: Discovery: Found device volumio-patio Aug 25 17:06:29 volumio-garage volumio[1377]: info: Discovery: Connecting to remote: 10.20.1.113 Aug 25 17:06:29 volumio-garage volumio[1377]: info: Discovery: this is already registered, d135c327-88b0-430e-86f5-0273789ec211 Aug 25 17:06:29 volumio-garage volumio[1377]: info: Discovery: Found device volumio-patio Aug 25 17:06:29 volumio-garage volumio[1377]: info: Discovery: Connecting to remote: 10.20.1.113 Aug 25 17:06:29 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.174 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 25 17:06:29 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 25 17:06:29 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:29 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:30 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 25 17:06:30 volumio-garage volumio[1377]: info: Discovery: adding 90bc88a4-0d0e-45e4-b176-a46101c8f990 Aug 25 17:06:30 volumio-garage volumio[1377]: info: Discovery: Found device volumio-basement Aug 25 17:06:30 volumio-garage volumio[1377]: info: Discovery: Connecting to remote: 10.20.1.174 Aug 25 17:06:30 volumio-garage volumio[1377]: info: Discovery: this is already registered, 90bc88a4-0d0e-45e4-b176-a46101c8f990 Aug 25 17:06:30 volumio-garage volumio[1377]: info: Discovery: Found device volumio-basement Aug 25 17:06:30 volumio-garage volumio[1377]: info: Discovery: Connecting to remote: 10.20.1.174 Aug 25 17:06:31 volumio-garage volumio[1377]: info: Discovery: Connected to remote: 10.20.1.113 Aug 25 17:06:33 volumio-garage volumio[1377]: info: Discovery: Connected to remote: 10.20.1.174 Aug 25 17:06:35 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:35 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:38 volumio-garage volumio[1377]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Aug 25 17:06:38 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:06:42 volumio-garage volumio[1377]: error: Plugin system_controller networkfs failed to complete 'onVolumioStart' in a timely fashion Aug 25 17:06:42 volumio-garage volumio[1377]: info: Preparing to generate the ALSA configuration file Aug 25 17:06:42 volumio-garage volumio[1377]: info: Asound.conf file written Aug 25 17:06:42 volumio-garage sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 25 17:06:42 volumio-garage sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage sudo[1626]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:42 volumio-garage volumio[1377]: info: Output device has changed, restarting MPD Aug 25 17:06:42 volumio-garage volumio[1377]: info: Output device has changed, restarting Shairport Sync Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:42 volumio-garage sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 17:06:42 volumio-garage sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage sudo[1631]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:42 volumio-garage sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 17:06:42 volumio-garage sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage volumio[1377]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 17:06:42 volumio-garage volumio[1377]: info: ___________ START PLUGINS ___________ Aug 25 17:06:42 volumio-garage volumio[1377]: info: ControllerMpd::onStart: Initializing MPD Aug 25 17:06:42 volumio-garage volumio[1377]: info: Creating MPD Configuration file Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 17:06:42 volumio-garage sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 17:06:42 volumio-garage sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage volumio[1377]: info: [1724605602422] CoreMusicLibrary::Adding element Media Servers Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 17:06:42 volumio-garage sudo[1641]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:42 volumio-garage systemd[1]: Listening on mpd.socket. Aug 25 17:06:42 volumio-garage sudo[1643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 17:06:42 volumio-garage systemd[1]: Starting Music Player Daemon... Aug 25 17:06:42 volumio-garage sudo[1643]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:42 volumio-garage systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 25 17:06:42 volumio-garage systemd[1]: mpd.service: Succeeded. Aug 25 17:06:42 volumio-garage systemd[1]: Stopped Music Player Daemon. Aug 25 17:06:42 volumio-garage systemd[1]: Starting Music Player Daemon... Aug 25 17:06:42 volumio-garage volumio[1377]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 17:06:42 volumio-garage volumio[1377]: info: [1724605602585] CoreMusicLibrary::Adding element Last_100 Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 17:06:42 volumio-garage volumio[1377]: info: [1724605602589] CoreMusicLibrary::Adding element Webradio Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:06:42 volumio-garage volumio[1377]: info: Initializing BBC Radios Aug 25 17:06:42 volumio-garage sudo[1648]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 17:06:42 volumio-garage sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage sudo[1648]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 25 17:06:42 volumio-garage sudo[1648]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: Creating Spotify config file Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: Cannot retrieve data for calling home Aug 25 17:06:42 volumio-garage volumio[1377]: info: MPD Permissions set Aug 25 17:06:42 volumio-garage volumio[1377]: info: MPD Permissions set Aug 25 17:06:42 volumio-garage volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 25 17:06:42 volumio-garage volumio[1377]: info: Spotify config file written Aug 25 17:06:42 volumio-garage sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 25 17:06:42 volumio-garage sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:42 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:06:43 volumio-garage volumio[1377]: info: Starting Shairport Sync Aug 25 17:06:43 volumio-garage volumio[1377]: info: Starting Shairport Sync Aug 25 17:06:43 volumio-garage systemd[1]: Started go-librespot Daemon. Aug 25 17:06:43 volumio-garage volumio[1377]: info: Starting Shairport Sync Aug 25 17:06:43 volumio-garage go-librespot[1673]: Librespot-go daemon starting... Aug 25 17:06:43 volumio-garage sudo[1667]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:43 volumio-garage sudo[1679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 17:06:43 volumio-garage sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:43 volumio-garage sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 17:06:43 volumio-garage sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:43 volumio-garage sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 17:06:43 volumio-garage sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:06:43 volumio-garage systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 17:06:43 volumio-garage systemd[1]: shairport-sync.service: Succeeded. Aug 25 17:06:43 volumio-garage systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 17:06:43 volumio-garage systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 17:06:43 volumio-garage ntpd[852]: Soliciting pool server 23.155.40.38 Aug 25 17:06:43 volumio-garage sudo[1679]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:43 volumio-garage sudo[1683]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:43 volumio-garage sudo[1685]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:43 volumio-garage volumio[1377]: info: Shairport-Sync Started Aug 25 17:06:43 volumio-garage volumio[1377]: Error adding Membership: Error: addMembership EINVAL Aug 25 17:06:43 volumio-garage volumio[1377]: info: Shairport-Sync Started Aug 25 17:06:43 volumio-garage volumio[1377]: info: Shairport-Sync Started Aug 25 17:06:44 volumio-garage go-librespot[1673]: time="2024-08-25T17:06:44Z" level=info msg="generated new device id: 2f39e4ada510481431639a54e950ac7ab93fc3c6" Aug 25 17:06:44 volumio-garage ntpd[852]: Soliciting pool server 5.78.62.36 Aug 25 17:06:44 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:44 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:44 volumio-garage ntpd[852]: Soliciting pool server 64.6.144.6 Aug 25 17:06:46 volumio-garage volumio[1377]: info: go-librespot daemon successfully initialized Aug 25 17:06:46 volumio-garage mpd[1658]: Aug 25 17:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 25 17:06:46 volumio-garage systemd[1]: Started Music Player Daemon. Aug 25 17:06:46 volumio-garage sudo[1643]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:46 volumio-garage sudo[1633]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:46 volumio-garage volumio[1377]: info: Completed starting Core Plugins Aug 25 17:06:46 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:46 volumio-garage volumio[1377]: info: ----- MyVolumio plugins startup ---- Aug 25 17:06:46 volumio-garage volumio[1377]: info: ------------------------------------------- Aug 25 17:06:46 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 25 17:06:46 volumio-garage volumio[1377]: info: MPD running with PID1658 Aug 25 17:06:46 volumio-garage volumio[1377]: ,establishing connection Aug 25 17:06:46 volumio-garage volumio[1377]: error: MPD error: The expression evaluated to a falsy value: Aug 25 17:06:46 volumio-garage volumio[1377]: assert.ok(self.idling) Aug 25 17:06:46 volumio-garage volumio[1377]: error: The expression evaluated to a falsy value: Aug 25 17:06:46 volumio-garage volumio[1377]: assert.ok(self.idling) Aug 25 17:06:46 volumio-garage volumio[1377]: error: MPD error: The expression evaluated to a falsy value: Aug 25 17:06:46 volumio-garage volumio[1377]: assert.ok(self.idling) Aug 25 17:06:46 volumio-garage volumio[1377]: error: The expression evaluated to a falsy value: Aug 25 17:06:46 volumio-garage volumio[1377]: assert.ok(self.idling) Aug 25 17:06:46 volumio-garage volumio[1377]: error: updateQueue error: null Aug 25 17:06:47 volumio-garage volumio[1377]: error: error Aug 25 17:06:47 volumio-garage volumio[1377]: error: error Aug 25 17:06:47 volumio-garage volumio[1377]: error: error Aug 25 17:06:49 volumio-garage volumio[1377]: info: Initializing connection to go-librespot Websocket Aug 25 17:06:49 volumio-garage go-librespot[1673]: time="2024-08-25T17:06:49Z" level=debug msg="new websocket client" Aug 25 17:06:49 volumio-garage volumio[1377]: info: Connection to go-librespot Websocket established Aug 25 17:06:50 volumio-garage ntpd[852]: Soliciting pool server 45.55.58.103 Aug 25 17:06:50 volumio-garage ntpd[852]: Soliciting pool server 23.186.168.1 Aug 25 17:06:50 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:50 volumio-garage ntpd[852]: Soliciting pool server 44.190.5.123 Aug 25 17:06:50 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:50 volumio-garage sudo[1484]: pam_unix(sudo:session): session closed for user root Aug 25 17:06:51 volumio-garage volumio[1377]: info: Listing playlists Aug 25 17:06:51 volumio-garage volumio[1377]: info: Listing playlists Aug 25 17:06:51 volumio-garage go-librespot[1673]: time="2024-08-25T17:06:51Z" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew4.spotify.com:80]" Aug 25 17:06:51 volumio-garage go-librespot[1673]: time="2024-08-25T17:06:51Z" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Aug 25 17:06:51 volumio-garage go-librespot[1673]: time="2024-08-25T17:06:51Z" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Aug 25 17:06:51 volumio-garage go-librespot[1673]: time="2024-08-25T17:06:51Z" level=debug msg="zeroconf server listening on port 40851" Aug 25 17:06:51 volumio-garage volumio[1377]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 25 17:06:51 volumio-garage ntpd[852]: Soliciting pool server 23.168.24.210 Aug 25 17:06:51 volumio-garage ntpd[852]: Soliciting pool server 72.30.35.89 Aug 25 17:06:51 volumio-garage ntpd[852]: Soliciting pool server 69.164.213.136 Aug 25 17:06:52 volumio-garage volumio[1377]: info: Getting Spotify volume Aug 25 17:06:52 volumio-garage volumio[1377]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 25 17:06:52 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:52 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:52 volumio-garage volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 25 17:06:52 volumio-garage volumio[1377]: SPOTIFY: SPOTIFY VOLUME undefined Aug 25 17:06:52 volumio-garage volumio[1377]: SPOTIFY: VOLUMIO VOLUME 100 Aug 25 17:06:52 volumio-garage volumio[1377]: info: Aligning Spotify Volume to Volumio Volume Aug 25 17:06:52 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:06:52 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:06:52 volumio-garage volumio[1377]: info: Setting Spotify Volume from Volumio: 100 Aug 25 17:06:52 volumio-garage ntpd[852]: Soliciting pool server 65.100.46.166 Aug 25 17:06:52 volumio-garage ntpd[852]: Soliciting pool server 64.142.54.12 Aug 25 17:06:52 volumio-garage ntpd[852]: Soliciting pool server 72.46.61.205 Aug 25 17:06:52 volumio-garage volumio[1377]: error: error Aug 25 17:06:52 volumio-garage volumio[1377]: error: error Aug 25 17:06:53 volumio-garage ntpd[852]: Soliciting pool server 66.205.249.28 Aug 25 17:06:53 volumio-garage ntpd[852]: Soliciting pool server 71.162.136.44 Aug 25 17:06:53 volumio-garage volumio[1377]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Aug 25 17:06:53 volumio-garage volumio[1377]: info: Sending Spotify command with payload to local API: /player/volume Aug 25 17:06:54 volumio-garage ntpd[852]: Soliciting pool server 2603:c020:0:8369::feeb:dab Aug 25 17:06:56 volumio-garage volumio[1568]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 25 17:06:57 volumio-garage volumio[1568]: .......++++ Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e330.9c151d25 does not match aorg 0xea75e332.9c0d1e7d from server@69.164.213.136 xmt 0xea75e34c.eec82d0c Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e330.9c16a294 does not match aorg 0xea75e332.9c1629f2 from server@23.186.168.1 xmt 0xea75e34d.2bc16244 Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e330.9c185666 does not match aorg 0xea75e332.9c18a9fa from server@5.78.62.36 xmt 0xea75e34d.31062486 Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e331.9c0cd86d does not match aorg 0xea75e333.9c0c566d from server@71.162.136.44 xmt 0xea75e34d.b3fdb4d0 Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e331.9c1ce305 does not match aorg 0xea75e333.9c19f930 from server@72.46.61.205 xmt 0xea75e34d.de6c18ae Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e331.9c207b24 does not match aorg 0xea75e333.9c1b46fb from server@72.30.35.89 xmt 0xea75e34d.e1123420 Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e331.9c18083a does not match aorg 0xea75e333.9c17bf8f from server@65.100.46.166 xmt 0xea75e34d.e1d91ed0 Aug 25 17:06:59 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e331.9c2b24c9 does not match aorg 0xea75e333.9c1e60ea from server@45.55.58.103 xmt 0xea75e34d.f2bc41f3 Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e332.9c0d1e7d does not match aorg 0xea75e336.9c0cc536 from server@69.164.213.136 xmt 0xea75e34e.b08cf85f Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e332.9c1629f2 does not match aorg 0xea75e336.9c1766ef from server@23.186.168.1 xmt 0xea75e34e.b11a8bf2 Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e332.9c18a9fa does not match aorg 0xea75e336.9c1c5d82 from server@5.78.62.36 xmt 0xea75e34e.b535af2d Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e333.9c0c566d does not match aorg 0xea75e335.9c0ceb34 from server@71.162.136.44 xmt 0xea75e34f.af14e6cb Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e333.9c19f930 does not match aorg 0xea75e335.9c1a7c76 from server@72.46.61.205 xmt 0xea75e34f.af28b69e Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e333.9c1e60ea does not match aorg 0xea75e335.9c1fadf0 from server@45.55.58.103 xmt 0xea75e34f.b3ede76d Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e333.9c1b46fb does not match aorg 0xea75e335.9c1bd382 from server@72.30.35.89 xmt 0xea75e34f.b5d133a8 Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e333.9c17bf8f does not match aorg 0xea75e335.9c192006 from server@65.100.46.166 xmt 0xea75e34f.b3c3d02a Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e333.9c1d28ca does not match aorg 0xea75e335.9c1e60cf from server@44.190.5.123 xmt 0xea75e34f.b69fba50 Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e334.9c0d30bc does not match aorg 0xea75e336.9c0cc536 from server@69.164.213.136 xmt 0xea75e350.ac5da9d1 Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e334.9c15c595 does not match aorg 0xea75e336.9c1766ef from server@23.186.168.1 xmt 0xea75e350.acd8a058 Aug 25 17:07:03 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e334.9c181d50 does not match aorg 0xea75e336.9c1c5d82 from server@5.78.62.36 xmt 0xea75e350.b0eb8f23 Aug 25 17:07:03 volumio-garage volumio[1377]: info: Discovery: Disconnected from remote: 10.20.1.174 Aug 25 17:07:06 volumio-garage volumio[1377]: error: MyVolumio Plugin failed to start in a timely fashion Aug 25 17:07:06 volumio-garage volumio[1377]: info: BOOT COMPLETED Aug 25 17:07:06 volumio-garage volumio[1377]: [Metrics] CommandRouter: 62s 279.34ms Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreCommandRouter::volumiosetStartupVolume Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 17:07:06 volumio-garage volumio[1377]: info: VolumeController:: Setting startup Volume 100 Aug 25 17:07:06 volumio-garage volumio[1377]: info: VolumeController::SetAlsaVolume100 Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreCommandRouter::Close All Modals sent Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreCommandRouter::Close All Modals sent Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreStateMachine::pushState Aug 25 17:07:06 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:07:06 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioPushState Aug 25 17:07:06 volumio-garage volumio[1377]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Aug 25 17:07:07 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 25 17:07:07 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 17:07:07 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c0ceb34 does not match aorg 0xea75e339.9c0cd0d6 from server@71.162.136.44 xmt 0xea75e352.19080687 Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c172c31 does not match aorg 0xea75e33b.9c0cb79d from server@64.142.54.12 xmt 0xea75e352.22e4b623 Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c192006 does not match aorg 0xea75e337.9c18d2a4 from server@65.100.46.166 xmt 0xea75e352.1f0fbd9c Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c1d0f5a does not match aorg 0xea75e33b.9c16c32b from server@23.168.24.210 xmt 0xea75e352.7f8f33d8 Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c1a7c76 does not match aorg 0xea75e337.9c1a4b79 from server@72.46.61.205 xmt 0xea75e352.804de4a4 Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c1fadf0 does not match aorg 0xea75e337.9c1ebb21 from server@45.55.58.103 xmt 0xea75e352.82cbac74 Aug 25 17:07:08 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e335.9c1bd382 does not match aorg 0xea75e339.9c1b0c23 from server@72.30.35.89 xmt 0xea75e352.82f651f4 Aug 25 17:07:09 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e337.9c0ccee8 does not match aorg 0xea75e339.9c0cd0d6 from server@71.162.136.44 xmt 0xea75e353.cdac52ae Aug 25 17:07:09 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e337.9c1d5ed3 does not match aorg 0xea75e33d.9c180056 from server@23.168.24.210 xmt 0xea75e353.ce3bb628 Aug 25 17:07:09 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e337.9c1b9c07 does not match aorg 0xea75e339.9c1b0c23 from server@72.30.35.89 xmt 0xea75e353.d0702f36 Aug 25 17:07:09 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e337.9c1666fb does not match aorg 0xea75e33d.9c0cf0c4 from server@64.142.54.12 xmt 0xea75e353.d6370080 Aug 25 17:07:09 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:07:09 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:07:10 volumio-garage volumio[1377]: info: Discovery: Remote connection error: 10.20.1.174 Aug 25 17:07:10 volumio-garage volumio[1377]: info: Discovery: Remote connection timeout: 10.20.1.174 Aug 25 17:07:10 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e339.9c18ed75 does not match aorg 0xea75e33d.9c0cf0c4 from server@64.142.54.12 xmt 0xea75e355.de0645b8 Aug 25 17:07:10 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e339.9c1ca273 does not match aorg 0xea75e33d.9c180056 from server@23.168.24.210 xmt 0xea75e356.0a7fcce6 Aug 25 17:07:10 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e33b.9c0cb79d does not match aorg 0xea75e33d.9c0cf0c4 from server@64.142.54.12 xmt 0xea75e357.b3edc98a Aug 25 17:07:10 volumio-garage ntpd[852]: receive: Unexpected origin timestamp 0xea75e33b.9c16c32b does not match aorg 0xea75e33d.9c180056 from server@23.168.24.210 xmt 0xea75e357.cc0cef44 Aug 25 17:07:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 17:07:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:07:12 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 17:07:14 volumio-garage volumio[1377]: info: Listing playlists Aug 25 17:07:14 volumio-garage volumio[1377]: info: Listing playlists Aug 25 17:07:16 volumio-garage volumio[1377]: info: Discovery: Remote connection error: 10.20.1.174 Aug 25 17:07:16 volumio-garage volumio[1377]: info: Discovery: Remote connection timeout: 10.20.1.174 Aug 25 17:07:17 volumio-garage volumio[1568]: ................................................................................................................................................++++ Aug 25 17:07:17 volumio-garage volumio[1568]: e is 65537 (0x010001) Aug 25 17:07:17 volumio-garage volumio[1568]: writing RSA key Aug 25 17:07:21 volumio-garage sshd[1722]: Connection reset by 10.20.1.27 port 62190 [preauth] Aug 25 17:07:25 volumio-garage volumio[1377]: info: Discovery: Remote connection error: 10.20.1.174 Aug 25 17:07:25 volumio-garage volumio[1377]: info: Discovery: Remote connection timeout: 10.20.1.174 Aug 25 17:07:29 volumio-garage volumio[1377]: verbose: New Socket.io Connection to 10.20.1.198:3000 from 10.20.1.174 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 25 17:07:30 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:07:30 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:07:30 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 25 17:07:30 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:07:30 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:07:30 volumio-garage volumio[1377]: verbose: New Socket.io Connection to volumio-garage.local from 10.20.1.27 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 25 17:07:30 volumio-garage volumio[1377]: info: Discovery: Connected to remote: 10.20.1.174 Aug 25 17:07:31 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:07:31 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:07:31 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetQueue Aug 25 17:07:31 volumio-garage volumio[1377]: info: CoreStateMachine::getQueue Aug 25 17:07:31 volumio-garage volumio[1377]: info: CorePlayQueue::getQueue Aug 25 17:07:31 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 25 17:07:31 volumio-garage volumio[1377]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Aug 25 17:07:31 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Aug 25 17:07:31 volumio-garage volumio[1377]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Aug 25 17:07:31 volumio-garage volumio[1377]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Aug 25 17:07:31 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Aug 25 17:07:31 volumio-garage volumio[1377]: info: Listing playlists Aug 25 17:07:31 volumio-garage volumio[1377]: info: Listing playlists Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 17:07:32 volumio-garage volumio[1377]: info: Adding plugin bluetooth to MyMusic Plugins Aug 25 17:07:32 volumio-garage volumio[1377]: info: Adding plugin multiroom to MyMusic Plugins Aug 25 17:07:32 volumio-garage volumio[1377]: info: Adding plugin metavolumio to MyMusic Plugins Aug 25 17:07:32 volumio-garage volumio[1377]: info: Adding plugin cd_controller to MyMusic Plugins Aug 25 17:07:32 volumio-garage volumio[1377]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 25 17:07:32 volumio-garage volumio[1377]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 25 17:07:32 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 25 17:07:33 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 25 17:07:33 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 25 17:07:33 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:07:33 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 17:07:33 volumio-garage volumio[1377]: info: Starting MyVolumio Remote Streaming Endpoints Aug 25 17:07:33 volumio-garage volumio[1377]: info: MyVolumio login type: Token Aug 25 17:07:33 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 25 17:07:33 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 25 17:07:35 volumio-garage volumio[1377]: info: Starting Streaming Service Transparent Proxy Aug 25 17:07:35 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 25 17:07:35 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 25 17:07:35 volumio-garage volumio[1377]: info: Streaming services startup Aug 25 17:07:35 volumio-garage volumio[1377]: info: Starting Streaming Daemon Aug 25 17:07:35 volumio-garage sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 25 17:07:35 volumio-garage sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:07:35 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 25 17:07:35 volumio-garage sudo[1787]: pam_unix(sudo:session): session closed for user root Aug 25 17:07:35 volumio-garage volumio[1377]: info: CALLMETHOD: system_controller system enableSSH true Aug 25 17:07:35 volumio-garage volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Aug 25 17:07:35 volumio-garage volumio[1377]: error: Cannot start Volumio Streaming Daemon Aug 25 17:07:35 volumio-garage volumio[1377]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 25 17:07:35 volumio-garage volumio[1377]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 25 17:07:35 volumio-garage sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Aug 25 17:07:35 volumio-garage sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:07:35 volumio-garage sudo[1795]: pam_unix(sudo:session): session closed for user root Aug 25 17:07:35 volumio-garage sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Aug 25 17:07:35 volumio-garage sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 17:07:36 volumio-garage systemd[1]: Reloading. Aug 25 17:07:36 volumio-garage systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 25 17:07:36 volumio-garage volumio[1377]: STREAMING PROXY: Starting server on port 3245 Aug 25 17:07:36 volumio-garage volumio[1377]: Node JS runtime: 14 Aug 25 17:07:36 volumio-garage systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Aug 25 17:07:37 volumio-garage volumio[1377]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 25 17:07:37 volumio-garage systemd[1]: Reloading. Aug 25 17:07:37 volumio-garage systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 25 17:07:38 volumio-garage volumio[1377]: info: MyVolumio token set successfully Aug 25 17:07:38 volumio-garage volumio[1377]: info: MYVOLUMIO: Adding device Aug 25 17:07:38 volumio-garage volumio[1377]: info: MYVOLUMIO: Evaluating Server Aug 25 17:07:38 volumio-garage systemd[1]: Reloading. Aug 25 17:07:38 volumio-garage systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 25 17:07:39 volumio-garage sudo[1797]: pam_unix(sudo:session): session closed for user root Aug 25 17:07:39 volumio-garage volumio[1377]: info: enable SSH service success Aug 25 17:07:40 volumio-garage volumio[1377]: info: MyVolumio Plan changed: premium Aug 25 17:07:40 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 25 17:07:40 volumio-garage volumio[1377]: info: Removing browser output: myVolumio user plan is not superstar Aug 25 17:07:40 volumio-garage volumio[1377]: info: Removing audio output: Aug 25 17:07:40 volumio-garage volumio[1377]: info: MYVOLUMIO: Adding device Aug 25 17:07:40 volumio-garage volumio[1377]: info: MYVOLUMIO: Evaluating Server Aug 25 17:07:40 volumio-garage volumio[1377]: info: Remote config written successfully Aug 25 17:07:40 volumio-garage volumio[1377]: info: Starting Tunnel 1 Aug 25 17:07:40 volumio-garage volumio[1377]: info: Starting Tunnel Connection Checker Aug 25 17:07:40 volumio-garage volumio[1377]: info: CoreCommandRouter::volumioGetState Aug 25 17:07:40 volumio-garage volumio[1377]: info: CorePlayQueue::getTrack 0 Aug 25 17:07:41 volumio-garage sshd[1739]: Accepted password for volumio from 10.20.1.27 port 62280 ssh2 Aug 25 17:07:41 volumio-garage sshd[1739]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Aug 25 17:07:41 volumio-garage systemd-logind[634]: New session 1 of user volumio. Aug 25 17:07:41 volumio-garage systemd[1]: Created slice User Slice of UID 1000. Aug 25 17:07:41 volumio-garage systemd[1]: Starting User Runtime Directory /run/user/1000... Aug 25 17:07:41 volumio-garage volumio[1377]: info: MYVolumio Device enabled Aug 25 17:07:41 volumio-garage volumio[1377]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 25 17:07:41 volumio-garage volumio[1377]: [2024-08-25T17:07:41.571Z] @firebase/database: FIREBASE WARNING: Exception was thrown by user callback. TypeError: Cannot read property 'replace' of null Aug 25 17:07:41 volumio-garage volumio[1377]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Aug 25 17:07:41 volumio-garage volumio[1377]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Aug 25 17:07:41 volumio-garage volumio[1377]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Aug 25 17:07:41 volumio-garage volumio[1377]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/v-conf/index.js:165:13 Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/multimap/index.js:150:9 Aug 25 17:07:41 volumio-garage volumio[1377]: at Array.forEach () Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/multimap/index.js:149:13 Aug 25 17:07:41 volumio-garage volumio[1377]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 25 17:07:41 volumio-garage volumio[1377]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5) Aug 25 17:07:41 volumio-garage volumio[1377]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10) Aug 25 17:07:41 volumio-garage volumio[1377]: at Config.set (/volumio/node_modules/v-conf/index.js:162:20) Aug 25 17:07:41 volumio-garage volumio[1377]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36225) Aug 25 17:07:41 volumio-garage volumio[1377]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17857 Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Aug 25 17:07:41 volumio-garage volumio[1377]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Aug 25 17:07:41 volumio-garage volumio[1377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 17:07:41 volumio-garage volumio[1377]: TypeError: Cannot read property 'replace' of null Aug 25 17:07:41 volumio-garage volumio[1377]: at MyVolumioPluginManager.fetchPluginKeys (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:8044) Aug 25 17:07:41 volumio-garage volumio[1377]: at MyVolumioPluginManager.loadMyVolumioPlugins (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:11563) Aug 25 17:07:41 volumio-garage volumio[1377]: at MyVolumioPluginManager.myVolumioDeviceStatusChanged (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:5527) Aug 25 17:07:41 volumio-garage volumio[1377]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6807 Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/v-conf/index.js:165:13 Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/multimap/index.js:150:9 Aug 25 17:07:41 volumio-garage volumio[1377]: at Array.forEach () Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/multimap/index.js:149:13 Aug 25 17:07:41 volumio-garage volumio[1377]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 25 17:07:41 volumio-garage volumio[1377]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5) Aug 25 17:07:41 volumio-garage volumio[1377]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10) Aug 25 17:07:41 volumio-garage volumio[1377]: at Config.set (/volumio/node_modules/v-conf/index.js:162:20) Aug 25 17:07:41 volumio-garage volumio[1377]: at myVolumio.togglePremiumPlugins (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:36225) Aug 25 17:07:41 volumio-garage volumio[1377]: at /myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:17857 Aug 25 17:07:41 volumio-garage volumio[1377]: at /volumio/node_modules/@firebase/database/dist/index.node.cjs.js:4549:22 Aug 25 17:07:41 volumio-garage volumio[1377]: at exceptionGuard (/volumio/node_modules/@firebase/database/dist/index.node.cjs.js:698:9) Aug 25 17:07:41 volumio-garage volumio[1377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 17:07:41 volumio-garage systemd[1]: Started User Runtime Directory /run/user/1000. Aug 25 17:07:41 volumio-garage systemd[1]: Starting User Manager for UID 1000... Aug 25 17:07:41 volumio-garage systemd[1880]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on GnuPG network certificate management daemon. Aug 25 17:07:42 volumio-garage systemd[1880]: Reached target Timers. Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on GnuPG cryptographic agent and passphrase cache. Aug 25 17:07:42 volumio-garage systemd[1880]: Starting D-Bus User Message Bus Socket. Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Aug 25 17:07:42 volumio-garage systemd[1880]: Reached target Paths. Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on Sound System. Aug 25 17:07:42 volumio-garage systemd[1880]: Listening on D-Bus User Message Bus Socket. Aug 25 17:07:42 volumio-garage systemd[1880]: Reached target Sockets. Aug 25 17:07:42 volumio-garage systemd[1880]: Reached target Basic System. Aug 25 17:07:42 volumio-garage systemd[1]: Started User Manager for UID 1000. Aug 25 17:07:42 volumio-garage systemd[1880]: Starting Music Player Daemon... Aug 25 17:07:42 volumio-garage systemd[1]: Started Session 1 of user volumio. Aug 25 17:07:42 volumio-garage sudo[1908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 17:06 Aug 25 17:07:42 volumio-garage sudo[1908]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"