-- Logs begin at Fri 2024-03-29 15:28:44 CET, end at Fri 2024-03-29 15:30:55 CET. -- Mar 29 15:29:00 volumio-pc ntpd[1460]: Soliciting pool server 62.128.1.18 Mar 29 15:29:00 volumio-pc ntpd[1460]: Soliciting pool server 144.76.0.164 Mar 29 15:29:00 volumio-pc ntpd[1460]: Soliciting pool server 194.25.134.196 Mar 29 15:29:00 volumio-pc sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 15:29:00 volumio-pc sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:00 volumio-pc sudo[1526]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:00 volumio-pc sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 15:29:00 volumio-pc sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:00 volumio-pc sudo[1529]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:00 volumio-pc sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 29 15:29:00 volumio-pc sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:00 volumio-pc systemd[1]: Started UPnP Renderer front-end to MPD. Mar 29 15:29:00 volumio-pc sudo[1534]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:00 volumio-pc volumio[734]: info: Upmpdcli Daemon Started Mar 29 15:29:00 volumio-pc volumio[1536]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 29 15:29:00 volumio-pc volumio[1536]: ..++++ Mar 29 15:29:01 volumio-pc volumio[1536]: .............................++++ Mar 29 15:29:01 volumio-pc volumio[1536]: e is 65537 (0x010001) Mar 29 15:29:01 volumio-pc volumio[1536]: writing RSA key Mar 29 15:29:01 volumio-pc volumio[734]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 29 15:29:01 volumio-pc ntpd[1460]: Soliciting pool server 136.243.202.118 Mar 29 15:29:01 volumio-pc ntpd[1460]: Soliciting pool server 129.70.132.34 Mar 29 15:29:02 volumio-pc ntpd[1460]: Soliciting pool server 176.9.157.155 Mar 29 15:29:02 volumio-pc ntpd[1460]: Soliciting pool server 2a0c:8900:1::ed58:3d69 Mar 29 15:29:02 volumio-pc volumio[734]: info: Getting Spotify volume Mar 29 15:29:03 volumio-pc volumio[734]: info: Spotify volume: 100 Mar 29 15:29:03 volumio-pc volumio[734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Mar 29 15:29:03 volumio-pc volumio[734]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.634&uuid=48ccbd74a51267d6160b97a81734114a" http://updates.volumio.org/downloader-v1/track-device Mar 29 15:29:03 volumio-pc volumio[734]: % Total % Received % Xferd Average Speed Time Time Time Current Mar 29 15:29:03 volumio-pc volumio[734]: Dload Upload Total Spent Left Speed Mar 29 15:29:03 volumio-pc volumio[734]: [132B blob data] Mar 29 15:29:03 volumio-pc volumio[734]: retrying in 5 seconds, trial 0 Mar 29 15:29:03 volumio-pc volumio[734]: info: Volumio Calling Home Mar 29 15:29:04 volumio-pc ntpd[1460]: receive: Unexpected origin timestamp 0xe9b14eb0.8bdda348 does not match aorg 0000000000.00000000 from server@176.9.157.155 xmt 0xe9b14eb0.6979bf09 Mar 29 15:29:04 volumio-pc ntpd[1460]: receive: Unexpected origin timestamp 0xe9b14eb0.8be5b426 does not match aorg 0000000000.00000000 from server@213.209.109.44 xmt 0xe9b14eb0.69bc51af Mar 29 15:29:04 volumio-pc ntpd[1460]: receive: Unexpected origin timestamp 0xe9b14eb0.8be4e87c does not match aorg 0000000000.00000000 from server@78.47.168.188 xmt 0xe9b14eb0.6974cdea Mar 29 15:29:04 volumio-pc ntpd[1460]: receive: Unexpected origin timestamp 0xe9b14eb0.8be25a3a does not match aorg 0000000000.00000000 from server@194.25.134.196 xmt 0xe9b14eb0.698344e4 Mar 29 15:29:04 volumio-pc ntpd[1460]: receive: Unexpected origin timestamp 0xe9b14eb0.8be1a5e1 does not match aorg 0000000000.00000000 from server@129.70.132.34 xmt 0xe9b14eb0.69fbdc94 Mar 29 15:29:04 volumio-pc volumio[734]: info: Volumio called home Mar 29 15:29:04 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:29:06 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:06 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:06 volumio-pc volumio[734]: verbose: New Socket.io Connection to 192.168.188.71:3000 from 192.168.188.28 UA: node-XMLHttpRequest Total Clients: 9 Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 29 15:29:06 volumio-pc volumio[734]: info: Adding plugin bluetooth to MyMusic Plugins Mar 29 15:29:06 volumio-pc volumio[734]: info: Adding plugin multiroom to MyMusic Plugins Mar 29 15:29:06 volumio-pc volumio[734]: info: Adding plugin metavolumio to MyMusic Plugins Mar 29 15:29:06 volumio-pc volumio[734]: info: Adding plugin cd_controller to MyMusic Plugins Mar 29 15:29:06 volumio-pc volumio[734]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 29 15:29:06 volumio-pc volumio[734]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 29 15:29:06 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 29 15:29:07 volumio-pc volumio[734]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 29 15:29:07 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 29 15:29:07 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:07 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:07 volumio-pc volumio[734]: info: Starting MyVolumio Remote Streaming Endpoints Mar 29 15:29:07 volumio-pc volumio[734]: info: MyVolumio login type: Token Mar 29 15:29:07 volumio-pc volumio[734]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 29 15:29:07 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 29 15:29:08 volumio-pc volumio[734]: info: Starting Streaming Service Transparent Proxy Mar 29 15:29:08 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 29 15:29:08 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 29 15:29:08 volumio-pc volumio[734]: info: Streaming services startup Mar 29 15:29:08 volumio-pc volumio[734]: info: Starting Streaming Daemon Mar 29 15:29:08 volumio-pc sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 15:29:08 volumio-pc sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:08 volumio-pc sudo[1587]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:08 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 29 15:29:08 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:08 volumio-pc volumio[734]: SPOTIFY: SPOTIFY VOLUME 100 Mar 29 15:29:08 volumio-pc volumio[734]: SPOTIFY: VOLUMIO VOLUME 50 Mar 29 15:29:08 volumio-pc volumio[734]: SPOTIFY: DELTA VOLUME ENOUGH: true Mar 29 15:29:08 volumio-pc volumio[734]: info: Setting Spotify Volume from Volumio: 50 Mar 29 15:29:08 volumio-pc volumio[734]: STREAMING PROXY: Starting server on port 3245 Mar 29 15:29:08 volumio-pc volumio[734]: Node JS runtime: 14 Mar 29 15:29:09 volumio-pc volumio[734]: Cannot compose Albumart path Mar 29 15:29:15 volumio-pc systemd[1]: systemd-fsckd.service: Succeeded. Mar 29 15:29:15 volumio-pc volumio[734]: Cannot compose Albumart path Mar 29 15:29:15 volumio-pc volumio[734]: error: Cannot start Volumio Streaming Daemon Mar 29 15:29:15 volumio-pc volumio[734]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 15:29:15 volumio-pc volumio[734]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 15:29:15 volumio-pc volumio[734]: SPOTIFY: SETTING SPOTIFY VOLUME 50 Mar 29 15:29:15 volumio-pc volumio[734]: info: Sending Spotify command with payload to local API: /player/volume Mar 29 15:29:15 volumio-pc volumio[734]: verbose: New Socket.io Connection to 192.168.188.71:3000 from 192.168.188.28 UA: node-XMLHttpRequest Total Clients: 10 Mar 29 15:29:15 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:15+01:00" level=debug msg="update volume to 32767/65535" Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 15:29:15 volumio-pc volumio[734]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 15:29:15 volumio-pc volumio[734]: info: Received Get System Version Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 15:29:15 volumio-pc volumio[734]: info: Received Get System Info Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 15:29:15 volumio-pc volumio[734]: info: Discovery: Getting this device information Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:15 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 15:29:15 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 15:29:15 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Mar 29 15:29:15 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:15+01:00" level=trace msg="emitting websocket event: volume" Mar 29 15:29:15 volumio-pc volumio[734]: SPOTIFY: received: {"type":"volume","data":{"value":50,"max":100}} Mar 29 15:29:15 volumio-pc volumio[734]: SPOTIFY: RECEIVED SPOTIFY VOLUME 50 Mar 29 15:29:16 volumio-pc volumio[734]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 29 15:29:16 volumio-pc volumio[734]: verbose: New Socket.io Connection to 192.168.188.71:3000 from 192.168.188.28 UA: node-XMLHttpRequest Total Clients: 10 Mar 29 15:29:16 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 29 15:29:16 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:16 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:16 volumio-pc volumio[734]: info: MyVolumio token set successfully Mar 29 15:29:16 volumio-pc volumio[734]: info: MYVOLUMIO: Adding device Mar 29 15:29:16 volumio-pc volumio[734]: info: MYVOLUMIO: Evaluating Server Mar 29 15:29:17 volumio-pc volumio[734]: info: MyVolumio Plan changed: premium Mar 29 15:29:17 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 29 15:29:17 volumio-pc volumio[734]: info: Removing browser output: myVolumio user plan is not superstar Mar 29 15:29:17 volumio-pc volumio[734]: info: Removing audio output: Mar 29 15:29:17 volumio-pc volumio[734]: info: MYVOLUMIO: Adding device Mar 29 15:29:17 volumio-pc volumio[734]: info: MYVOLUMIO: Evaluating Server Mar 29 15:29:17 volumio-pc volumio[734]: info: Remote config written successfully Mar 29 15:29:17 volumio-pc volumio[734]: info: Starting Tunnel 1 Mar 29 15:29:17 volumio-pc volumio[734]: info: Starting Tunnel Connection Checker Mar 29 15:29:17 volumio-pc volumio[734]: info: Completed starting MyVolumio Plugin Mar 29 15:29:17 volumio-pc volumio[734]: info: MYVolumio Device enabled Mar 29 15:29:17 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 29 15:29:17 volumio-pc volumio[734]: info: MyVolumio status changed Mar 29 15:29:17 volumio-pc volumio[734]: info: Streaming services startup Mar 29 15:29:17 volumio-pc volumio[734]: info: Starting Streaming Daemon Mar 29 15:29:17 volumio-pc sudo[1629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 15:29:17 volumio-pc sudo[1629]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:17 volumio-pc volumio[734]: info: Setting Geolocation for MyVolumio to eu4 Mar 29 15:29:17 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:17 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:17 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:17 volumio-pc sudo[1629]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:17 volumio-pc volumio[734]: error: Cannot start Volumio Streaming Daemon Mar 29 15:29:17 volumio-pc volumio[734]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 29 15:29:17 volumio-pc volumio[734]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 29 15:29:17 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 29 15:29:17 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 29 15:29:17 volumio-pc volumio[734]: info: [1711722557651] Starting BluetoothController Mar 29 15:29:17 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 29 15:29:18 volumio-pc sudo[1632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 29 15:29:18 volumio-pc sudo[1632]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc sudo[1632]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: MultiRoom plugin initialized Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: STOPPING SNAPCLIENT Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: Snap server stop Mar 29 15:29:18 volumio-pc sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 29 15:29:18 volumio-pc sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: STOPPING volumioStreaming Mar 29 15:29:18 volumio-pc sudo[1648]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:18 volumio-pc sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 29 15:29:18 volumio-pc sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc sudo[1651]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:18 volumio-pc sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 29 15:29:18 volumio-pc sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc sudo[1654]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 29 15:29:18 volumio-pc sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 29 15:29:18 volumio-pc sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc sudo[1657]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 29 15:29:18 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 29 15:29:18 volumio-pc volumio[734]: info: Preparing to generate the ALSA configuration file Mar 29 15:29:18 volumio-pc volumio[734]: info: Updating MyVolumio device info Mar 29 15:29:18 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:18 volumio-pc volumio[734]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 29 15:29:18 volumio-pc volumio[734]: info: Reading ALSA contributions from plugins. Mar 29 15:29:18 volumio-pc volumio[734]: info: Setting Geolocation for MyVolumio to eu4 Mar 29 15:29:18 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:18 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:18 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: Removed streaming files Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: volumioStreaming STOPPED Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: SNAPSERVER STOPPED Mar 29 15:29:18 volumio-pc volumio[734]: info: MRS: SNAPCLIENT STOPPED Mar 29 15:29:18 volumio-pc volumio[734]: info: Asound.conf file written Mar 29 15:29:18 volumio-pc sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 15:29:18 volumio-pc sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc sudo[1660]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:18 volumio-pc volumio[734]: info: Output device has changed, restarting MPD Mar 29 15:29:18 volumio-pc sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 15:29:18 volumio-pc sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:18 volumio-pc sudo[1665]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:19 volumio-pc volumio[734]: info: Output device has changed, restarting Shairport Sync Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:19 volumio-pc sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 15:29:19 volumio-pc sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:19 volumio-pc systemd[1]: Stopping Music Player Daemon... Mar 29 15:29:19 volumio-pc volumio[734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 15:29:19 volumio-pc systemd[1]: mpd.service: Succeeded. Mar 29 15:29:19 volumio-pc systemd[1]: Stopped Music Player Daemon. Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output for this device Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:19 volumio-pc sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio PC Mar 29 15:29:19 volumio-pc sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:19 volumio-pc systemd[1]: Starting Music Player Daemon... Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding audio output: Mar 29 15:29:19 volumio-pc sudo[1677]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding METAVOLUMIO REST API Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding Manifest REST API Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 29 15:29:19 volumio-pc volumio[734]: info: Preparing CD Folders Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding CD REST API Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 29 15:29:19 volumio-pc volumio[734]: info: Starting UDEV Watcher for CD Mar 29 15:29:19 volumio-pc volumio[734]: info: Detecting CD presence with UDEV Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding inputs REST Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 29 15:29:19 volumio-pc volumio[734]: info: Scanning Audio Inputs Mar 29 15:29:19 volumio-pc volumio[734]: info: Checking against Known Cards name Mar 29 15:29:19 volumio-pc volumio[734]: info: Checking against Known Cards name Mar 29 15:29:19 volumio-pc volumio[734]: info: Checking against Known Cards name Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding Server instance for streaming Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 29 15:29:19 volumio-pc volumio[734]: error: Hi Res Audio Failed Login: Missing Login Data Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 29 15:29:19 volumio-pc volumio[734]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 29 15:29:19 volumio-pc sudo[1686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 29 15:29:19 volumio-pc sudo[1686]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:19 volumio-pc volumio[734]: info: Stopping AccessToken refresher cron Mar 29 15:29:19 volumio-pc sudo[1686]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:19 volumio-pc volumio[734]: info: AccessToken refresher cron started Mar 29 15:29:19 volumio-pc volumio[734]: info: Stopping AccessToken refresher cron for QOBUZ Mar 29 15:29:19 volumio-pc volumio[734]: info: AccessToken refresher cron started for QOBUZ Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding TIDAL REST API Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding QOBUZ REST API Endpoints Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 29 15:29:19 volumio-pc volumio[734]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Volumio PC' Mar 29 15:29:19 volumio-pc volumio[734]: Can't get device info: No such device Mar 29 15:29:19 volumio-pc volumio[734]: info: MPD Permissions set Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Adding multiroomSync output Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding audio output: Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:19 volumio-pc volumio[734]: info: Starting Shairport Sync Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Found cast device: B8405-83295070e909c325cce0845157749b40 Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding audio output: Mar 29 15:29:19 volumio-pc volumio[734]: info: MRS: Found cast device: TPM191E-012cfbfd74f63e765ed9fc81f32cce81 Mar 29 15:29:19 volumio-pc volumio[734]: info: Adding audio output: Mar 29 15:29:19 volumio-pc sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 15:29:19 volumio-pc sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:19 volumio-pc systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 15:29:19 volumio-pc systemd[1]: shairport-sync.service: Succeeded. Mar 29 15:29:19 volumio-pc systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 15:29:19 volumio-pc systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 15:29:19 volumio-pc sudo[1698]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:19 volumio-pc volumio[734]: info: Shairport-Sync Started Mar 29 15:29:19 volumio-pc volumio[734]: info: Successfully Added MyVolumio device Mar 29 15:29:19 volumio-pc mpd[1676]: Mar 29 15:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 15:29:19 volumio-pc volumio[734]: info: Successfully Updated MyVolumio device Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 15:29:19 volumio-pc volumio[734]: error: MPD error: Error: This socket has been ended by the other party Mar 29 15:29:19 volumio-pc volumio[734]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 29 15:29:19 volumio-pc volumio[734]: error: MPD error: Error: This socket has been ended by the other party Mar 29 15:29:19 volumio-pc volumio[734]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 29 15:29:19 volumio-pc volumio[734]: error: MPD error: Error: This socket has been ended by the other party Mar 29 15:29:19 volumio-pc volumio[734]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 29 15:29:19 volumio-pc volumio[734]: info: Updating MyVolumio device info Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:19 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:20 volumio-pc volumio[734]: info: Successfully Added MyVolumio device Mar 29 15:29:20 volumio-pc volumio[734]: info: Successfully Updated MyVolumio device Mar 29 15:29:20 volumio-pc systemd[1]: Started Music Player Daemon. Mar 29 15:29:20 volumio-pc sudo[1668]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:20 volumio-pc volumio[734]: error: updateQueue error: null Mar 29 15:29:21 volumio-pc systemd[1]: systemd-hostnamed.service: Succeeded. Mar 29 15:29:22 volumio-pc sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 29 15:29:22 volumio-pc sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:22 volumio-pc sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 29 15:29:22 volumio-pc sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:22 volumio-pc systemd[1]: Started Volumio Bluetooth Module. Mar 29 15:29:22 volumio-pc sudo[1712]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:22 volumio-pc systemd[1]: Started MyVolumio SSH Tunnel. Mar 29 15:29:22 volumio-pc sudo[1718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 29 15:29:22 volumio-pc sudo[1718]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:22 volumio-pc volumiobt[1715]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 29 15:29:22 volumio-pc volumio[734]: info: Volumio BT Module successfully started Mar 29 15:29:22 volumio-pc sudo[1718]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:22 volumio-pc sudo[1716]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:22 volumio-pc volumio[734]: info: Remote SSH Started Mar 29 15:29:22 volumio-pc autossh[1721]: port set to 0, monitoring disabled Mar 29 15:29:22 volumio-pc autossh[1721]: starting ssh (count 1) Mar 29 15:29:22 volumio-pc autossh[1721]: ssh child pid is 1725 Mar 29 15:29:22 volumio-pc volumio[734]: info: TidalConnect service stoped! Mar 29 15:29:22 volumio-pc volumio[734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Mar 29 15:29:22 volumio-pc volumio[734]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 29 15:29:22 volumio-pc volumio[734]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 29 15:29:22 volumio-pc volumiossh-tunnel[1719]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc sudo[1735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 29 15:29:22 volumio-pc sudo[1735]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:22 volumio-pc systemd[1]: Started Volumio Tidal Connect Service. Mar 29 15:29:22 volumio-pc sudo[1735]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 15:29:22 volumio-pc volumio[734]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: Executing endpoint tc_getconfig Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 29 15:29:22 volumio-pc vtcs[1737]: STARTING TidalConnect services, version: 1.3.0.19 Mar 29 15:29:22 volumio-pc vtcs[1737]: STARTED TidalConnect services. Mar 29 15:29:22 volumio-pc volumio[734]: info: Executing endpoint tc_connect Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 29 15:29:22 volumio-pc volumio[734]: info: Connecting to TidalConnect Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::servicePushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::pushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output update for this device Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 29 15:29:22 volumio-pc volumio[734]: verbose: CURRENT POSITION 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::syncState stateService stop Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::syncState currentStatus stop Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::pushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output update for this device Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:22 volumio-pc volumio[734]: info: No code Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::pushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output update for this device Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::servicePushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::pushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output update for this device Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Mar 29 15:29:22 volumio-pc volumio[734]: verbose: CURRENT POSITION 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::syncState stateService stop Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::syncState currentStatus stop Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::pushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output update for this device Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:22 volumio-pc volumio[734]: info: No code Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreStateMachine::pushState Mar 29 15:29:22 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:22 volumio-pc volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output update for this device Mar 29 15:29:22 volumio-pc volumio[734]: info: MRS: Pushing multiroomSync output Mar 29 15:29:22 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:22 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:22 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:22 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:22 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:22 volumio-pc volumio[734]: SPOTIFY: RECEIVED VOLUMIO VOLUME 50 Mar 29 15:29:23 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 15:29:23 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:23 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 15:29:23 volumio-pc sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 29 15:29:23 volumio-pc sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:29:23 volumio-pc volumiobt[1715]: pulseaudio: no process found Mar 29 15:29:23 volumio-pc sudo[1757]: pam_unix(sudo:session): session closed for user root Mar 29 15:29:23 volumio-pc dbus-daemon[487]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=1761 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 29 15:29:23 volumio-pc systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 29 15:29:23 volumio-pc volumio[734]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 15:29:23 volumio-pc dbus-daemon[487]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 29 15:29:23 volumio-pc systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 29 15:29:23 volumio-pc rtkit-daemon[1762]: Successfully called chroot. Mar 29 15:29:23 volumio-pc rtkit-daemon[1762]: Successfully dropped privileges. Mar 29 15:29:23 volumio-pc rtkit-daemon[1762]: Successfully limited resources. Mar 29 15:29:23 volumio-pc rtkit-daemon[1762]: Running. Mar 29 15:29:23 volumio-pc rtkit-daemon[1762]: Canary thread running. Mar 29 15:29:23 volumio-pc rtkit-daemon[1762]: Watchdog thread running. Mar 29 15:29:23 volumio-pc dbus-daemon[487]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=1762 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 29 15:29:23 volumio-pc systemd[1]: Starting Authorization Manager... Mar 29 15:29:23 volumio-pc volumio[734]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 29 15:29:23 volumio-pc polkitd[1765]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 29 15:29:23 volumio-pc dbus-daemon[487]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 29 15:29:23 volumio-pc systemd[1]: Started Authorization Manager. Mar 29 15:29:23 volumio-pc volumio-remote-updater[479]: No test mode Mar 29 15:29:23 volumio-pc volumio-remote-updater[479]: No alpha test mode Mar 29 15:29:23 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 29 15:29:23 volumio-pc volumio[734]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 29 15:29:23 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 29 15:29:23 volumio-pc pulseaudio[1761]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 29 15:29:23 volumio-pc pulseaudio[1761]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 29 15:29:23 volumio-pc volumiobt[1715]: Applying permissions Mar 29 15:29:23 volumio-pc volumiobt[1715]: Setting BT discoverable and pairable Mar 29 15:29:23 volumio-pc pulseaudio[1761]: 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 Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# -e power on Mar 29 15:29:23 volumio-pc volumiobt[1715]: Invalid command in menu main: -e Mar 29 15:29:23 volumio-pc volumiobt[1715]: Mar 29 15:29:23 volumio-pc volumiobt[1715]: Use "help" for a list of available commands in a menu. Mar 29 15:29:23 volumio-pc volumiobt[1715]: Use "menu " if you want to enter any submenu. Mar 29 15:29:23 volumio-pc volumiobt[1715]: Use "back" if you want to return to menu main. Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# agent on Mar 29 15:29:23 volumio-pc volumiobt[1715]: Failed to register agent object Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# discoverable on Mar 29 15:29:23 volumio-pc volumiobt[1715]: No default controller available Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# pairable on Mar 29 15:29:23 volumio-pc volumiobt[1715]: No default controller available Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# agent NoInputNoOutput Mar 29 15:29:23 volumio-pc volumiobt[1715]: Failed to register agent object Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# default-agent Mar 29 15:29:23 volumio-pc volumiobt[1715]: No agent is registered Mar 29 15:29:23 volumio-pc volumiobt[1715]: [bluetooth]# quit Mar 29 15:29:23 volumio-pc volumiobt[1715]: [59B blob data] Mar 29 15:29:23 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 15:29:24 volumio-pc volumio[734]: info: MRS: Getting audio outputs on start Mar 29 15:29:24 volumio-pc volumio[734]: info: MRS: Requesting all other devices output Mar 29 15:29:25 volumio-pc volumio[734]: info: TidalConnect service started! Mar 29 15:29:25 volumio-pc volumio[734]: info: BOOT COMPLETED Mar 29 15:29:25 volumio-pc volumio[734]: [Metrics] CommandRouter: 37s 367.51ms Mar 29 15:29:25 volumio-pc volumio[734]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 15:29:25 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:25 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:29:25 volumio-pc volumio[734]: info: CoreCommandRouter::Close All Modals sent Mar 29 15:29:25 volumio-pc volumio[734]: info: CoreCommandRouter::Close All Modals sent Mar 29 15:29:26 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 15:29:26 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 15:29:26 volumio-pc volumio[734]: No protocol specified Mar 29 15:29:26 volumio-pc volumio[734]: xcb_connection_has_error() returned true Mar 29 15:29:26 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:29:27 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 15:29:28 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:28 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:32 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 29 15:29:32 volumio-pc volumio[734]: info: CURURI: music-library Mar 29 15:29:32 volumio-pc volumio[734]: info: Preload queue cleared Mar 29 15:29:45 volumio-pc volumiologrotate[483]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 29 15:29:45 volumio-pc volumiologrotate[483]: ls: cannot access 'PC': No such file or directory Mar 29 15:29:47 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 29 15:29:47 volumio-pc volumio[734]: info: Preload queue cleared Mar 29 15:29:54 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:29:54 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:29:57 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:57+01:00" level=error msg="did not receive last pong from dealer, 30s passed" Mar 29 15:29:57 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:57+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\"" Mar 29 15:29:57 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:57+01:00" level=debug msg="re-established dealer connection" Mar 29 15:29:57 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:57+01:00" level=debug msg="received connection id: Y2UwMmM4MjctZDIxYi00YjdhLWJhNDQtZTI4MjE2NWFhMDBhK2RlYWxlcit0Y3A6Ly9ndWMzLWRlYWxlci1hLXM4anouZ3VjMy5zcG90aWZ5Lm5ldDo1NzAwKzhDMzE4QTlFQ0JBMkI2MDA5Nzc1MTQ1NTdGQTY1M0UxNzE0QTE2OERBOTk3N0FBM0VFMjhDQjUxMUU4QjBDRjU=" Mar 29 15:29:58 volumio-pc go-librespot[1481]: time="2024-03-29T15:29:58+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 29 15:30:02 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 15:30:02 volumio-pc volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:30:44 volumio-pc volumio[734]: info: Retrieving Cloud Streaming UI Mar 29 15:30:44 volumio-pc volumio[734]: info: Getting Tidal Cloud Configuration Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:30:44 volumio-pc volumio[734]: info: Getting Qobuz Cloud Configuration Mar 29 15:30:44 volumio-pc volumio[734]: info: Asking plugin for UI Config Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:30:44 volumio-pc volumio[734]: info: Getting Spotify Cloud Configuration Mar 29 15:30:44 volumio-pc volumio[734]: info: Asking plugin for UI Config Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:30:44 volumio-pc volumio[734]: info: Saving Spotify Acccount Mar 29 15:30:44 volumio-pc volumio[734]: info: Got it Mar 29 15:30:44 volumio-pc volumio[734]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 15:30:44 volumio-pc volumio[734]: info: Got Tidal Cloud Configuration Mar 29 15:30:44 volumio-pc volumio[734]: info: Got it Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:30:44 volumio-pc volumio[734]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 15:30:45 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 15:30:45 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 15:30:45 volumio-pc volumiologrotate[483]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 29 15:30:45 volumio-pc volumiologrotate[483]: ls: cannot access 'PC': No such file or directory Mar 29 15:30:49 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 15:30:50 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 29 15:30:50 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 29 15:30:52 volumio-pc volumio[734]: info: Error browsing device GBUE4K for shares : Error: Command failed: echo volumio | smbclient -L gbue4k.local. Mar 29 15:30:52 volumio-pc volumio[734]: Unable to initialize messaging context Mar 29 15:30:52 volumio-pc volumio[734]: info: Error browsing device 192-168-188-1 for shares : Error: Command failed: echo volumio | smbclient -L fritz.box. Mar 29 15:30:52 volumio-pc volumio[734]: Unable to initialize messaging context Mar 29 15:30:53 volumio-pc volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 15:30:55 volumio-pc volumio[734]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 15:30:55 volumio-pc volumio[734]: Error: dns service error: unknown Mar 29 15:30:55 volumio-pc volumio[734]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Mar 29 15:30:55 volumio-pc volumio[734]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Mar 29 15:30:55 volumio-pc volumio[734]: errorCode: -65537 Mar 29 15:30:55 volumio-pc volumio[734]: } Mar 29 15:30:55 volumio-pc volumio[734]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 15:30:55 volumio-pc sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 15:29 Mar 29 15:30:55 volumio-pc sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"