-- Logs begin at Thu 2019-02-14 14:11:59 +04, end at Thu 2024-11-28 15:37:30 +04. -- Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 28 15:36:00 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 28 15:36:00 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:00 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:00 volumio volumio[1105]: info: MRS: MultiRoom plugin initialized Nov 28 15:36:00 volumio volumio[1105]: info: MRS: STOPPING SNAPCLIENT Nov 28 15:36:00 volumio volumio[1105]: info: MRS: Snap server stop Nov 28 15:36:00 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 28 15:36:00 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:00 volumio volumio[1105]: info: MRS: STOPPING volumioStreaming Nov 28 15:36:00 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 28 15:36:00 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:00 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 28 15:36:00 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:00 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:00 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:00 volumio sudo[1521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 28 15:36:00 volumio sudo[1521]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:00 volumio sudo[1521]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:00 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 28 15:36:00 volumio volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 28 15:36:00 volumio volumio[1105]: info: Preparing to generate the ALSA configuration file Nov 28 15:36:00 volumio volumio[1105]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 28 15:36:00 volumio volumio[1105]: info: Reading ALSA contributions from plugins. Nov 28 15:36:00 volumio volumio[1105]: info: Setting Geolocation for MyVolumio to eu2 Nov 28 15:36:00 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:00 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:00 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:00 volumio volumio[1105]: info: MRS: Removed streaming files Nov 28 15:36:00 volumio volumio[1105]: info: MRS: volumioStreaming STOPPED Nov 28 15:36:00 volumio volumio[1105]: info: MRS: SNAPSERVER STOPPED Nov 28 15:36:00 volumio volumio[1105]: info: MRS: SNAPCLIENT STOPPED Nov 28 15:36:00 volumio volumio[1105]: error: Cannot start Volumio Streaming Daemon Nov 28 15:36:00 volumio volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 28 15:36:00 volumio volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 28 15:36:00 volumio volumio[1105]: info: Asound.conf file written Nov 28 15:36:01 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 28 15:36:01 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:01 volumio volumio[1105]: info: Output device has changed, restarting MPD Nov 28 15:36:01 volumio sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 28 15:36:01 volumio volumio[1105]: info: Output device has changed, restarting Shairport Sync Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:01 volumio sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio sudo[1532]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:01 volumio sudo[1534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 28 15:36:01 volumio sudo[1534]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio systemd[1]: Stopping Music Player Daemon... Nov 28 15:36:01 volumio volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 28 15:36:01 volumio volumio[1105]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 28 15:36:01 volumio systemd[1]: mpd.service: Succeeded. Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 28 15:36:01 volumio systemd[1]: Stopped Music Player Daemon. Nov 28 15:36:01 volumio volumio[1105]: info: MRS: Pushing multiroomSync output for this device Nov 28 15:36:01 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:01 volumio volumio[1105]: info: Adding audio output: Nov 28 15:36:01 volumio volumio[1105]: info: Adding audio output: Nov 28 15:36:01 volumio sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Nov 28 15:36:01 volumio sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio volumio[1105]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 28 15:36:01 volumio volumio[1105]: info: Adding METAVOLUMIO REST API Endpoints Nov 28 15:36:01 volumio volumio[1105]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 28 15:36:01 volumio volumio[1105]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 28 15:36:01 volumio volumio[1105]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 28 15:36:01 volumio volumio[1105]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 28 15:36:01 volumio volumio[1105]: info: Preparing CD Folders Nov 28 15:36:01 volumio volumio[1105]: info: Adding CD REST API Endpoints Nov 28 15:36:01 volumio volumio[1105]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 28 15:36:01 volumio volumio[1105]: info: Starting UDEV Watcher for CD Nov 28 15:36:01 volumio volumio[1105]: info: Detecting CD presence with UDEV Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 28 15:36:01 volumio volumio[1105]: info: Adding inputs REST Endpoints Nov 28 15:36:01 volumio volumio[1105]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 28 15:36:01 volumio volumio[1105]: info: Scanning Audio Inputs Nov 28 15:36:01 volumio systemd[1]: Starting Music Player Daemon... Nov 28 15:36:01 volumio sudo[1543]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:01 volumio volumio[1105]: info: Checking against Known Cards name Nov 28 15:36:01 volumio volumio[1105]: info: Adding Server instance for streaming Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 28 15:36:01 volumio volumio[1105]: error: Hi Res Audio Failed Login: Missing Login Data Nov 28 15:36:01 volumio volumio[1105]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 28 15:36:01 volumio volumio[1105]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 28 15:36:01 volumio volumio[1105]: info: Refreshing TIDAL token Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 28 15:36:01 volumio volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 28 15:36:01 volumio sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 28 15:36:01 volumio volumio[1105]: info: Stopping AccessToken refresher cron for QOBUZ Nov 28 15:36:01 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio sudo[1544]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 28 15:36:01 volumio sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio sudo[1544]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:01 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:01 volumio volumio[1105]: info: AccessToken refresher cron started for QOBUZ Nov 28 15:36:01 volumio volumio[1105]: info: Adding QOBUZ REST API Endpoints Nov 28 15:36:01 volumio volumio[1105]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 28 15:36:01 volumio volumio[1105]: info: Bluetooth name changed to Volumio Nov 28 15:36:01 volumio volumio[1105]: info: MPD Permissions set Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: Starting Shairport Sync Nov 28 15:36:01 volumio sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 28 15:36:01 volumio sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 28 15:36:01 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 28 15:36:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 28 15:36:01 volumio sudo[1562]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 28 15:36:01 volumio volumio[1105]: info: Shairport-Sync Started Nov 28 15:36:01 volumio volumio[1105]: info: Updating MyVolumio device info Nov 28 15:36:01 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:01 volumio volumio[1105]: info: Access Token successfully retrieved Nov 28 15:36:01 volumio mpd[1555]: Nov 28 15:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 28 15:36:01 volumio systemd[1]: Started Music Player Daemon. Nov 28 15:36:01 volumio sudo[1534]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:02 volumio volumio[1105]: error: updateQueue error: null Nov 28 15:36:02 volumio volumio[1105]: info: Successfully retrieved User Session From TIDAL Nov 28 15:36:02 volumio volumio[1105]: info: Successfully Updated MyVolumio device Nov 28 15:36:03 volumio volumio[1105]: info: Successfully retrieved User Subscription From TIDAL Nov 28 15:36:03 volumio volumio[1105]: info: Adding TIDAL to Browse Sources Nov 28 15:36:03 volumio volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 28 15:36:03 volumio volumio[1105]: info: [1732793763067] CoreMusicLibrary::Adding element TIDAL Nov 28 15:36:03 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 28 15:36:03 volumio volumio[1105]: Cannot find translation for source TIDAL Nov 28 15:36:03 volumio volumio[1105]: info: Stopping AccessToken refresher cron Nov 28 15:36:03 volumio volumio[1105]: info: AccessToken refresher cron started Nov 28 15:36:03 volumio volumio[1105]: info: Adding TIDAL REST API Endpoints Nov 28 15:36:03 volumio volumio[1105]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 28 15:36:03 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 28 15:36:03 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:04 volumio systemd[1]: Started MyVolumio SSH Tunnel. Nov 28 15:36:04 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:04 volumio volumio[1105]: info: Remote SSH Started Nov 28 15:36:04 volumio autossh[1615]: port set to 0, monitoring disabled Nov 28 15:36:04 volumio autossh[1615]: starting ssh (count 1) Nov 28 15:36:04 volumio autossh[1615]: ssh child pid is 1618 Nov 28 15:36:04 volumio volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 28 15:36:04 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:04 volumio sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 28 15:36:04 volumio sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:04 volumio volumio[1105]: info: Setting Geolocation for MyVolumio to eu2 Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:04 volumio systemd[1]: Started Volumio Bluetooth Module. Nov 28 15:36:04 volumio sudo[1622]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:04 volumio volumio[1105]: info: Volumio BT Module successfully started Nov 28 15:36:04 volumio sudo[1625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 28 15:36:04 volumio sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:04 volumio volumiobt[1624]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 28 15:36:04 volumio sudo[1625]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:04 volumio volumio[1105]: info: TidalConnect service stoped! Nov 28 15:36:04 volumio volumio[1105]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 28 15:36:04 volumio volumio[1105]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 28 15:36:04 volumio sudo[1637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 28 15:36:04 volumio sudo[1637]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:04 volumio volumiossh-tunnel[1614]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Nov 28 15:36:04 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 28 15:36:04 volumio sudo[1637]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:04 volumio volumio[1105]: info: Executing endpoint tc_getconfig Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 28 15:36:04 volumio vtcs[1639]: STARTING TidalConnect services, version: 1.4.0.34 Nov 28 15:36:04 volumio vtcs[1639]: STARTED TidalConnect services. Nov 28 15:36:04 volumio volumio[1105]: info: Executing endpoint tc_connect Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 28 15:36:04 volumio volumio[1105]: info: Connecting to TidalConnect Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:04 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:04 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:04 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:04 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:04 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:04 volumio volumio[1105]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:04 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:04 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:04 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:04 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:04 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:04 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:04 volumio volumio[1105]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 28 15:36:05 volumio volumio[1105]: info: Successfully Added MyVolumio device Nov 28 15:36:05 volumio volumio[1105]: info: Updating MyVolumio device info Nov 28 15:36:05 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:05 volumio sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 28 15:36:05 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 28 15:36:05 volumio volumiobt[1624]: pulseaudio: no process found Nov 28 15:36:05 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Nov 28 15:36:05 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1664 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 28 15:36:05 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 28 15:36:05 volumio volumio[1105]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 15:36:05 volumio dbus-daemon[665]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 28 15:36:05 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 28 15:36:05 volumio rtkit-daemon[1665]: Successfully called chroot. Nov 28 15:36:05 volumio rtkit-daemon[1665]: Successfully dropped privileges. Nov 28 15:36:05 volumio rtkit-daemon[1665]: Successfully limited resources. Nov 28 15:36:05 volumio rtkit-daemon[1665]: Running. Nov 28 15:36:05 volumio rtkit-daemon[1665]: Canary thread running. Nov 28 15:36:05 volumio rtkit-daemon[1665]: Watchdog thread running. Nov 28 15:36:05 volumio dbus-daemon[665]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1665 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 28 15:36:05 volumio systemd[1]: Starting Authorization Manager... Nov 28 15:36:05 volumio volumio[1105]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 28 15:36:05 volumio polkitd[1669]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 28 15:36:05 volumio dbus-daemon[665]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 28 15:36:05 volumio systemd[1]: Started Authorization Manager. Nov 28 15:36:05 volumio pulseaudio[1664]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 28 15:36:05 volumio pulseaudio[1664]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 28 15:36:05 volumio volumiobt[1624]: Applying permissions Nov 28 15:36:05 volumio volumiobt[1624]: Setting BT discoverable and pairable Nov 28 15:36:05 volumio bluetoothd[743]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Nov 28 15:36:05 volumio pulseaudio[1664]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 28 15:36:05 volumio bluetoothd[743]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Nov 28 15:36:05 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Nov 28 15:36:05 volumio kernel: Bluetooth: RFCOMM socket layer initialized Nov 28 15:36:05 volumio kernel: Bluetooth: RFCOMM ver 1.11 Nov 28 15:36:05 volumio volumiobt[1624]: [CHG] Controller D8:3A:DD:12:06:6C UUIDs: 00001112-0000-1000-8000-00805f9b34fb Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [91B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [55B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [117B blob data] Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# -e power on Nov 28 15:36:05 volumio volumiobt[1624]: Invalid command in menu main: -e Nov 28 15:36:05 volumio volumiobt[1624]: Nov 28 15:36:05 volumio volumiobt[1624]: Use "help" for a list of available commands in a menu. Nov 28 15:36:05 volumio volumiobt[1624]: Use "menu " if you want to enter any submenu. Nov 28 15:36:05 volumio volumiobt[1624]: Use "back" if you want to return to menu main. Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# agent on Nov 28 15:36:05 volumio volumiobt[1624]: Agent is already registered Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# discoverable on Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# pairable on Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# agent NoInputNoOutput Nov 28 15:36:05 volumio volumiobt[1624]: Agent is already registered Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# default-agent Nov 28 15:36:05 volumio volumiobt[1624]: [bluetooth]# quit Nov 28 15:36:05 volumio volumiobt[1624]: [59B blob data] Nov 28 15:36:06 volumio volumio[1105]: info: MRS: Getting audio outputs on start Nov 28 15:36:06 volumio volumio[1105]: info: MRS: Requesting all other devices output Nov 28 15:36:06 volumio volumio[1105]: info: Successfully Updated MyVolumio device Nov 28 15:36:06 volumio volumio[1105]: info: Successfully Added MyVolumio device Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 15:36:06 volumio volumio[1105]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 28 15:36:06 volumio volumio[1105]: info: Received Get System Version Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 15:36:06 volumio volumio[1105]: info: Received Get System Info Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 15:36:06 volumio volumio[1105]: info: Discovery: Getting this device information Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 28 15:36:06 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:06 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 15:36:07 volumio volumio[1105]: info: TidalConnect service started! Nov 28 15:36:07 volumio volumio[1105]: [Metrics] CommandRouter: 32s 222.71ms Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::volumiosetStartupVolume Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:07 volumio volumio[1105]: info: VolumeController:: Setting startup Volume 100 Nov 28 15:36:07 volumio volumio[1105]: info: VolumeController::SetAlsaVolume100 Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::Close All Modals sent Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::Close All Modals sent Nov 28 15:36:07 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:07 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:07 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:07 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:07 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:08 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 28 15:36:08 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 28 15:36:08 volumio volumio[1105]: xcb_connection_has_error() returned true Nov 28 15:36:08 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 28 15:36:08 volumio ntpd[808]: Soliciting pool server 149.255.155.210 Nov 28 15:36:09 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 28 15:36:09 volumio volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 28 15:36:09 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 28 15:36:09 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:10 volumio volumio[1105]: info: CoreCommandRouter::volumioPrevious Nov 28 15:36:10 volumio volumio[1105]: info: CoreStateMachine::previous Nov 28 15:36:10 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:10 volumio volumio[1105]: info: ControllerMpd::seek Nov 28 15:36:10 volumio volumio[1105]: error: [2@0] {seek} Bad song index Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::volumioNext Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::next Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::stop Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:11 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:11 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::serviceStop Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::serviceStop Nov 28 15:36:11 volumio volumio[1105]: info: ControllerMpd::stop Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand stop took 51 milliseconds Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::play index undefined Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::startPlaybackTimer Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::clearAddPlayTracks USB/android/Ac Dc/02. Shoot To Thrill.wav Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand stop took 2 milliseconds Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 15:36:11 volumio volumio[1105]: info: Nov 28 15:36:11 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Nov 28 15:36:11 volumio volumio[1105]: info: Ignoring MPD Status Update Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand clear took 1 milliseconds Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "USB/android/Ac Dc/02. Shoot To Thrill.wav" Nov 28 15:36:11 volumio volumio[1105]: error: updateQueue error: null Nov 28 15:36:11 volumio volumio[1105]: info: Nov 28 15:36:11 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Nov 28 15:36:11 volumio volumio[1105]: info: Ignoring MPD Status Update Nov 28 15:36:11 volumio volumio[1105]: info: ------------------------------ 2ms Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand add "USB/android/Ac Dc/02. Shoot To Thrill.wav" took 2 milliseconds Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Nov 28 15:36:11 volumio volumio[1105]: info: ------------------------------ 2ms Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand play took 1 milliseconds Nov 28 15:36:11 volumio volumio[1105]: info: Nov 28 15:36:11 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:11 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:11 volumio volumio[1105]: info: Nov 28 15:36:11 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand status took 7 milliseconds Nov 28 15:36:11 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand clearerror took 2 milliseconds Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand status took 2 milliseconds Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:11 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:11 volumio volumio[1105]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shoot To Thrill","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/02. Shoot To Thrill.wav","trackType":"wav"} Nov 28 15:36:11 volumio volumio[1105]: verbose: CURRENT POSITION 1 Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::syncState stateService pause Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:11 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:11 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:11 volumio volumio[1105]: info: ------------------------------ 25ms Nov 28 15:36:11 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 28 15:36:11 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:11 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:11 volumio volumio[1105]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shoot To Thrill","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/02. Shoot To Thrill.wav","trackType":"wav"} Nov 28 15:36:11 volumio volumio[1105]: verbose: CURRENT POSITION 1 Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::syncState stateService pause Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:36:11 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:11 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:11 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:11 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:11 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:11 volumio volumio[1105]: info: ------------------------------ 41ms Nov 28 15:36:12 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 1 Nov 28 15:36:12 volumio kernel: xhci_hcd 0000:01:00.0: @0000000429514ce0 00000000 00000000 0e000000 03028001 Nov 28 15:36:13 volumio ntpd[808]: Soliciting pool server 2606:4700:f1::1 Nov 28 15:36:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 15:36:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 28 15:36:13 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 28 15:36:14 volumio volumio-remote-updater[644]: No test mode Nov 28 15:36:14 volumio volumio-remote-updater[644]: No alpha test mode Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 28 15:36:14 volumio volumio[1105]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 28 15:36:14 volumio volumio[1105]: info: BOOT COMPLETED Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::volumioGetBrowseSources Nov 28 15:36:14 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 15:36:22 volumio volumio[1105]: info: Discovery: Getting this device information Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::volumioPlay Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::play index undefined Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::startPlaybackTimer Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::clearAddPlayTracks USB/android/Ac Dc/02. Shoot To Thrill.wav Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:36:22 volumio volumio[1105]: info: Nov 28 15:36:22 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand stop took 3 milliseconds Nov 28 15:36:22 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 15:36:22 volumio volumio[1105]: info: Nov 28 15:36:22 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Nov 28 15:36:22 volumio volumio[1105]: info: Ignoring MPD Status Update Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand clear took 1 milliseconds Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "USB/android/Ac Dc/02. Shoot To Thrill.wav" Nov 28 15:36:22 volumio volumio[1105]: error: updateQueue error: null Nov 28 15:36:22 volumio volumio[1105]: info: Nov 28 15:36:22 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Nov 28 15:36:22 volumio volumio[1105]: info: Ignoring MPD Status Update Nov 28 15:36:22 volumio volumio[1105]: info: ------------------------------ 3ms Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand add "USB/android/Ac Dc/02. Shoot To Thrill.wav" took 2 milliseconds Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Nov 28 15:36:22 volumio volumio[1105]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 28 15:36:22 volumio volumio[1105]: info: ------------------------------ 7ms Nov 28 15:36:22 volumio volumio[1105]: info: ------------------------------ 3ms Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand play took 2 milliseconds Nov 28 15:36:22 volumio volumio[1105]: info: Nov 28 15:36:22 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:22 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:22 volumio volumio[1105]: info: Nov 28 15:36:22 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand status took 3 milliseconds Nov 28 15:36:22 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:22 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shoot To Thrill","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/02. Shoot To Thrill.wav","trackType":"wav"} Nov 28 15:36:22 volumio volumio[1105]: verbose: CURRENT POSITION 1 Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:36:22 volumio volumio[1105]: info: ------------------------------ 9ms Nov 28 15:36:22 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 28 15:36:22 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:22 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":320,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shoot To Thrill","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/02. Shoot To Thrill.wav","trackType":"wav"} Nov 28 15:36:22 volumio volumio[1105]: verbose: CURRENT POSITION 1 Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Nov 28 15:36:22 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:22 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:22 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:22 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:22 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:22 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:22 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:22 volumio volumio[1105]: info: ------------------------------ 34ms Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioNext Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::next Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::stop Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::stPlaybackTimer Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::serviceStop Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 1 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::serviceStop Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::stop Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:36:24 volumio volumio[1105]: info: Nov 28 15:36:24 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand stop took 35 milliseconds Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::play index undefined Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::startPlaybackTimer Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::clearAddPlayTracks USB/android/Ac Dc/07. Back In Black.wav Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::updateTrackBlock Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrackBlock Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand status took 2 milliseconds Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand stop took 2 milliseconds Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Nov 28 15:36:24 volumio volumio[1105]: info: Nov 28 15:36:24 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Nov 28 15:36:24 volumio volumio[1105]: info: Ignoring MPD Status Update Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand clear took 1 milliseconds Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "USB/android/Ac Dc/07. Back In Black.wav" Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shoot To Thrill","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/02. Shoot To Thrill.wav","trackType":"wav"} Nov 28 15:36:24 volumio volumio[1105]: verbose: CURRENT POSITION 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::syncState stateService stop Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:24 volumio volumio[1105]: info: No code Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:24 volumio volumio[1105]: info: ------------------------------ 35ms Nov 28 15:36:24 volumio volumio[1105]: error: updateQueue error: null Nov 28 15:36:24 volumio volumio[1105]: info: Nov 28 15:36:24 volumio volumio[1105]: ---------------------------- MPD announces system playlist update Nov 28 15:36:24 volumio volumio[1105]: info: Ignoring MPD Status Update Nov 28 15:36:24 volumio volumio[1105]: info: ------------------------------ 31ms Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand add "USB/android/Ac Dc/07. Back In Black.wav" took 31 milliseconds Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Nov 28 15:36:24 volumio volumio[1105]: info: Nov 28 15:36:24 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:24 volumio volumio[1105]: info: ------------------------------ 18ms Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand play took 17 milliseconds Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:24 volumio volumio[1105]: info: Nov 28 15:36:24 volumio volumio[1105]: ---------------------------- MPD announces state update: player Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::getState Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand status took 1 milliseconds Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::parseState Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/07. Back In Black.wav","trackType":"wav"} Nov 28 15:36:24 volumio volumio[1105]: verbose: CURRENT POSITION 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Nov 28 15:36:24 volumio volumio[1105]: info: ------------------------------ 5ms Nov 28 15:36:24 volumio volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 28 15:36:24 volumio volumio[1105]: verbose: ControllerMpd::parseTrackInfo Nov 28 15:36:24 volumio volumio[1105]: info: ControllerMpd::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::servicePushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Back In Black","artist":"Ac-Dc","album":"Greatest Hits CD1","uri":"USB/android/Ac Dc/07. Back In Black.wav","trackType":"wav"} Nov 28 15:36:24 volumio volumio[1105]: verbose: CURRENT POSITION 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::syncState stateService play Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::syncState currentStatus play Nov 28 15:36:24 volumio volumio[1105]: info: Received an update from plugin. extracting info from payload Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:24 volumio volumio[1105]: info: CoreStateMachine::pushState Nov 28 15:36:24 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:24 volumio volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output update for this device Nov 28 15:36:24 volumio volumio[1105]: info: MRS: Pushing multiroomSync output Nov 28 15:36:24 volumio volumio[1105]: info: ------------------------------ 25ms Nov 28 15:36:52 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 15:36:52 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 15:36:52 volumio volumio[1105]: info: Discovery: Getting this device information Nov 28 15:36:52 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 28 15:36:52 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:36:52 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 15:37:20 volumio ntpd[808]: Soliciting pool server 2a14:7c0:1002:101a:: Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 28 15:37:22 volumio volumio[1105]: info: Discovery: Getting this device information Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 28 15:37:22 volumio volumio[1105]: info: CorePlayQueue::getTrack 2 Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:22 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:22 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:23 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:23 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:23 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:23 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:23 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:23 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:23 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:23 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:23 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Nov 28 15:37:23 volumio volumio[1105]: error: MRS: impossible to set browserPlayback volume: device not found Nov 28 15:37:29 volumio volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPause Nov 28 15:37:29 volumio volumio[1105]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPause] in plugin multiroom Nov 28 15:37:29 volumio volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 15:37:29 volumio volumio[1105]: TypeError: Cannot read property 'then' of undefined Nov 28 15:37:29 volumio volumio[1105]: at outputs.audioOutputPause (/volumio/app/plugins/audio_interface/outputs/index.js:417:9) Nov 28 15:37:29 volumio volumio[1105]: at CoreCommandRouter.audioOutputPause (/volumio/app/index.js:2285:30) Nov 28 15:37:29 volumio volumio[1105]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Nov 28 15:37:29 volumio volumio[1105]: at Socket.emit (events.js:315:20) Nov 28 15:37:29 volumio volumio[1105]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 28 15:37:29 volumio volumio[1105]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 28 15:37:29 volumio volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 28 15:37:30 volumio sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-28 15:36 Nov 28 15:37:30 volumio sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"