-- Logs begin at Fri 2025-09-05 17:46:34 EDT, end at Fri 2025-09-05 17:48:12 EDT. -- Sep 05 17:47:00 volumio-mac volumio[2101]: ........................++++ Sep 05 17:47:00 volumio-mac volumio[2101]: ..++++ Sep 05 17:47:00 volumio-mac volumio[2101]: e is 65537 (0x010001) Sep 05 17:47:00 volumio-mac volumio[2101]: writing RSA key Sep 05 17:47:00 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:00 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:01 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:02 volumio-mac volumio[1413]: Cannot compose Albumart path Sep 05 17:47:02 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:02 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:02 volumio-mac volumio[1413]: info: Getting Spotify volume Sep 05 17:47:02 volumio-mac volumio[1413]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 47 Sep 05 17:47:02 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:02 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:02 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Sep 05 17:47:02 volumio-mac volumio[1413]: SPOTIFY: SPOTIFY VOLUME undefined Sep 05 17:47:02 volumio-mac volumio[1413]: SPOTIFY: VOLUMIO VOLUME 86 Sep 05 17:47:02 volumio-mac volumio[1413]: info: Aligning Spotify Volume to Volumio Volume Sep 05 17:47:02 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:02 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:02 volumio-mac volumio[1413]: info: Setting Spotify Volume from Volumio: 86 Sep 05 17:47:02 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin bluetooth to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin multiroom to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin metavolumio to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin cd_controller to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 05 17:47:03 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:03 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:03 volumio-mac volumio[1413]: info: Starting MyVolumio Remote Streaming Endpoints Sep 05 17:47:03 volumio-mac volumio[1413]: info: MyVolumio login type: Token Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 05 17:47:03 volumio-mac volumio[1413]: info: Starting Streaming Service Transparent Proxy Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 05 17:47:03 volumio-mac volumio[1413]: info: Streaming services startup Sep 05 17:47:03 volumio-mac volumio[1413]: info: Starting Streaming Daemon Sep 05 17:47:03 volumio-mac sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 05 17:47:03 volumio-mac sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:03 volumio-mac sudo[2317]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:03 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 05 17:47:03 volumio-mac volumio[1413]: error: Cannot start Volumio Streaming Daemon Sep 05 17:47:03 volumio-mac volumio[1413]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 05 17:47:03 volumio-mac volumio[1413]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 05 17:47:04 volumio-mac volumio[1413]: SPOTIFY: SETTING SPOTIFY VOLUME 86 Sep 05 17:47:04 volumio-mac volumio[1413]: info: Sending Spotify command with payload to local API: /player/volume Sep 05 17:47:04 volumio-mac volumio[1413]: STREAMING PROXY: Starting server on port 3245 Sep 05 17:47:04 volumio-mac volumio[1413]: Node JS runtime: 14 Sep 05 17:47:04 volumio-mac volumio[1413]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 05 17:47:04 volumio-mac sudo[2324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 17:47:04 volumio-mac sudo[2324]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:04 volumio-mac sudo[2324]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:05 volumio-mac sudo[2327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 17:47:05 volumio-mac sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:05 volumio-mac sudo[2327]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:05 volumio-mac volumio[1413]: info: MyVolumio token set successfully Sep 05 17:47:05 volumio-mac volumio[1413]: info: MYVOLUMIO: Adding device Sep 05 17:47:05 volumio-mac volumio[1413]: info: MYVOLUMIO: Evaluating Server Sep 05 17:47:05 volumio-mac volumio[1413]: verbose: New Socket.io Connection to 192.168.50.21 from 192.168.50.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 47 Sep 05 17:47:05 volumio-mac systemd[1]: systemd-fsckd.service: Succeeded. Sep 05 17:47:06 volumio-mac volumio[1413]: info: MyVolumio Plan changed: premium Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 05 17:47:06 volumio-mac volumio[1413]: info: Removing browser output: myVolumio user plan is not superstar Sep 05 17:47:06 volumio-mac volumio[1413]: info: Removing audio output: Sep 05 17:47:06 volumio-mac volumio[1413]: info: MYVOLUMIO: Adding device Sep 05 17:47:06 volumio-mac volumio[1413]: info: MYVOLUMIO: Evaluating Server Sep 05 17:47:06 volumio-mac volumio[1413]: info: Remote config written successfully Sep 05 17:47:06 volumio-mac volumio[1413]: info: Starting Tunnel 1 Sep 05 17:47:06 volumio-mac volumio[1413]: info: Starting Tunnel Connection Checker Sep 05 17:47:06 volumio-mac volumio[1413]: info: Completed starting MyVolumio Plugin Sep 05 17:47:06 volumio-mac volumio[1413]: info: MYVolumio Device enabled Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 05 17:47:06 volumio-mac volumio[1413]: info: MyVolumio status changed Sep 05 17:47:06 volumio-mac volumio[1413]: info: Streaming services startup Sep 05 17:47:06 volumio-mac volumio[1413]: info: Starting Streaming Daemon Sep 05 17:47:06 volumio-mac sudo[2362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 05 17:47:06 volumio-mac sudo[2362]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:06 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 17:47:06 volumio-mac sudo[2362]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:06 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [1757108826463] Starting BluetoothController Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 05 17:47:06 volumio-mac sudo[2365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 05 17:47:06 volumio-mac sudo[2365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:06 volumio-mac sudo[2365]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:06 volumio-mac volumio[1413]: info: MRS: MultiRoom plugin initialized Sep 05 17:47:06 volumio-mac volumio[1413]: info: MRS: STOPPING SNAPCLIENT Sep 05 17:47:06 volumio-mac volumio[1413]: info: MRS: Snap server stop Sep 05 17:47:06 volumio-mac sudo[2381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 05 17:47:06 volumio-mac sudo[2381]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:06 volumio-mac sudo[2381]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:06 volumio-mac volumio[1413]: info: MRS: STOPPING volumioStreaming Sep 05 17:47:06 volumio-mac sudo[2384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 05 17:47:06 volumio-mac sudo[2384]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:06 volumio-mac sudo[2384]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:06 volumio-mac sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 05 17:47:06 volumio-mac sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:06 volumio-mac sudo[2387]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 05 17:47:06 volumio-mac sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:47:06 volumio-mac sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:06 volumio-mac sudo[2390]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 05 17:47:06 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Sep 05 17:47:07 volumio-mac volumio[1413]: info: Preparing to generate the ALSA configuration file Sep 05 17:47:07 volumio-mac volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 05 17:47:07 volumio-mac volumio[1413]: info: Reading ALSA contributions from plugins. Sep 05 17:47:07 volumio-mac volumio[1413]: info: Setting Geolocation for MyVolumio to us1 Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 17:47:07 volumio-mac volumio[1413]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 05 17:47:07 volumio-mac volumio[1413]: info: Received Get System Info Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 17:47:07 volumio-mac volumio[1413]: info: Discovery: Getting this device information Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: Listing playlists Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 17:47:07 volumio-mac volumio[1413]: info: Setting Geolocation for MyVolumio to us3 Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Removed streaming files Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: volumioStreaming STOPPED Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: SNAPSERVER STOPPED Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: SNAPCLIENT STOPPED Sep 05 17:47:07 volumio-mac volumio[1413]: error: Cannot start Volumio Streaming Daemon Sep 05 17:47:07 volumio-mac volumio[1413]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 05 17:47:07 volumio-mac volumio[1413]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: Asound.conf file written Sep 05 17:47:07 volumio-mac sudo[2393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 05 17:47:07 volumio-mac sudo[2393]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2393]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting MPD Sep 05 17:47:07 volumio-mac sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 17:47:07 volumio-mac sudo[2398]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2398]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting Shairport Sync Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 17:47:07 volumio-mac sudo[2401]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: musicservicesshield.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 05 17:47:07 volumio-mac systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 05 17:47:07 volumio-mac systemd[1]: Stopping Music Player Daemon... Sep 05 17:47:07 volumio-mac volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output for this device Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding METAVOLUMIO REST API Endpoints Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 05 17:47:07 volumio-mac volumio[1413]: error: Hi Res Audio Failed Login: Missing Login Data Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Sep 05 17:47:07 volumio-mac volumio[1413]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Sep 05 17:47:07 volumio-mac volumio[1413]: info: Stopping AccessToken refresher cron Sep 05 17:47:07 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 05 17:47:07 volumio-mac volumio[1413]: info: AccessToken refresher cron started Sep 05 17:47:07 volumio-mac volumio[1413]: info: Stopping AccessToken refresher cron for QOBUZ Sep 05 17:47:07 volumio-mac volumio[1413]: info: AccessToken refresher cron started for QOBUZ Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding TIDAL REST API Endpoints Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding QOBUZ REST API Endpoints Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 17:47:07 volumio-mac volumio[1413]: info: Received Get System Info Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 17:47:07 volumio-mac volumio[1413]: info: Discovery: Getting this device information Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 17:47:07 volumio-mac volumio[1413]: info: MPD Permissions set Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:07 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:07 volumio-mac sudo[2407]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 17:47:07 volumio-mac sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2407]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: Starting Shairport Sync Sep 05 17:47:07 volumio-mac volumio[1413]: info: Preparing to generate the ALSA configuration file Sep 05 17:47:07 volumio-mac sudo[2424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 17:47:07 volumio-mac sudo[2424]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 17:47:07 volumio-mac volumio[1413]: [Metrics] CommandRouter: 20s 495.83ms Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumiosetStartupVolume Sep 05 17:47:07 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::Close All Modals sent Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::Close All Modals sent Sep 05 17:47:07 volumio-mac volumio[1413]: info: Asound.conf file written Sep 05 17:47:07 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac sudo[2424]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 05 17:47:07 volumio-mac sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2440]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting MPD Sep 05 17:47:07 volumio-mac sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 17:47:07 volumio-mac sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2445]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting Shairport Sync Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 17:47:07 volumio-mac sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 05 17:47:07 volumio-mac volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: Preparing to generate the ALSA configuration file Sep 05 17:47:07 volumio-mac volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 05 17:47:07 volumio-mac volumio[1413]: info: Reading ALSA contributions from plugins. Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-3b93d2c63ebe2239c6d1f9ba8291d382 Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 05 17:47:07 volumio-mac volumio[1413]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 05 17:47:07 volumio-mac volumio[1413]: info: MPD Permissions set Sep 05 17:47:07 volumio-mac volumio[1413]: info: Shairport-Sync Started Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2451]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 17:47:07 volumio-mac sudo[2451]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2451]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Cast-Group-37d932be06f4422c9696d4e7c9994f3b-2 Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: Starting Shairport Sync Sep 05 17:47:07 volumio-mac sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 17:47:07 volumio-mac sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 17:47:07 volumio-mac volumio[1413]: info: Asound.conf file written Sep 05 17:47:07 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 05 17:47:07 volumio-mac sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2480]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: /usr/sbin/alsactl: set_control:1461: Cannot write control '3:3:0:Playback Channel Map:0' : Device or resource busy Sep 05 17:47:07 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting MPD Sep 05 17:47:07 volumio-mac sudo[2471]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac sudo[2487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 17:47:07 volumio-mac sudo[2487]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2487]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting Shairport Sync Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 17:47:07 volumio-mac sudo[2490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 05 17:47:07 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 05 17:47:07 volumio-mac volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: Preparing to generate the ALSA configuration file Sep 05 17:47:07 volumio-mac sudo[2493]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 17:47:07 volumio-mac sudo[2493]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac sudo[2493]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-54ffa577094904e457489c73f4b57e7a Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 05 17:47:07 volumio-mac volumio[1413]: info: Reading ALSA contributions from plugins. Sep 05 17:47:07 volumio-mac volumio[1413]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 05 17:47:07 volumio-mac volumio[1413]: info: MPD Permissions set Sep 05 17:47:07 volumio-mac volumio[1413]: info: Shairport-Sync Started Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Cast-Group-ee86723712514cb4a3fcd815fa05dd1b-1 Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: Starting Shairport Sync Sep 05 17:47:07 volumio-mac volumio[1413]: info: Asound.conf file written Sep 05 17:47:07 volumio-mac sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 17:47:07 volumio-mac sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 17:47:07 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac sudo[2533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 05 17:47:07 volumio-mac sudo[2533]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac volumio[1413]: /usr/sbin/alsactl: set_control:1461: Cannot write control '3:3:0:Playback Channel Map:0' : Device or resource busy Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting MPD Sep 05 17:47:07 volumio-mac sudo[2533]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac sudo[2538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 17:47:07 volumio-mac sudo[2538]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac sudo[2538]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac sudo[2528]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Output device has changed, restarting Shairport Sync Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 17:47:07 volumio-mac sudo[2541]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 17:47:07 volumio-mac systemd[1]: mpd.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Music Player Daemon. Sep 05 17:47:07 volumio-mac systemd[1]: Starting Music Player Daemon... Sep 05 17:47:07 volumio-mac volumio[1413]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac sudo[2546]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 17:47:07 volumio-mac sudo[2546]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-3eb5fb60e5f4e61d0b772fac2fa4f19e Sep 05 17:47:07 volumio-mac sudo[2546]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-fd1265f0e632bcb21505b598b2eae1fd Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: MPD Permissions set Sep 05 17:47:07 volumio-mac volumio[1413]: info: Shairport-Sync Started Sep 05 17:47:07 volumio-mac volumio[1413]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 17:47:07 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:07 volumio-mac volumio[1413]: info: Starting Shairport Sync Sep 05 17:47:07 volumio-mac sudo[2560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 17:47:07 volumio-mac sudo[2560]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:07 volumio-mac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 17:47:07 volumio-mac systemd[1]: shairport-sync.service: Succeeded. Sep 05 17:47:07 volumio-mac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 17:47:07 volumio-mac sudo[2560]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:07 volumio-mac volumio[1413]: info: Shairport-Sync Started Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-Max-f3cf83221fe4d3fb39fa89edf6ff584a Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:07 volumio-mac volumio[1413]: info: Successfully Added MyVolumio device Sep 05 17:47:07 volumio-mac volumio[1413]: info: MRS: Found cast device: Pixel-Tablet-70ba6b8e7f893198083e4d832f0bb4ff Sep 05 17:47:07 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac mpd[2552]: Sep 05 17:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 17:47:08 volumio-mac volumio[1413]: info: Updating MyVolumio device info Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:08 volumio-mac volumio[1413]: info: Updating MyVolumio device info Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: LenovoCD-24502F-ecbf0a106f10b946a0066ca34f4b51c9 Sep 05 17:47:08 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Mini-196be3396a0015f8b9eb2c059c2b869d Sep 05 17:47:08 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac volumio[1413]: info: Successfully Added MyVolumio device Sep 05 17:47:08 volumio-mac systemd[1]: Started Music Player Daemon. Sep 05 17:47:08 volumio-mac sudo[2541]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:08 volumio-mac sudo[2401]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:08 volumio-mac sudo[2448]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:08 volumio-mac sudo[2490]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:08 volumio-mac volumio[1413]: error: MPD error: The expression evaluated to a falsy value: Sep 05 17:47:08 volumio-mac volumio[1413]: assert.ok(self.idling) Sep 05 17:47:08 volumio-mac volumio[1413]: error: The expression evaluated to a falsy value: Sep 05 17:47:08 volumio-mac volumio[1413]: assert.ok(self.idling) Sep 05 17:47:08 volumio-mac volumio[1413]: error: MPD error: The expression evaluated to a falsy value: Sep 05 17:47:08 volumio-mac volumio[1413]: assert.ok(self.idling) Sep 05 17:47:08 volumio-mac volumio[1413]: error: The expression evaluated to a falsy value: Sep 05 17:47:08 volumio-mac volumio[1413]: assert.ok(self.idling) Sep 05 17:47:08 volumio-mac volumio[1413]: error: MPD error: The expression evaluated to a falsy value: Sep 05 17:47:08 volumio-mac volumio[1413]: assert.ok(self.idling) Sep 05 17:47:08 volumio-mac volumio[1413]: error: The expression evaluated to a falsy value: Sep 05 17:47:08 volumio-mac volumio[1413]: assert.ok(self.idling) Sep 05 17:47:08 volumio-mac volumio[1413]: error: updateQueue error: null Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-f8c84e624f98e0419e83510447955ad8 Sep 05 17:47:08 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Cast-Group-9635258fa0a64322b9033dd23393cb96 Sep 05 17:47:08 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 05 17:47:08 volumio-mac volumio[2567]: cset: --> shielding system active with Sep 05 17:47:08 volumio-mac volumio[2567]: cset: "system" cpuset of CPUSPEC(1-7) with 278 tasks running Sep 05 17:47:08 volumio-mac volumio[2567]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 05 17:47:08 volumio-mac systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Home-988819c0e477822663895fb87b585bf4 Sep 05 17:47:08 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-Audio-41b73146d74a7d591045e6387e4208d0 Sep 05 17:47:08 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-3b93d2c63ebe2239c6d1f9ba8291d382 Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 17:47:08 volumio-mac volumio[1413]: info: Received Get System Info Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 17:47:08 volumio-mac volumio[1413]: info: Discovery: Getting this device information Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:08 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:08 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: LenovoCD-24502F-ecbf0a106f10b946a0066ca34f4b51c9 Sep 05 17:47:08 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-54ffa577094904e457489c73f4b57e7a Sep 05 17:47:09 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Cast-Group-ee86723712514cb4a3fcd815fa05dd1b-1 Sep 05 17:47:09 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Cast-Group-37d932be06f4422c9696d4e7c9994f3b-2 Sep 05 17:47:09 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-3eb5fb60e5f4e61d0b772fac2fa4f19e Sep 05 17:47:09 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-Max-f3cf83221fe4d3fb39fa89edf6ff584a Sep 05 17:47:09 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Hub-fd1265f0e632bcb21505b598b2eae1fd Sep 05 17:47:09 volumio-mac volumio[1413]: info: MRS: Found cast device: Pixel-Tablet-70ba6b8e7f893198083e4d832f0bb4ff Sep 05 17:47:10 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Nest-Mini-196be3396a0015f8b9eb2c059c2b869d Sep 05 17:47:10 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-f8c84e624f98e0419e83510447955ad8 Sep 05 17:47:10 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Cast-Group-9635258fa0a64322b9033dd23393cb96 Sep 05 17:47:10 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Home-ba0ae58995b8f14f6c9acc1d46a5d6f8 Sep 05 17:47:10 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:10 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Home-988819c0e477822663895fb87b585bf4 Sep 05 17:47:10 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-Audio-41b73146d74a7d591045e6387e4208d0 Sep 05 17:47:11 volumio-mac sudo[2591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 05 17:47:11 volumio-mac sudo[2591]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:11 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-HD-b7d70e394f4a202f79a7c1146b18af00 Sep 05 17:47:11 volumio-mac volumio[1413]: info: Adding audio output: Sep 05 17:47:11 volumio-mac systemd[1]: Started MyVolumio SSH Tunnel. Sep 05 17:47:11 volumio-mac sudo[2591]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:11 volumio-mac volumio[1413]: info: Remote SSH Started Sep 05 17:47:11 volumio-mac autossh[2594]: port set to 0, monitoring disabled Sep 05 17:47:11 volumio-mac autossh[2594]: starting ssh (count 1) Sep 05 17:47:11 volumio-mac autossh[2594]: ssh child pid is 2597 Sep 05 17:47:11 volumio-mac volumio[1413]: info: MRS: Found cast device: Chromecast-HD-b7d70e394f4a202f79a7c1146b18af00 Sep 05 17:47:11 volumio-mac volumio[1413]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 48 Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:11 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:11 volumio-mac volumio[1413]: info: Successfully Updated MyVolumio device Sep 05 17:47:11 volumio-mac volumio[1413]: info: Successfully Updated MyVolumio device Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 17:47:11 volumio-mac volumio[1413]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 05 17:47:11 volumio-mac volumiossh-tunnel[2593]: Warning: Permanently added '[us3.myvolumio.org]:2222,[159.203.57.91]:2222' (RSA) to the list of known hosts. Sep 05 17:47:11 volumio-mac volumio[1413]: info: Received Get System Version Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 17:47:11 volumio-mac volumio[1413]: info: Received Get System Info Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 17:47:11 volumio-mac volumio[1413]: info: Discovery: Getting this device information Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:11 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:11 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 17:47:12 volumio-mac volumio[1413]: info: MRS: Getting audio outputs on start Sep 05 17:47:12 volumio-mac volumio[1413]: info: MRS: Requesting all other devices output Sep 05 17:47:13 volumio-mac volumio[1413]: info: MRS: Found cast device: Google-Home-ba0ae58995b8f14f6c9acc1d46a5d6f8 Sep 05 17:47:14 volumio-mac volumio[1413]: info: BOOT COMPLETED Sep 05 17:47:15 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 17:47:16 volumio-mac systemd[1]: systemd-timedated.service: Succeeded. Sep 05 17:47:17 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: Starting cast device: Google-Cast-Group-9635258fa0a64322b9033dd23393cb96 Sep 05 17:47:17 volumio-mac volumio[1413]: info: Enabled audio output: Google-Cast-Group-9635258fa0a64322b9033dd23393cb96 Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: Setting this device as Streaming Server Sep 05 17:47:17 volumio-mac volumio[1413]: info: Sep 05 17:47:17 volumio-mac volumio[1413]: [1757108837009] ---------------------------- MRS: Setting Streaming Server Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: enable multiroom server output Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: Set multiroom target PCM to volumioMultiRoom Sep 05 17:47:17 volumio-mac volumio[1413]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Sep 05 17:47:17 volumio-mac volumio[1413]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: STARTING volumioStreaming Sep 05 17:47:17 volumio-mac sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:47:17 volumio-mac sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:17 volumio-mac sudo[2617]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:17 volumio-mac sudo[2620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Sep 05 17:47:17 volumio-mac sudo[2620]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:17 volumio-mac systemd[1]: Started VolumioStreamingService. Sep 05 17:47:17 volumio-mac sudo[2620]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:17 volumio-mac volumio[1413]: info: MRS: volumioStreaming STARTED Sep 05 17:47:17 volumio-mac sudo[2623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:47:17 volumio-mac sudo[2623]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:17 volumio-mac sudo[2623]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:18 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 17:47:18 volumio-mac volumio[1413]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 49 Sep 05 17:47:18 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:18 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:21 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 17:47:21 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:21 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 17:47:21 volumio-mac volumio-remote-updater[905]: No test mode Sep 05 17:47:21 volumio-mac volumio-remote-updater[905]: No alpha test mode Sep 05 17:47:21 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 05 17:47:22 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:22 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:22 volumio-mac volumio[1413]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 05 17:47:22 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 17:47:23 volumio-mac volumio[1413]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 05 17:47:23 volumio-mac volumio[1413]: info: Received Get System Version Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 17:47:23 volumio-mac volumio[1413]: info: Received Get System Info Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 17:47:23 volumio-mac volumio[1413]: info: Discovery: Getting this device information Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:23 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetBrowseSources Sep 05 17:47:23 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 17:47:24 volumio-mac volumio[1413]: info: VolumeController::SetAlsaVolume91 Sep 05 17:47:24 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:24 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:24 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:24 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:24 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 17:47:24 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:24 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:24 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:24 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:24 volumio-mac volumio[1413]: SPOTIFY: SPOTIFY VOLUME 86 Sep 05 17:47:24 volumio-mac volumio[1413]: SPOTIFY: VOLUMIO VOLUME 91 Sep 05 17:47:24 volumio-mac volumio[1413]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 05 17:47:24 volumio-mac volumio[1413]: info: Setting Spotify Volume from Volumio: 91 Sep 05 17:47:25 volumio-mac volumio[1413]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 05 17:47:26 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 17:47:26 volumio-mac volumio[1413]: SPOTIFY: SETTING SPOTIFY VOLUME 91 Sep 05 17:47:26 volumio-mac volumio[1413]: info: Sending Spotify command with payload to local API: /player/volume Sep 05 17:47:27 volumio-mac volumio[1413]: info: MRS: STARTING volumioStreaming Sep 05 17:47:27 volumio-mac sudo[2649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:47:27 volumio-mac sudo[2649]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:27 volumio-mac sudo[2649]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:27 volumio-mac sudo[2652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Sep 05 17:47:27 volumio-mac sudo[2652]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:27 volumio-mac systemd[1]: Stopping VolumioStreamingService... Sep 05 17:47:27 volumio-mac systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Sep 05 17:47:27 volumio-mac systemd[1]: volumioStreaming.service: Succeeded. Sep 05 17:47:27 volumio-mac systemd[1]: Stopped VolumioStreamingService. Sep 05 17:47:27 volumio-mac systemd[1]: Started VolumioStreamingService. Sep 05 17:47:27 volumio-mac sudo[2652]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:27 volumio-mac volumio[1413]: info: MRS: volumioStreaming STARTED Sep 05 17:47:27 volumio-mac sudo[2655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:47:27 volumio-mac sudo[2655]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:27 volumio-mac sudo[2655]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:30 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:30 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPlay Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreStateMachine::play index undefined Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 17:47:32 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreStateMachine::startPlaybackTimer Sep 05 17:47:32 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 17:47:32 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 05 17:47:32 volumio-mac volumio[1413]: info: [1757108852511] ControllerWebradio::clearAddPlayTrack Sep 05 17:47:32 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 17:47:32 volumio-mac volumio[1413]: info: sendMpdCommand stop took 2 milliseconds Sep 05 17:47:32 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 17:47:32 volumio-mac volumio[1413]: info: Sep 05 17:47:32 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:32 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:32 volumio-mac volumio[1413]: info: sendMpdCommand clear took 2 milliseconds Sep 05 17:47:32 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand load "https://dancewave.online/dance.flac.ogg.pls" Sep 05 17:47:32 volumio-mac volumio[1413]: info: Sep 05 17:47:32 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:32 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:32 volumio-mac volumio[1413]: info: Sep 05 17:47:32 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:32 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:32 volumio-mac volumio[1413]: info: Sep 05 17:47:32 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:32 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:32 volumio-mac volumio[1413]: error: updateQueue error: null Sep 05 17:47:32 volumio-mac volumio[1413]: info: ------------------------------ 4ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: Sep 05 17:47:33 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:33 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:33 volumio-mac volumio[1413]: info: sendMpdCommand load "https://dancewave.online/dance.flac.ogg.pls" took 1455 milliseconds Sep 05 17:47:33 volumio-mac volumio[1413]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 17:47:33 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand play Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 1456ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 1456ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 1455ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 4ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: Sep 05 17:47:33 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:33 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:33 volumio-mac volumio[1413]: info: Sep 05 17:47:33 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:33 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:33 volumio-mac volumio[1413]: info: Sep 05 17:47:33 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:33 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:33 volumio-mac volumio[1413]: info: sendMpdCommand play took 6 milliseconds Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 4ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 3ms Sep 05 17:47:33 volumio-mac volumio[1413]: info: ------------------------------ 3ms Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 3 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 3 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 4 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 4 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 3 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus stop Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:35 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: ------------------------------ 29ms Sep 05 17:47:35 volumio-mac volumio[1413]: info: ------------------------------ 51ms Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: Sep 05 17:47:35 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 50 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 49 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 49 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 4 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 4 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: info: sendMpdCommand status took 2 milliseconds Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:35 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:35 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:35 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:35 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:35 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 91ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 92ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 90ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Dance Wave FLAC Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Dance Wave FLAC Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 54 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 53 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 53 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"http://dancewave.online/dance.flac.ogg","artist":null,"album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 90ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 89ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 87ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Dance Wave FLAC Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] Current track does not have sufficient metadata: Missing artist. Not a composite title! Dance Wave FLAC Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces system playlist update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Ignoring MPD Status Update Sep 05 17:47:36 volumio-mac volumio[1413]: info: Sep 05 17:47:36 volumio-mac volumio[1413]: ---------------------------- MPD announces state update: player Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::getState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand status Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 3ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand status took 2 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 37ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand status took 38 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 37ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand status took 37 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 37ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand status took 37 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 37 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseState Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 65ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: ControllerMpd::parseTrackInfo Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":978,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ControllerMpd::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::servicePushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CorePlayQueue::getTrack 0 Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dance Wave!","artist":"Dance Wave!","album":null,"uri":"http://dancewave.online/dance.flac.ogg","trackType":"ogg"} Sep 05 17:47:36 volumio-mac volumio[1413]: verbose: CURRENT POSITION 0 Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState stateService play Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::syncState currentStatus play Sep 05 17:47:36 volumio-mac volumio[1413]: info: Received an update from plugin. extracting info from payload Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreStateMachine::pushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioPushState Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output update for this device Sep 05 17:47:36 volumio-mac volumio[1413]: info: MRS: Pushing multiroomSync output Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 124ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 123ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: ------------------------------ 124ms Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] Current track has sufficient metadata: title (Dance Wave!) and artist (Dance Wave!) passed on explicitly Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Dance Wave!","url":"https://www.last.fm/music/Dance+Wave%21/_/Dance+Wave%21","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"5","playcount":"69","artist":{"name":"Dance Wave!","url":"https://www.last.fm/music/Dance+Wave%21"},"userplaycount":"1","userloved":"0","toptags":""}} Sep 05 17:47:36 volumio-mac volumio[1413]: info: [LastFM] Updated missing track duration: 0 Sep 05 17:47:37 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:47:38 volumio-mac volumio[1413]: info: Executing endpoint metavolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: Executing endpoint metavolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: Executing endpoint metavolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: Executing endpoint metavolumio Sep 05 17:47:38 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 05 17:47:49 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: multiroom , disableAudioOutput Sep 05 17:47:49 volumio-mac volumio[1413]: info: MRS: Setting this device as single Sep 05 17:47:49 volumio-mac volumio[1413]: info: Sep 05 17:47:49 volumio-mac volumio[1413]: [1757108869535] ---------------------------- Setting Multiroom Single Sep 05 17:47:49 volumio-mac volumio[1413]: info: MRS: disable multiroom output Sep 05 17:47:49 volumio-mac volumio[1413]: info: MRS: Set multiroom target PCM to volumioDiscard Sep 05 17:47:49 volumio-mac volumio[1413]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Sep 05 17:47:50 volumio-mac volumio[1413]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Sep 05 17:47:50 volumio-mac volumio[1413]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioLocalPlayback Sep 05 17:47:50 volumio-mac volumio[1413]: info: MRS: STOPPING volumioStreaming Sep 05 17:47:50 volumio-mac sudo[2782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 05 17:47:50 volumio-mac sudo[2782]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:51 volumio-mac systemd[1]: Stopping VolumioStreamingService... Sep 05 17:47:51 volumio-mac systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Sep 05 17:47:51 volumio-mac systemd[1]: volumioStreaming.service: Succeeded. Sep 05 17:47:51 volumio-mac systemd[1]: Stopped VolumioStreamingService. Sep 05 17:47:51 volumio-mac sudo[2785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream9.ts Sep 05 17:47:51 volumio-mac sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:47:51 volumio-mac sudo[2785]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:51 volumio-mac sudo[2782]: pam_unix(sudo:session): session closed for user root Sep 05 17:47:51 volumio-mac volumio[1413]: info: MRS: Removed streaming files Sep 05 17:47:51 volumio-mac volumio[1413]: info: MRS: volumioStreaming STOPPED Sep 05 17:47:52 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:48:07 volumio-mac volumio[1413]: info: CoreCommandRouter::volumioGetState Sep 05 17:48:12 volumio-mac volumio[1413]: info: CoreCommandRouter::executeOnPlugin: multiroom , enableAudioOutput Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: Starting cast device: Google-Home-988819c0e477822663895fb87b585bf4 Sep 05 17:48:12 volumio-mac volumio[1413]: info: Enabled audio output: Google-Home-988819c0e477822663895fb87b585bf4 Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: Setting this device as Streaming Server Sep 05 17:48:12 volumio-mac volumio[1413]: info: Sep 05 17:48:12 volumio-mac volumio[1413]: [1757108892695] ---------------------------- MRS: Setting Streaming Server Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: enable multiroom server output Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: Set multiroom target PCM to volumioMultiRoom Sep 05 17:48:12 volumio-mac volumio[1413]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioMultiRoom Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Sep 05 17:48:12 volumio-mac volumio[1413]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: STARTING volumioStreaming Sep 05 17:48:12 volumio-mac sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:48:12 volumio-mac sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:48:12 volumio-mac sudo[2828]: pam_unix(sudo:session): session closed for user root Sep 05 17:48:12 volumio-mac sudo[2831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Sep 05 17:48:12 volumio-mac sudo[2831]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: Connected to cast device: Google-Home-988819c0e477822663895fb87b585bf4 Sep 05 17:48:12 volumio-mac systemd[1]: Started VolumioStreamingService. Sep 05 17:48:12 volumio-mac sudo[2831]: pam_unix(sudo:session): session closed for user root Sep 05 17:48:12 volumio-mac volumio[1413]: info: MRS: volumioStreaming STARTED Sep 05 17:48:12 volumio-mac sudo[2834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 05 17:48:12 volumio-mac sudo[2834]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 17:48:12 volumio-mac sudo[2834]: pam_unix(sudo:session): session closed for user root Sep 05 17:48:12 volumio-mac volumio[1413]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 17:48:12 volumio-mac volumio[1413]: TypeError: Cannot read property 'on' of undefined Sep 05 17:48:12 volumio-mac volumio[1413]: at /myvolumio/plugins/audio_interface/multiroom/multiroom_real:1:29834 Sep 05 17:48:12 volumio-mac volumio[1413]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/senders/platform.js:95:20 Sep 05 17:48:12 volumio-mac volumio[1413]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/receiver.js:51:14 Sep 05 17:48:12 volumio-mac volumio[1413]: at fn.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/request-response.js:27:7) Sep 05 17:48:12 volumio-mac volumio[1413]: at fn.emit (events.js:412:35) Sep 05 17:48:12 volumio-mac volumio[1413]: at Channel.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2-client/lib/controllers/controller.js:16:10) Sep 05 17:48:12 volumio-mac volumio[1413]: at Channel.emit (events.js:400:28) Sep 05 17:48:12 volumio-mac volumio[1413]: at Client.onmessage (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/channel.js:23:10) Sep 05 17:48:12 volumio-mac volumio[1413]: at Client.emit (events.js:412:35) Sep 05 17:48:12 volumio-mac volumio[1413]: at PacketStreamWrapper.onpacket (/myvolumio/plugins/audio_interface/multiroom/node_modules/castv2/lib/client.js:81:10) Sep 05 17:48:12 volumio-mac volumio[1413]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 17:48:12 volumio-mac sudo[2846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-05 17:47 Sep 05 17:48:12 volumio-mac sudo[2846]: 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="95d9088e5dcb3a55aabfe7f209b9ae00946f3ec5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Aug 2025 06:37:19 PM CEST" VOLUMIO_VERSION="3.839" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="3616d4b5f30ddd7d34bf1bdb3bca86e3"