-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-08-17 10:55:14 UTC. -- Aug 17 10:54:50 volumio-upstairs ntpd[642]: receive: Unexpected origin timestamp 0xe55b15c8.73ecce2a does not match aorg 0000000000.00000000 from server@213.157.100.66 xmt 0xea6afffa.ad528416 Aug 17 10:54:50 volumio-upstairs ntpd[642]: receive: Unexpected origin timestamp 0xe55b15c8.73e71144 does not match aorg 0000000000.00000000 from server@62.112.193.9 xmt 0xea6afffa.ad3d1488 Aug 17 10:54:50 volumio-upstairs systemd[1]: Starting Daily apt download activities... Aug 17 10:54:50 volumio-upstairs ntpd[642]: receive: Unexpected origin timestamp 0xe55b15c8.73f12bf8 does not match aorg 0000000000.00000000 from server@82.141.152.3 xmt 0xea6afffa.ae3b51a7 Aug 17 10:54:50 volumio-upstairs ntpd[642]: receive: Unexpected origin timestamp 0xe55b15c8.73ef10be does not match aorg 0000000000.00000000 from server@80.249.164.126 xmt 0xea6afffa.ae686853 Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: A device disapperared from network Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: A device disapperared from network Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: A device disapperared from network Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: A device disapperared from network Aug 17 10:54:50 volumio-upstairs volumio[894]: info: Discovery: adding c79b0721-d5af-4092-af31-65f21f169a0c Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: Found device Volumio downstairs Aug 17 10:54:50 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 10:54:50 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:54:50 volumio-upstairs volumio[894]: info: Discovery: adding bf49d867-0585-46ca-a3e1-6eb08f87d226 Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: Found device Volumio upstairs Aug 17 10:54:50 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 10:54:50 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:54:50 volumio-upstairs volumio[894]: info: Discovery: this is already registered, bf49d867-0585-46ca-a3e1-6eb08f87d226 Aug 17 10:54:50 volumio-upstairs volumio[894]: info: mDNS: Found device Volumio upstairs Aug 17 10:54:50 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 10:54:50 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:54:51 volumio-upstairs systemd[1]: apt-daily.service: Succeeded. Aug 17 10:54:51 volumio-upstairs systemd[1]: Started Daily apt download activities. Aug 17 10:54:51 volumio-upstairs systemd[1]: Starting Daily apt upgrade and clean activities... Aug 17 10:54:51 volumio-upstairs volumio[894]: info: Discovery: this is already registered, c79b0721-d5af-4092-af31-65f21f169a0c Aug 17 10:54:51 volumio-upstairs volumio[894]: info: mDNS: Found device Volumio downstairs Aug 17 10:54:51 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 10:54:51 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 17 10:54:52 volumio-upstairs volumio[894]: info: Adding plugin bluetooth to MyMusic Plugins Aug 17 10:54:52 volumio-upstairs volumio[894]: info: Adding plugin multiroom to MyMusic Plugins Aug 17 10:54:52 volumio-upstairs volumio[894]: info: Adding plugin cd_controller to MyMusic Plugins Aug 17 10:54:52 volumio-upstairs volumio[894]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 17 10:54:52 volumio-upstairs volumio[894]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 17 10:54:52 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 17 10:54:52 volumio-upstairs systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 17 10:54:52 volumio-upstairs systemd[1]: Started Daily apt upgrade and clean activities. Aug 17 10:54:53 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 17 10:54:53 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 17 10:54:53 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:53 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:53 volumio-upstairs volumio[894]: info: Starting MyVolumio Remote Streaming Endpoints Aug 17 10:54:53 volumio-upstairs volumio[894]: info: MyVolumio login type: Token Aug 17 10:54:53 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 17 10:54:53 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 17 10:54:53 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 17 10:54:53 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 17 10:54:53 volumio-upstairs volumio[894]: info: Streaming services startup Aug 17 10:54:53 volumio-upstairs volumio[894]: info: Starting Streaming Daemon Aug 17 10:54:54 volumio-upstairs sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 17 10:54:54 volumio-upstairs sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:54:54 volumio-upstairs systemd[1]: Stopping Volumio Streaming Daemon... Aug 17 10:54:54 volumio-upstairs systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Aug 17 10:54:54 volumio-upstairs systemd[1]: volumio-streaming-daemon.service: Succeeded. Aug 17 10:54:54 volumio-upstairs systemd[1]: Stopped Volumio Streaming Daemon. Aug 17 10:54:54 volumio-upstairs systemd[1]: Started Volumio Streaming Daemon. Aug 17 10:54:54 volumio-upstairs sudo[1478]: pam_unix(sudo:session): session closed for user root Aug 17 10:54:54 volumio-upstairs volumio-streaming-daemon[1484]: ############################ Aug 17 10:54:54 volumio-upstairs volumio-streaming-daemon[1484]: # Volumio Streaming Daemon # Aug 17 10:54:54 volumio-upstairs volumio-streaming-daemon[1484]: # Running on port 7777 # Aug 17 10:54:54 volumio-upstairs volumio-streaming-daemon[1484]: ############################ Aug 17 10:54:54 volumio-upstairs volumio-streaming-daemon[1484]: Environment: production Aug 17 10:54:54 volumio-upstairs volumio[894]: error: MyVolumio Custom Token format not valid, refreshing it Aug 17 10:54:55 volumio-upstairs volumio[894]: info: MyVolumio login type: Token Aug 17 10:54:55 volumio-upstairs volumio[894]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 17 10:54:55 volumio-upstairs volumio[894]: info: MYVOLUMIO: Adding device Aug 17 10:54:55 volumio-upstairs volumio[894]: info: MYVOLUMIO: Evaluating Server Aug 17 10:54:56 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Removing browser output: myVolumio user plan is not superstar Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Removing audio output: Aug 17 10:54:56 volumio-upstairs volumio[894]: info: MYVOLUMIO: Adding device Aug 17 10:54:56 volumio-upstairs volumio[894]: info: MYVOLUMIO: Evaluating Server Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Remote config written successfully Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Starting Tunnel 1 Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Starting Tunnel Connection Checker Aug 17 10:54:56 volumio-upstairs sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 17 10:54:56 volumio-upstairs sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:54:56 volumio-upstairs systemd[1]: Started MyVolumio SSH Tunnel. Aug 17 10:54:56 volumio-upstairs sudo[1513]: pam_unix(sudo:session): session closed for user root Aug 17 10:54:56 volumio-upstairs autossh[1517]: port set to 0, monitoring disabled Aug 17 10:54:56 volumio-upstairs autossh[1517]: starting ssh (count 1) Aug 17 10:54:56 volumio-upstairs autossh[1517]: ssh child pid is 1522 Aug 17 10:54:56 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 17 10:54:56 volumio-upstairs volumio[894]: info: MyVolumio status changed Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Streaming services startup Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Re-Starting Streaming Daemon Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Setting Geolocation for MyVolumio to eu7 Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:56 volumio-upstairs volumiossh-tunnel[1515]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[68.183.69.189]:2222' (RSA) to the list of known hosts. Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Remote SSH Started Aug 17 10:54:56 volumio-upstairs volumio-streaming-daemon[1484]: Restarting daemon Aug 17 10:54:56 volumio-upstairs volumio-streaming-daemon[1484]: Environment: production Aug 17 10:54:56 volumio-upstairs volumio[894]: info: Setting Geolocation for MyVolumio to eu7 Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:56 volumio-upstairs volumio-streaming-daemon[1484]: Environment: production Aug 17 10:54:56 volumio-upstairs volumio[894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Aug 17 10:54:56 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 17 10:54:56 volumio-upstairs volumio[894]: error: [MyVolumio PluginManager] Cache data is invalid! Aug 17 10:54:57 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioGetState Aug 17 10:54:57 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:54:57 volumio-upstairs volumio[894]: info: Fetching Streaming Services browse cache Aug 17 10:54:57 volumio-upstairs volumio-streaming-daemon[1484]: Enabled services: Aug 17 10:54:57 volumio-upstairs volumio-streaming-daemon[1484]: 1) tidal Aug 17 10:54:57 volumio-upstairs volumio-streaming-daemon[1484]: 2) qobuz Aug 17 10:54:57 volumio-upstairs volumio[894]: info: Updating MyVolumio device info Aug 17 10:54:57 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:54:57 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Aug 17 10:54:57 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 17 10:54:57 volumio-upstairs volumio[894]: info: [1723892097944] Starting BluetoothController Aug 17 10:54:58 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 17 10:55:00 volumio-upstairs sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Aug 17 10:55:00 volumio-upstairs sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:00 volumio-upstairs sudo[1540]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:00 volumio-upstairs sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 17 10:55:00 volumio-upstairs sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:00 volumio-upstairs sudo[1542]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:00 volumio-upstairs volumio[894]: info: MultiRoom plugin initialized Aug 17 10:55:00 volumio-upstairs volumio[894]: STOPPING SNAPCLIENT Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 17 10:55:00 volumio-upstairs sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 17 10:55:00 volumio-upstairs sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 17 10:55:00 volumio-upstairs sudo[1559]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Preparing to generate the ALSA configuration file Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Updating MyVolumio device info Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 17 10:55:00 volumio-upstairs volumio[894]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Reading ALSA contributions from plugins. Aug 17 10:55:00 volumio-upstairs volumio[894]: info: MRS: SNAPCLIENT STOPPED Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Snap server and client installed Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Asound.conf file written Aug 17 10:55:00 volumio-upstairs sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 17 10:55:00 volumio-upstairs sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:00 volumio-upstairs sudo[1562]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Output device has changed, restarting MPD Aug 17 10:55:00 volumio-upstairs sudo[1567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 17 10:55:00 volumio-upstairs sudo[1567]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Output device has changed, restarting Shairport Sync Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:55:00 volumio-upstairs sudo[1567]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:00 volumio-upstairs sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 17 10:55:00 volumio-upstairs sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:00 volumio-upstairs systemd[1]: Stopping Music Player Daemon... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 17 10:55:00 volumio-upstairs systemd[1]: mpd.service: Succeeded. Aug 17 10:55:00 volumio-upstairs systemd[1]: Stopped Music Player Daemon. Aug 17 10:55:00 volumio-upstairs systemd[1]: Starting Music Player Daemon... Aug 17 10:55:00 volumio-upstairs volumio[894]: info: MPD Permissions set Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 17 10:55:00 volumio-upstairs volumio[894]: info: Starting Shairport Sync Aug 17 10:55:01 volumio-upstairs sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 17 10:55:01 volumio-upstairs sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:01 volumio-upstairs systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 17 10:55:01 volumio-upstairs systemd[1]: shairport-sync.service: Succeeded. Aug 17 10:55:01 volumio-upstairs systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 17 10:55:01 volumio-upstairs systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 17 10:55:01 volumio-upstairs sudo[1581]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Shairport-Sync Started Aug 17 10:55:01 volumio-upstairs volumio-streaming-daemon[1484]: Environment: production Aug 17 10:55:01 volumio-upstairs volumio-streaming-daemon[1484]: Environment: production Aug 17 10:55:01 volumio-upstairs volumio[894]: Current plan: premium. Enabling manifest UI Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 17 10:55:01 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 17 10:55:01 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 17 10:55:01 volumio-upstairs volumio[894]: info: MRS: Pushing multiroomSync output for this device Aug 17 10:55:01 volumio-upstairs volumio[894]: info: MRS: Pushing multiroomSync output Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding audio output: Aug 17 10:55:01 volumio-upstairs sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio upstairs Aug 17 10:55:01 volumio-upstairs sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding METAVOLUMIO REST API Endpoints Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Preparing CD Folders Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding CD REST API Endpoints Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Starting UDEV Watcher for CD Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Detecting CD presence with UDEV Aug 17 10:55:01 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 17 10:55:01 volumio-upstairs sudo[1589]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding inputs REST Endpoints Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Scanning Audio Inputs Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Checking against Known Cards name Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding Server instance for streaming Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 17 10:55:01 volumio-upstairs volumio[894]: error: Hi Res Audio Failed Login: Missing Login Data Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Refreshing TIDAL token Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 17 10:55:01 volumio-upstairs volumio[894]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Stopping AccessToken refresher cron for QOBUZ Aug 17 10:55:01 volumio-upstairs sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 17 10:55:01 volumio-upstairs sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:01 volumio-upstairs sudo[1594]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:01 volumio-upstairs volumio[894]: info: AccessToken refresher cron started for QOBUZ Aug 17 10:55:01 volumio-upstairs volumio[894]: info: MRS: Adding multiroomSync output Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Adding audio output: Aug 17 10:55:01 volumio-upstairs volumio[894]: info: MRS: Pushing multiroomSync output Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Bluetooth name changed to Volumio upstairs Aug 17 10:55:01 volumio-upstairs volumio[894]: info: Fetching Streaming Services browse cache Aug 17 10:55:02 volumio-upstairs mpd[1587]: Aug 17 10:55 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 17 10:55:02 volumio-upstairs mpd[1587]: Aug 17 10:55 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 17 10:55:02 volumio-upstairs mpd[1587]: Aug 17 10:55 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 17 10:55:02 volumio-upstairs systemd[1]: Started Music Player Daemon. Aug 17 10:55:02 volumio-upstairs sudo[1569]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:02 volumio-upstairs volumio[894]: error: updateQueue error: null Aug 17 10:55:02 volumio-upstairs volumio[894]: info: Access Token successfully retrieved Aug 17 10:55:03 volumio-upstairs volumio[894]: info: Successfully retrieved User Session From TIDAL Aug 17 10:55:03 volumio-upstairs volumio[894]: info: Successfully retrieved User Subscription From TIDAL Aug 17 10:55:03 volumio-upstairs volumio[894]: info: Adding TIDAL to Browse Sources Aug 17 10:55:03 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 17 10:55:03 volumio-upstairs volumio[894]: info: [1723892103368] CoreMusicLibrary::Adding element TIDAL Aug 17 10:55:03 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 17 10:55:03 volumio-upstairs volumio[894]: Cannot find translation for sourceMedia Servers Aug 17 10:55:03 volumio-upstairs volumio[894]: Cannot find translation for sourceSoundCloud Aug 17 10:55:03 volumio-upstairs volumio[894]: Cannot find translation for sourceTIDAL Aug 17 10:55:03 volumio-upstairs volumio[894]: info: Stopping AccessToken refresher cron Aug 17 10:55:03 volumio-upstairs volumio[894]: info: AccessToken refresher cron started Aug 17 10:55:04 volumio-upstairs sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 17 10:55:04 volumio-upstairs sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:04 volumio-upstairs systemd[1]: Started Volumio Bluetooth Module. Aug 17 10:55:04 volumio-upstairs sudo[1600]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:04 volumio-upstairs sudo[1603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Aug 17 10:55:04 volumio-upstairs sudo[1603]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:04 volumio-upstairs volumiobt[1602]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Aug 17 10:55:04 volumio-upstairs volumio[894]: info: Volumio BT Module successfully started Aug 17 10:55:04 volumio-upstairs sudo[1603]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:04 volumio-upstairs volumio[894]: info: TidalConnect service stoped! Aug 17 10:55:04 volumio-upstairs volumio[894]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 17 10:55:04 volumio-upstairs volumio[894]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 17 10:55:04 volumio-upstairs sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 17 10:55:04 volumio-upstairs sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:04 volumio-upstairs systemd[1]: Started Volumio Tidal Connect Service. Aug 17 10:55:04 volumio-upstairs sudo[1611]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:05 volumio-upstairs volumio[894]: info: Executing endpoint tc_getconfig Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 17 10:55:05 volumio-upstairs vtcs[1613]: STARTING TidalConnect services, version: 1.1.7.5 Aug 17 10:55:05 volumio-upstairs vtcs[1613]: STARTED TidalConnect services. Aug 17 10:55:05 volumio-upstairs volumio[894]: info: Executing endpoint tc_connect Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 17 10:55:05 volumio-upstairs volumio[894]: Connecting to TidalConnect. Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreCommandRouter::servicePushState Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreStateMachine::pushState Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioPushState Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:55:05 volumio-upstairs volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreCommandRouter::servicePushState Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreStateMachine::pushState Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CoreCommandRouter::volumioPushState Aug 17 10:55:05 volumio-upstairs volumio[894]: info: CorePlayQueue::getTrack 0 Aug 17 10:55:05 volumio-upstairs volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Aug 17 10:55:05 volumio-upstairs sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Aug 17 10:55:05 volumio-upstairs sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 17 10:55:05 volumio-upstairs volumiobt[1602]: pulseaudio: no process found Aug 17 10:55:05 volumio-upstairs sudo[1631]: pam_unix(sudo:session): session closed for user root Aug 17 10:55:05 volumio-upstairs dbus-daemon[459]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.27' (uid=1000 pid=1635 comm="/usr/bin/pulseaudio ") Aug 17 10:55:05 volumio-upstairs systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 17 10:55:05 volumio-upstairs volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 17 10:55:05 volumio-upstairs volumio[894]: BT PLUGIN MESSAGE: Playing: false Aug 17 10:55:05 volumio-upstairs volumio[894]: BT PLUGIN MESSAGE: VOLATILE: undefined Aug 17 10:55:05 volumio-upstairs volumio[894]: BT PLUGIN MESSAGE: STATE:undefined Aug 17 10:55:05 volumio-upstairs dbus-daemon[459]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 17 10:55:05 volumio-upstairs systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 17 10:55:05 volumio-upstairs rtkit-daemon[1636]: Successfully called chroot. Aug 17 10:55:05 volumio-upstairs rtkit-daemon[1636]: Successfully dropped privileges. Aug 17 10:55:05 volumio-upstairs rtkit-daemon[1636]: Successfully limited resources. Aug 17 10:55:05 volumio-upstairs rtkit-daemon[1636]: Running. Aug 17 10:55:05 volumio-upstairs rtkit-daemon[1636]: Canary thread running. Aug 17 10:55:05 volumio-upstairs rtkit-daemon[1636]: Watchdog thread running. Aug 17 10:55:05 volumio-upstairs dbus-daemon[459]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.28' (uid=0 pid=1636 comm="/usr/lib/rtkit/rtkit-daemon ") Aug 17 10:55:05 volumio-upstairs systemd[1]: Starting Authorization Manager... Aug 17 10:55:05 volumio-upstairs volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 17 10:55:05 volumio-upstairs volumio[894]: BT PLUGIN MESSAGE: Playing: false Aug 17 10:55:05 volumio-upstairs volumio[894]: BT PLUGIN MESSAGE: VOLATILE: undefined Aug 17 10:55:05 volumio-upstairs volumio[894]: BT PLUGIN MESSAGE: STATE:undefined Aug 17 10:55:05 volumio-upstairs polkitd[1639]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 17 10:55:05 volumio-upstairs dbus-daemon[459]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 17 10:55:05 volumio-upstairs systemd[1]: Started Authorization Manager. Aug 17 10:55:06 volumio-upstairs pulseaudio[1635]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 17 10:55:06 volumio-upstairs pulseaudio[1635]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Applying permissions Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Setting BT discoverable and pairable Aug 17 10:55:06 volumio-upstairs bluetoothd[696]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSource Aug 17 10:55:06 volumio-upstairs bluetoothd[696]: Endpoint registered: sender=:1.48 path=/MediaEndpoint/A2DPSink Aug 17 10:55:06 volumio-upstairs pulseaudio[1635]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Aug 17 10:55:06 volumio-upstairs kernel: Bluetooth: RFCOMM TTY layer initialized Aug 17 10:55:06 volumio-upstairs kernel: Bluetooth: RFCOMM socket layer initialized Aug 17 10:55:06 volumio-upstairs kernel: Bluetooth: RFCOMM ver 1.11 Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Agent registered Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [117B blob data] Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# -e power on Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Invalid command in menu main: -e Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Use "help" for a list of available commands in a menu. Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Use "menu " if you want to enter any submenu. Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Use "back" if you want to return to menu main. Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# agent on Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Agent is already registered Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# discoverable on Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# pairable on Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# agent NoInputNoOutput Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: Agent is already registered Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# default-agent Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [bluetooth]# quit Aug 17 10:55:06 volumio-upstairs volumiobt[1602]: [59B blob data] Aug 17 10:55:06 volumio-upstairs volumio[894]: info: MRS: Getting audio outputs on start Aug 17 10:55:06 volumio-upstairs volumio[894]: info: MRS: Requesting all other devices output Aug 17 10:55:06 volumio-upstairs volumio[894]: info: MRS: Updating multiroomSync output Aug 17 10:55:07 volumio-upstairs volumio[894]: info: TidalConnect service started! Aug 17 10:55:13 volumio-upstairs volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 10:55:13 volumio-upstairs volumio[894]: Error: connect EHOSTUNREACH 192.168.100.187:49152 Aug 17 10:55:13 volumio-upstairs volumio[894]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 17 10:55:13 volumio-upstairs volumio[894]: errno: -113, Aug 17 10:55:13 volumio-upstairs volumio[894]: code: 'EHOSTUNREACH', Aug 17 10:55:13 volumio-upstairs volumio[894]: syscall: 'connect', Aug 17 10:55:13 volumio-upstairs volumio[894]: address: '192.168.100.187', Aug 17 10:55:13 volumio-upstairs volumio[894]: port: 49152 Aug 17 10:55:13 volumio-upstairs volumio[894]: } Aug 17 10:55:13 volumio-upstairs volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 17 10:55:14 volumio-upstairs sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-17 10:54 Aug 17 10:55:14 volumio-upstairs sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="543f2de901f774399af6eae9d76f06fe4162e5dd" VOLUMIO_FE_VERSION="dff19baf69ee85af3381c73e932cb6852dfe38e0" VOLUMIO_FE3_VERSION="081f1f7a36b167d793d831ee89ac02e7f8f3ee4b" VOLUMIO_BE_VERSION="1eeee4d17c356c3967f07f80a7dfdbc12de2e066" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 08 Dec 2021 12:21:52 PM CET" VOLUMIO_VERSION="3.173" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1c9433a7294bad3fb7682386561b54d4"