-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-05-13 18:48:17 UTC. -- May 13 18:47:56 motivo sudo[8541]: pam_unix(sudo:session): session closed for user root May 13 18:47:56 motivo sudo[9078]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-serial-plugin ; USER=root ; COMMAND=/bin/cp -rp README.md UIConfigAudio.json UIConfigSystem.json config.json dacFiltersMapFile.json deploy.sh i18n i2sHdmiMapFile.json icons index.js inputMapFile.json inputs.json install.sh package.json requiredConf.json serialTest.js touchevents.sh /volumio/app/plugins/music_service/inputs/ May 13 18:47:56 motivo sudo[9078]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 13 18:47:56 motivo sudo[9078]: pam_unix(sudo:session): session closed for user root May 13 18:47:56 motivo sudo[9080]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-serial-plugin ; USER=root ; COMMAND=/usr/bin/killall node May 13 18:47:56 motivo sudo[9080]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 13 18:47:56 motivo sudo[9080]: pam_unix(sudo:session): session closed for user root May 13 18:47:56 motivo sudo[9083]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio-serial-plugin ; USER=root ; COMMAND=/bin/journalctl -f May 13 18:47:56 motivo sudo[9083]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 13 18:47:56 motivo systemd[1]: volumio.service: Main process exited, code=killed, status=15/TERM May 13 18:47:56 motivo volumio-remote-updater[660]: [2024-05-13 18:47:56] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 13 18:47:56 motivo volumio-remote-updater[660]: [2024-05-13 18:47:56] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 13 18:47:56 motivo vtcs[8856]: [2024-05-13 18:47:56.715] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 13 18:47:56 motivo sudo[8770]: pam_unix(sudo:session): session closed for user root May 13 18:47:56 motivo systemd[2141]: media-Elements.mount: Succeeded. May 13 18:47:56 motivo systemd[1]: media-Elements.mount: Succeeded. May 13 18:47:56 motivo ntfs-3g[8599]: Unmounting /dev/sda1 (Elements) May 13 18:47:56 motivo systemd[1]: volumio.service: Succeeded. May 13 18:47:56 motivo systemd[1]: Started dynamicswap service. May 13 18:47:56 motivo systemd[1]: dynamicswap.service: Succeeded. May 13 18:47:57 motivo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 13 18:47:57 motivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. May 13 18:47:57 motivo systemd[1]: Stopped Volumio Backend Module. May 13 18:47:57 motivo systemd[1]: Started Volumio Backend Module. May 13 18:47:57 motivo systemd[1]: Started dynamicswap service. May 13 18:47:57 motivo systemd[1]: dynamicswap.service: Succeeded. May 13 18:47:58 motivo volumio[9096]: info: ------------------------------------------- May 13 18:47:58 motivo volumio[9096]: info: ----- Volumio3 ---- May 13 18:47:58 motivo volumio[9096]: info: ------------------------------------------- May 13 18:47:58 motivo volumio[9096]: info: ----- System startup ---- May 13 18:47:58 motivo volumio[9096]: info: ------------------------------------------- May 13 18:47:58 motivo volumio[9096]: info: MYVOLUMIO Environment detected May 13 18:47:58 motivo volumio[9096]: info: Plugin folders cleanup May 13 18:47:58 motivo volumio[9096]: info: Scanning into folder /volumio/app/plugins/ May 13 18:47:58 motivo volumio[9096]: info: Scanning category audio_interface May 13 18:47:58 motivo volumio[9096]: info: Scanning category miscellanea May 13 18:47:58 motivo volumio[9096]: info: Scanning category music_service May 13 18:47:58 motivo volumio[9096]: info: Scanning category plugins.json May 13 18:47:58 motivo volumio[9096]: info: Scanning category system_controller May 13 18:47:58 motivo volumio[9096]: info: Scanning category user_interface May 13 18:47:58 motivo volumio[9096]: info: Scanning into folder /data/plugins/ May 13 18:47:58 motivo volumio[9096]: info: Plugin folders cleanup completed May 13 18:47:58 motivo volumio[9096]: info: ------------------------------------------- May 13 18:47:58 motivo volumio[9096]: info: ----- Core plugins startup ---- May 13 18:47:58 motivo volumio[9096]: info: ------------------------------------------- May 13 18:47:59 motivo volumio[9096]: info: Loading plugins from folder /volumio/app/plugins/ May 13 18:47:59 motivo volumio[9096]: info: Adding plugin bluetooth to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin multiroom to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin upnp to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin metavolumio to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin airplay_emulation to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin cd_controller to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin raat to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin smart_inputs to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin tidalconnect to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Adding plugin upnp_browser to MyMusic Plugins May 13 18:47:59 motivo volumio[9096]: info: Loading plugins from folder /data/plugins/ May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "system"... May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "appearance"... May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "network"... May 13 18:47:59 motivo volumio[9096]: info: Refreshing Cached IP Addresses May 13 18:47:59 motivo sudo[9123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 18:47:59 motivo sudo[9123]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:47:59 motivo sudo[9125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 18:47:59 motivo sudo[9125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "services"... May 13 18:47:59 motivo sudo[9123]: pam_unix(sudo:session): session closed for user root May 13 18:47:59 motivo sudo[9125]: pam_unix(sudo:session): session closed for user root May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "alsa_controller"... May 13 18:47:59 motivo sudo[9128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 13 18:47:59 motivo sudo[9128]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:47:59 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "wizard"... May 13 18:47:59 motivo volumio[9096]: info: Loading plugin "networkfs"... May 13 18:47:59 motivo volumio[9096]: info: Starting Udev Watcher for removable devices May 13 18:47:59 motivo volumio[9096]: info: Ignoring mount for partition: boot May 13 18:47:59 motivo volumio[9096]: info: Ignoring mount for partition: volumio May 13 18:47:59 motivo volumio[9096]: info: Ignoring mount for partition: volumio_data May 13 18:47:59 motivo volumio[9096]: info: Mounting Device Elements May 13 18:48:00 motivo sudo[9139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Elements -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 13 18:48:00 motivo sudo[9139]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:01 motivo volumio-remote-updater[660]: [2024-05-13 18:48:01] [connect] Successful connection May 13 18:48:03 motivo sudo[9128]: pam_unix(sudo:session): session closed for user root May 13 18:48:04 motivo ntfs-3g[9144]: Version 2017.3.23AR.3 integrated FUSE 28 May 13 18:48:04 motivo ntfs-3g[9144]: Mounted /dev/sda1 (Read-Write, label "Elements", NTFS 3.1) May 13 18:48:04 motivo ntfs-3g[9144]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 13 18:48:04 motivo ntfs-3g[9144]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 May 13 18:48:04 motivo ntfs-3g[9144]: Global ownership and permissions enforced, configuration type 7 May 13 18:48:04 motivo sudo[9139]: pam_unix(sudo:session): session closed for user root May 13 18:48:04 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 18:48:04 motivo volumio[9096]: info: Loading plugin "volumio_command_line_client"... May 13 18:48:04 motivo volumio[9096]: info: Loading plugin "upnp"... May 13 18:48:04 motivo volumio[9096]: info: [1715626084968] Starting Upmpd Daemon May 13 18:48:04 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 18:48:04 motivo volumio[9096]: info: Loading plugin "my_music"... May 13 18:48:04 motivo volumio[9096]: info: Loading plugin "mpd"... May 13 18:48:05 motivo volumio[9096]: info: Loading plugin "upnp_browser"... May 13 18:48:05 motivo volumio[9096]: info: Loading plugin "bluetooth"... May 13 18:48:05 motivo volumio[9096]: info: [1715626085586] Starting BluetoothController May 13 18:48:05 motivo volumio[9096]: info: Loading plugin "alarm-clock"... May 13 18:48:05 motivo volumio[9096]: info: Loading plugin "manifestui"... May 13 18:48:05 motivo volumio[9096]: info: Loading plugin "metavolumio"... May 13 18:48:06 motivo volumio[9096]: info: Loading plugin "airplay_emulation"... May 13 18:48:06 motivo volumio[9096]: info: Starting Shairport Sync May 13 18:48:06 motivo volumio[9096]: info: Loading plugin "cd_controller"... May 13 18:48:06 motivo volumio[9096]: info: Loading plugin "last_100"... May 13 18:48:06 motivo volumio[9096]: info: Loading plugin "raat"... May 13 18:48:06 motivo volumio[9096]: info: RAAT Plugin loaded May 13 18:48:06 motivo volumio[9096]: info: Adding restartRAATSocket REST API Endpoint May 13 18:48:06 motivo volumio[9096]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat May 13 18:48:06 motivo volumio[9096]: info: Loading plugin "streaming_services"... May 13 18:48:07 motivo volumio[9096]: info: Starting Streaming Service Transparent Proxy May 13 18:48:07 motivo volumio[9096]: info: Loading plugin "tidalconnect"... May 13 18:48:07 motivo volumio[9096]: info: Loading plugin "webradio"... May 13 18:48:07 motivo volumio[9096]: info: Loading plugin "i2s_dacs"... May 13 18:48:07 motivo volumio[9096]: info: I2S DAC not set, start Auto-detection May 13 18:48:07 motivo volumio[9096]: info: Loading plugin "volumiodiscovery"... May 13 18:48:07 motivo volumio[9096]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 13 18:48:07 motivo volumio[9096]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 18:48:07 motivo volumio[9096]: *** WARNING *** For more information see May 13 18:48:07 motivo volumio[9096]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 13 18:48:07 motivo node[9096]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 13 18:48:07 motivo volumio[9096]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 18:48:07 motivo volumio[9096]: *** WARNING *** For more information see May 13 18:48:07 motivo node[9096]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 18:48:07 motivo node[9096]: *** WARNING *** For more information see May 13 18:48:07 motivo node[9096]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 13 18:48:07 motivo node[9096]: *** WARNING *** Please fix your application to use the native API of Avahi! May 13 18:48:07 motivo node[9096]: *** WARNING *** For more information see May 13 18:48:07 motivo volumio[9096]: info: Applying required configuration parameters for plugin volumiodiscovery May 13 18:48:07 motivo volumio[9096]: info: Discovery: Started advertising with name: Motivo May 13 18:48:07 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 18:48:07 motivo volumio[9096]: info: Loading plugin "multiroom"... May 13 18:48:08 motivo volumio[9096]: STREAMING PROXY: Starting server on port 3245 May 13 18:48:08 motivo volumio[9096]: Node JS runtime: 14 May 13 18:48:08 motivo volumio[9096]: info: Applying required configuration parameters for plugin multiroom May 13 18:48:09 motivo sudo[9156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 13 18:48:09 motivo sudo[9156]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:09 motivo sudo[9156]: pam_unix(sudo:session): session closed for user root May 13 18:48:09 motivo volumio[9096]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists May 13 18:48:09 motivo volumio[9096]: info: MRS: MultiRoom plugin initialized May 13 18:48:09 motivo volumio[9096]: info: MRS: STOPPING SNAPCLIENT May 13 18:48:09 motivo volumio[9096]: info: MRS: Snap server stop May 13 18:48:09 motivo sudo[9172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 13 18:48:09 motivo sudo[9172]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:09 motivo volumio[9096]: info: MRS: STOPPING volumioStreaming May 13 18:48:09 motivo sudo[9174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 13 18:48:09 motivo sudo[9174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:09 motivo sudo[9172]: pam_unix(sudo:session): session closed for user root May 13 18:48:09 motivo volumio[9096]: info: Loading plugin "outputs"... May 13 18:48:09 motivo volumio[9096]: info: Loading plugin "albumart"... May 13 18:48:09 motivo sudo[9179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 13 18:48:09 motivo sudo[9174]: pam_unix(sudo:session): session closed for user root May 13 18:48:09 motivo sudo[9179]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:09 motivo sudo[9180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 13 18:48:09 motivo sudo[9180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:09 motivo sudo[9180]: pam_unix(sudo:session): session closed for user root May 13 18:48:09 motivo volumio[9096]: info: Plugin example_plugin is not enabled May 13 18:48:09 motivo volumio[9096]: info: Loading plugin "hi_res_audio"... May 13 18:48:09 motivo sudo[9179]: pam_unix(sudo:session): session closed for user root May 13 18:48:09 motivo volumio[9096]: Forking 3 albumart workers May 13 18:48:10 motivo volumio[9096]: info: Applying required configuration parameters for plugin hi_res_audio May 13 18:48:10 motivo volumio[9096]: info: Loading plugin "inputs"... May 13 18:48:10 motivo volumio[9096]: Starting albumart workers May 13 18:48:10 motivo volumio[9096]: Starting albumart workers May 13 18:48:10 motivo volumio[9096]: Starting albumart workers May 13 18:48:10 motivo volumio[9096]: info: Loading plugin "qobuz"... May 13 18:48:11 motivo volumio[9096]: info: Loading plugin "smart_inputs"... May 13 18:48:11 motivo volumio[9096]: info: Loading plugin "tidal"... May 13 18:48:12 motivo volumio[9096]: info: Loading plugin "motivocontrol"... May 13 18:48:12 motivo volumio[9096]: info: Adding this device properties May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties May 13 18:48:12 motivo volumio[9096]: info: Setting Additional Device Volumio Properties: [object Object] May 13 18:48:12 motivo volumio[9096]: info: Checking For Motivo Hardware May 13 18:48:12 motivo volumio[9096]: info: Loading plugin "updater_comm"... May 13 18:48:12 motivo volumio[9096]: info: Plugin mpdemulation is not enabled May 13 18:48:12 motivo volumio[9096]: info: Loading plugin "rest_api"... May 13 18:48:12 motivo volumio[9096]: info: Loading plugin "websocket"... May 13 18:48:12 motivo volumio[9096]: info: Loading i18n strings for locale en May 13 18:48:12 motivo volumio[9096]: Updating browse sources language May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::initPlayerControls May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:48:12 motivo volumio[9096]: Express server listening on port 3000 May 13 18:48:12 motivo volumio[9096]: [Metrics] WebUI: 14s 911.39ms May 13 18:48:12 motivo volumio[9096]: info: CoreStateMachine::resetVolumioState May 13 18:48:12 motivo volumio[9096]: info: CoreStateMachine::getcurrentVolume May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::volumioRetrievevolume May 13 18:48:12 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:13 motivo volumio[9096]: info: Motivo Hardware found May 13 18:48:13 motivo volumio[9096]: info: MRS: Removed streaming files May 13 18:48:13 motivo volumio[9096]: info: MRS: volumioStreaming STOPPED May 13 18:48:13 motivo volumio[9096]: info: MRS: SNAPSERVER STOPPED May 13 18:48:13 motivo volumio[9096]: info: MRS: SNAPCLIENT STOPPED May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 1 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 2 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.146 UA: node-XMLHttpRequest Total Clients: 4 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 5 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.172 UA: node-XMLHttpRequest Total Clients: 6 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 7 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.152 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 9 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28 from 192.168.1.169 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 10 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 11 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.146 UA: node-XMLHttpRequest Total Clients: 12 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.172 UA: node-XMLHttpRequest Total Clients: 13 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.172 UA: node-XMLHttpRequest Total Clients: 14 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 192.168.1.28:3000 from 192.168.1.146 UA: node-XMLHttpRequest Total Clients: 15 May 13 18:48:13 motivo volumio[9096]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 16 May 13 18:48:13 motivo volumio[9096]: info: Reloading queue from file May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: VolumeController:: Volume=46 Mute =false May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::updateTrackBlock May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrackBlock May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioRetrievevolume May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::setRepeat null single undefined May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::setRandom null May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:13 motivo volumio[9096]: info: Setting Device type: Raspberry PI May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetQueue May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::getQueue May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getQueue May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid May 13 18:48:13 motivo volumio[9096]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction May 13 18:48:13 motivo volumio[9096]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection May 13 18:48:13 motivo volumio[9096]: info: Received Get System Info May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:48:13 motivo volumio[9096]: info: Discovery: Getting this device information May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetVisibleSources May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 13 18:48:13 motivo volumio[9096]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 13 18:48:13 motivo volumio[9096]: info: Received Get System Info May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 13 18:48:13 motivo volumio[9096]: info: Discovery: Getting this device information May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: Listing playlists May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetQueue May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::getQueue May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getQueue May 13 18:48:13 motivo volumio[9096]: info: Completed loading Core Plugins May 13 18:48:13 motivo volumio[9096]: info: Preparing to generate the ALSA configuration file May 13 18:48:13 motivo volumio[9096]: info: Discovery: adding 8f63edbf-2b60-44dd-9586-453595e5cd30 May 13 18:48:13 motivo volumio[9096]: info: Discovery: Found device Integro May 13 18:48:13 motivo volumio[9096]: info: Discovery: Connecting to remote: 192.168.1.172 May 13 18:48:13 motivo volumio[9096]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 13 18:48:13 motivo volumio[9096]: info: Reading ALSA contributions from plugins. May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: Discovery: adding c202970e-65e8-4f70-b348-21e0162f95a9 May 13 18:48:13 motivo volumio[9096]: info: Discovery: Found device Motivo May 13 18:48:13 motivo volumio[9096]: info: Discovery: Connecting to remote: 192.168.1.146 May 13 18:48:13 motivo volumio[9096]: info: Discovery: this is already registered, c202970e-65e8-4f70-b348-21e0162f95a9 May 13 18:48:13 motivo volumio[9096]: info: Discovery: Found device Motivo May 13 18:48:13 motivo volumio[9096]: info: Discovery: Connecting to remote: 192.168.1.146 May 13 18:48:13 motivo volumio[9096]: info: VolumeController:: Volume=46 Mute =false May 13 18:48:13 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:13 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:13 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:13 motivo volumio[9096]: info: Discovery: this is already registered, 8f63edbf-2b60-44dd-9586-453595e5cd30 May 13 18:48:13 motivo volumio[9096]: info: Discovery: Found device Integro May 13 18:48:13 motivo volumio[9096]: info: Discovery: Connecting to remote: 192.168.1.172 May 13 18:48:14 motivo volumio[9096]: info: Discovery: adding a853d138-ac3c-48de-9753-40f7c799d34e May 13 18:48:14 motivo volumio[9096]: info: Discovery: Found device Motivo May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:14 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:14 motivo volumio[9096]: info: MRS: Pushing multiroomSync output for this device May 13 18:48:14 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:14 motivo volumio[9096]: info: Adding audio output: May 13 18:48:14 motivo volumio[9096]: info: Adding audio output: May 13 18:48:14 motivo volumio[9096]: info: Asound.conf file unchanged, so no further update is needed May 13 18:48:14 motivo volumio[9096]: info: Output device has changed, restarting MPD May 13 18:48:14 motivo sudo[9259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 18:48:14 motivo sudo[9259]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo volumio[9096]: info: Output device has changed, restarting Shairport Sync May 13 18:48:14 motivo sudo[9259]: pam_unix(sudo:session): session closed for user root May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:14 motivo sudo[9262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 18:48:14 motivo sudo[9262]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo systemd[1]: Stopping Music Player Daemon... May 13 18:48:14 motivo systemd[1]: mpd.service: Succeeded. May 13 18:48:14 motivo volumio[9096]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 18:48:14 motivo systemd[1]: Stopped Music Player Daemon. May 13 18:48:14 motivo volumio[9096]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo systemd[1]: Starting Music Player Daemon... May 13 18:48:14 motivo volumio[9096]: info: ___________ START PLUGINS ___________ May 13 18:48:14 motivo volumio-remote-updater[660]: [2024-05-13 18:48:14] [connect] Successful connection May 13 18:48:14 motivo volumio[9096]: info: ControllerMpd::onStart: Initializing MPD May 13 18:48:14 motivo volumio[9096]: info: Creating MPD Configuration file May 13 18:48:14 motivo sudo[9271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 13 18:48:14 motivo sudo[9271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:48:14 motivo sudo[9271]: pam_unix(sudo:session): session closed for user root May 13 18:48:14 motivo volumio[9096]: info: [1715626094275] CoreMusicLibrary::Adding element Media Servers May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:48:14 motivo sudo[9273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 13 18:48:14 motivo sudo[9273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 13 18:48:14 motivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 13 18:48:14 motivo systemd[1]: mpd.service: Succeeded. May 13 18:48:14 motivo systemd[1]: Stopped Music Player Daemon. May 13 18:48:14 motivo systemd[1]: Starting Music Player Daemon... May 13 18:48:14 motivo volumio[9096]: info: Adding Manifest REST API Endpoints May 13 18:48:14 motivo volumio[9096]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui May 13 18:48:14 motivo volumio[9096]: info: Adding METAVOLUMIO REST API Endpoints May 13 18:48:14 motivo volumio[9096]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 13 18:48:14 motivo volumio[9096]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio May 13 18:48:14 motivo volumio[9096]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio May 13 18:48:14 motivo volumio[9096]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:14 motivo sudo[9278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Motivo May 13 18:48:14 motivo sudo[9278]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo sudo[9278]: pam_unix(sudo:session): session closed for user root May 13 18:48:14 motivo volumio[9096]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 13 18:48:14 motivo volumio[9096]: info: Preparing CD Folders May 13 18:48:14 motivo volumio[9096]: info: Adding CD REST API Endpoints May 13 18:48:14 motivo volumio[9096]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 13 18:48:14 motivo volumio[9096]: info: Starting UDEV Watcher for CD May 13 18:48:14 motivo volumio[9096]: info: Detecting CD presence with UDEV May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:48:14 motivo volumio[9096]: info: [1715626094569] CoreMusicLibrary::Adding element Last_100 May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:48:14 motivo volumio[9096]: info: Starting RAAT Plugin May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 13 18:48:14 motivo volumio[9096]: info: Additional UI Settings Added for plugin music_service/raat May 13 18:48:14 motivo volumio[9096]: info: Registering DSP Elements listener and retrieving current ones May 13 18:48:14 motivo volumio[9096]: info: Additional DSP elements updated May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: Updating RAAT Signal Path May 13 18:48:14 motivo volumio[9096]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: Not Reporting Auto name since its the default one May 13 18:48:14 motivo volumio[9096]: info: Streaming services startup May 13 18:48:14 motivo volumio[9096]: info: Starting Streaming Daemon May 13 18:48:14 motivo sudo[9291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 13 18:48:14 motivo sudo[9291]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo sudo[9291]: pam_unix(sudo:session): session closed for user root May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 13 18:48:14 motivo volumio[9096]: info: [1715626094804] CoreMusicLibrary::Adding element Webradio May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:48:14 motivo sudo[9298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 13 18:48:14 motivo sudo[9298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:14 motivo volumio[9096]: info: Initializing BBC Radios May 13 18:48:14 motivo systemd[1]: Stopping Volumio Tidal Connect Service... May 13 18:48:14 motivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 13 18:48:14 motivo systemd[1]: vtcs.service: Succeeded. May 13 18:48:14 motivo systemd[1]: Stopped Volumio Tidal Connect Service. May 13 18:48:14 motivo sudo[9298]: pam_unix(sudo:session): session closed for user root May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus May 13 18:48:14 motivo volumio[9096]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 13 18:48:14 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:15 motivo volumio[9096]: error: Hi Res Audio Failed Login: Missing Login Data May 13 18:48:15 motivo volumio[9096]: info: Adding HIGHRESAUDIO REST API Endpoints May 13 18:48:15 motivo volumio[9096]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 13 18:48:15 motivo volumio[9096]: info: Initializing Serial Communication on port /dev/ttyAMA1 May 13 18:48:15 motivo volumio[9096]: info: Touch Event Listener Process Starting May 13 18:48:15 motivo volumio[9096]: info: Adding inputs REST Endpoints May 13 18:48:15 motivo volumio[9096]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 13 18:48:15 motivo volumio[9096]: info: Scanning Audio Inputs May 13 18:48:15 motivo sudo[9315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root May 13 18:48:15 motivo sudo[9315]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:15 motivo volumio[9096]: info: Checking against Known Cards name May 13 18:48:15 motivo volumio[9096]: info: Checking against Known Cards name May 13 18:48:15 motivo volumio[9096]: info: Adding Server instance for streaming May 13 18:48:15 motivo volumio[9096]: info: Refreshing TIDAL token May 13 18:48:15 motivo volumio[9096]: info: Volumio Calling Home May 13 18:48:15 motivo volumio[9096]: (node:9096) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 13 18:48:15 motivo volumio[9096]: (Use `node --trace-deprecation ...` to show where the warning was created) May 13 18:48:15 motivo volumio[9096]: info: Stopping AccessToken refresher cron for QOBUZ May 13 18:48:15 motivo volumio[9096]: info: AccessToken refresher cron started for QOBUZ May 13 18:48:15 motivo volumio[9096]: info: Adding QOBUZ REST API Endpoints May 13 18:48:15 motivo volumio[9096]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 13 18:48:15 motivo mpd[9276]: May 13 18:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 13 18:48:15 motivo systemd[1]: Started Music Player Daemon. May 13 18:48:15 motivo sudo[9262]: pam_unix(sudo:session): session closed for user root May 13 18:48:15 motivo sudo[9273]: pam_unix(sudo:session): session closed for user root May 13 18:48:15 motivo volumio[9096]: info: Discovery: this is already registered, a853d138-ac3c-48de-9753-40f7c799d34e May 13 18:48:15 motivo volumio[9096]: info: Discovery: Found device Motivo May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::volumioGetState May 13 18:48:15 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing audio output on request May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: Discovery: Connected to remote: 192.168.1.146 May 13 18:48:15 motivo sudo[9325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 13 18:48:15 motivo sudo[9325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:15 motivo volumio[9096]: info: Serial port opened successfully May 13 18:48:15 motivo volumio[9096]: info: Sending serial start messages May 13 18:48:15 motivo sudo[9325]: pam_unix(sudo:session): session closed for user root May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET VOLUME May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET AVAILABLEINPUTS May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET HWVER May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET FWVER May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET POWEROFFAVAILABLE May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET HEADPHONESTATUS May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET SLEEP May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET TIMEOUT May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET LEDDIMM May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET BOOTMODE May 13 18:48:15 motivo volumio[9096]: info: Display with brightness control is connected, setting display brightness May 13 18:48:15 motivo volumio[9096]: info: Setting Display Brightness to 12 May 13 18:48:15 motivo sudo[9327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 13 18:48:15 motivo sudo[9327]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:15 motivo sudo[9327]: pam_unix(sudo:session): session closed for user root May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET AVAILABLEI2SHDMIMODE May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET AVAILABLEDACFILTER May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing audio output on request May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: Discovery: Connected to remote: 192.168.1.172 May 13 18:48:15 motivo volumio[9096]: info: MRS: Adding multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: Adding audio output: May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio[9096]: error: Cannot start Volumio Streaming Daemon May 13 18:48:15 motivo volumio[9096]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 13 18:48:15 motivo volumio[9096]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 13 18:48:15 motivo volumio[9096]: info: Bluetooth name changed to Motivo May 13 18:48:15 motivo volumio[9096]: info: MPD Permissions set May 13 18:48:15 motivo volumio[9096]: info: MPD Permissions set May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH VOLUME 66 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'VOLUME', '66' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---VOLUME--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---66--- May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride May 13 18:48:15 motivo volumio[9096]: info: Setting Device Volume Override May 13 18:48:15 motivo volumio[9096]: info: Applying Volume Override May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 13 18:48:15 motivo volumio[9096]: info: Updating Volume Controller Parameters: Device: 5 Name: Analog + I2S + Headphones Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 13 18:48:15 motivo volumio[9096]: info: Enabling external Volume Control May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: SET MAXVOL 100 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:15 motivo volumio[9096]: SENDING SERIAL MESSAGE: SET MAXVOL 100 May 13 18:48:15 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:15 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output update for this device May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:15 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output update for this device May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:15 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output update for this device May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: MRS: Adding multiroomSync output May 13 18:48:15 motivo volumio[9096]: info: Adding audio output: May 13 18:48:15 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:15 motivo volumio-remote-updater[660]: [2024-05-13 18:48:15] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1715626094 101 May 13 18:48:15 motivo volumio[9096]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 17 May 13 18:48:15 motivo volumio[9096]: info: Volumio called home May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH AVAILABLEINPUTS 10000000000000000000000000000000 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'AVAILABLEINPUTS', '10000000000000000000000000000000' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---AVAILABLEINPUTS--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---10000000000000000000000000000000--- May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH HWVER 2.0 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'HWVER', '2.0' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---HWVER--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---2.0--- May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 13 18:48:15 motivo volumio[9096]: info: Setting Additional System Software info: Hardware Revision: 2.0 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 13 18:48:15 motivo volumio[9096]: info: Setting HW Firmware info: undefined May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 13 18:48:15 motivo volumio[9096]: info: Setting HW Version info: 2.0 May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH FWVER 0.1.1 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'FWVER', '0.1.1' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---FWVER--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---0.1.1--- May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 13 18:48:15 motivo volumio[9096]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.1.1 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 13 18:48:15 motivo volumio[9096]: info: Setting HW Firmware info: 0.1.1 May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 13 18:48:15 motivo volumio[9096]: info: Setting HW Version info: 2.0 May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH POWEROFFAVAILABLE 1 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'POWEROFFAVAILABLE', '1' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---POWEROFFAVAILABLE--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---1--- May 13 18:48:15 motivo volumio[9096]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH HEADPHONESTATUS 0 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'HEADPHONESTATUS', '0' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---HEADPHONESTATUS--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---0--- May 13 18:48:15 motivo volumio[9096]: info: MCU Signalled Headphone Mode Disabled May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:15 motivo volumio[9096]: info: Updating RAAT Signal Path May 13 18:48:15 motivo volumio[9096]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH SLEEP 0 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'SLEEP', '0' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---SLEEP--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---0--- May 13 18:48:15 motivo volumio[9096]: info: MCU Signalled Sleep Mode Disabled May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH TIMEOUT 20 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'TIMEOUT', '20' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---TIMEOUT--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---20--- May 13 18:48:15 motivo volumio[9096]: info: Enabling Advanced system settings configuration May 13 18:48:15 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections May 13 18:48:15 motivo volumio[9096]: info: Additional UI Settings Added for plugin music_service/inputs May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH LEDDIMM 100 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'LEDDIMM', '100' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---LEDDIMM--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---100--- May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH BOOTMODE 0 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'BOOTMODE', '0' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---BOOTMODE--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---0--- May 13 18:48:15 motivo volumio[9096]: info: MCU Signalled Auto Boot Mode On Power Disabled May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH AVAILABLEI2SHDMIMODE 11111111111111111111110000000000 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'AVAILABLEI2SHDMIMODE', '11111111111111111111110000000000' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---AVAILABLEI2SHDMIMODE--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---11111111111111111111110000000000--- May 13 18:48:15 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH AVAILABLEDACFILTER 11111110000000000000000000000000 May 13 18:48:15 motivo volumio[9096]: [ 'PUSH', 'AVAILABLEDACFILTER', '11111110000000000000000000000000' ] May 13 18:48:15 motivo volumio[9096]: COMMAND: ---AVAILABLEDACFILTER--- May 13 18:48:15 motivo volumio[9096]: MESSAGE: ---11111110000000000000000000000000--- May 13 18:48:15 motivo sudo[9338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on May 13 18:48:15 motivo sudo[9338]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:15 motivo sudo[9338]: pam_unix(sudo:session): session closed for user root May 13 18:48:15 motivo volumio[9096]: info: Adding audio output: May 13 18:48:16 motivo volumio[9096]: error: MPD error: The expression evaluated to a falsy value: May 13 18:48:16 motivo volumio[9096]: assert.ok(self.idling) May 13 18:48:16 motivo volumio[9096]: error: The expression evaluated to a falsy value: May 13 18:48:16 motivo volumio[9096]: assert.ok(self.idling) May 13 18:48:16 motivo volumio[9096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 18 May 13 18:48:16 motivo volumio[9096]: info: MPD running with PID9276 May 13 18:48:16 motivo volumio[9096]: ,establishing connection May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable May 13 18:48:16 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH MAXVOL 100 May 13 18:48:16 motivo volumio[9096]: [ 'PUSH', 'MAXVOL', '100' ] May 13 18:48:16 motivo volumio[9096]: COMMAND: ---MAXVOL--- May 13 18:48:16 motivo volumio[9096]: MESSAGE: ---100--- May 13 18:48:16 motivo volumio[9096]: error: Serial API: Failed to decode command: MAXVOL, message: 100 May 13 18:48:16 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH MAXVOL 100 May 13 18:48:16 motivo volumio[9096]: [ 'PUSH', 'MAXVOL', '100' ] May 13 18:48:16 motivo volumio[9096]: COMMAND: ---MAXVOL--- May 13 18:48:16 motivo volumio[9096]: MESSAGE: ---100--- May 13 18:48:16 motivo volumio[9096]: error: Serial API: Failed to decode command: MAXVOL, message: 100 May 13 18:48:16 motivo sudo[9351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service May 13 18:48:16 motivo sudo[9351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo volumio[9096]: error: updateQueue error: null May 13 18:48:16 motivo sudo[9351]: pam_unix(sudo:session): session closed for user root May 13 18:48:16 motivo sudo[9353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service May 13 18:48:16 motivo sudo[9353]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo systemd[1]: Stopping RAAT DAEMON... May 13 18:48:16 motivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM May 13 18:48:16 motivo systemd[1]: raat-daemon.service: Succeeded. May 13 18:48:16 motivo systemd[1]: Stopped RAAT DAEMON. May 13 18:48:16 motivo systemd[1]: Started RAAT DAEMON. May 13 18:48:16 motivo sudo[9353]: pam_unix(sudo:session): session closed for user root May 13 18:48:16 motivo volumio[9096]: info: Adding audio output: May 13 18:48:16 motivo volumio[9096]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 19 May 13 18:48:16 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET MUTE May 13 18:48:16 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET VOLUME May 13 18:48:16 motivo sudo[9366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 May 13 18:48:16 motivo sudo[9366]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo sudo[9366]: pam_unix(sudo:session): session closed for user root May 13 18:48:16 motivo volumio[9096]: info: Raat Daemon started successfully May 13 18:48:16 motivo volumio[9096]: error: updateQueue error: null May 13 18:48:16 motivo volumio[9096]: info: Access Token successfully retrieved May 13 18:48:16 motivo volumio[9096]: info: Executing endpoint restartRAATSocket May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection May 13 18:48:16 motivo volumio[9096]: info: Starting Shairport Sync May 13 18:48:16 motivo volumio[9096]: info: Starting Shairport Sync May 13 18:48:16 motivo sudo[9371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 18:48:16 motivo sudo[9371]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo volumio[9096]: info: Starting Shairport Sync May 13 18:48:16 motivo sudo[9374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 18:48:16 motivo sudo[9374]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 18:48:16 motivo systemd[1]: shairport-sync.service: Succeeded. May 13 18:48:16 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 18:48:16 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 18:48:16 motivo sudo[9371]: pam_unix(sudo:session): session closed for user root May 13 18:48:16 motivo sudo[9377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 13 18:48:16 motivo volumio[9096]: info: Adding Inputs via Serial API May 13 18:48:16 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET INPUT May 13 18:48:16 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 18:48:16 motivo sudo[9377]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo volumio[9096]: info: Adding Advanced Audio Settings via Serial API May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 13 18:48:16 motivo volumio[9096]: info: Additional UI Settings Added for plugin music_service/inputs May 13 18:48:16 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 13 18:48:16 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET I2SHDMIMODE May 13 18:48:16 motivo systemd[1]: shairport-sync.service: Succeeded. May 13 18:48:16 motivo volumio[9096]: info: Adding Advanced Audio Settings via Serial API May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 13 18:48:16 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 18:48:16 motivo volumio[9096]: SENDING SERIAL MESSAGE: GET DACFILTER May 13 18:48:16 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 18:48:16 motivo sudo[9374]: pam_unix(sudo:session): session closed for user root May 13 18:48:16 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH MUTE 0 May 13 18:48:16 motivo volumio[9096]: [ 'PUSH', 'MUTE', '0' ] May 13 18:48:16 motivo volumio[9096]: COMMAND: ---MUTE--- May 13 18:48:16 motivo volumio[9096]: MESSAGE: ---0--- May 13 18:48:16 motivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 13 18:48:16 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:16 motivo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 13 18:48:16 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 13 18:48:16 motivo systemd[1]: shairport-sync.service: Succeeded. May 13 18:48:16 motivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:16 motivo volumio[9096]: info: MRS: Pushing multiroomSync output update for this device May 13 18:48:16 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:16 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH VOLUME 66 May 13 18:48:16 motivo volumio[9096]: [ 'PUSH', 'VOLUME', '66' ] May 13 18:48:16 motivo volumio[9096]: COMMAND: ---VOLUME--- May 13 18:48:16 motivo volumio[9096]: MESSAGE: ---66--- May 13 18:48:16 motivo volumio[9096]: info: CoreStateMachine::pushState May 13 18:48:16 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:16 motivo volumio[9096]: info: CoreCommandRouter::volumioPushState May 13 18:48:16 motivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 13 18:48:16 motivo sudo[9382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 13 18:48:16 motivo sudo[9377]: pam_unix(sudo:session): session closed for user root May 13 18:48:16 motivo sudo[9382]: pam_unix(sudo:session): session opened for user root by (uid=0) May 13 18:48:16 motivo volumio[9096]: info: MRS: Pushing multiroomSync output update for this device May 13 18:48:16 motivo volumio[9096]: info: MRS: Pushing multiroomSync output May 13 18:48:16 motivo sudo[9382]: pam_unix(sudo:session): session closed for user root May 13 18:48:17 motivo volumio[9096]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 20 May 13 18:48:17 motivo volumio[9096]: RECEIVED SERIAL MESSAGE: PUSH INPUT 1 May 13 18:48:17 motivo volumio[9096]: [ 'PUSH', 'INPUT', '1' ] May 13 18:48:17 motivo volumio[9096]: COMMAND: ---INPUT--- May 13 18:48:17 motivo volumio[9096]: MESSAGE: ---1--- May 13 18:48:17 motivo volumio[9096]: info: CoreCommandRouter::servicePushState May 13 18:48:17 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:17 motivo volumio[9096]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received inputs May 13 18:48:17 motivo volumio[9096]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 13 18:48:17 motivo volumio[9096]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 13 18:48:17 motivo volumio[9096]: info: CorePlayQueue::getTrack 0 May 13 18:48:17 motivo volumio[9096]: info: Upmpdcli Daemon Started May 13 18:48:17 motivo volumio[9096]: info: Shairport-Sync Started May 13 18:48:17 motivo volumio[9096]: Error adding Membership: Error: addMembership EINVAL May 13 18:48:17 motivo volumio[9096]: info: Shairport-Sync Started May 13 18:48:17 motivo volumio[9096]: info: Shairport-Sync Started May 13 18:48:17 motivo volumio[9096]: {"status":"stop","position":0,"title":"Somebody's Watching Me","artist":"Rockwell","album":"Somebody's Watching Me","albumart":"https://resources.tidal.com/images/53c27016/edd1/46f6/bb35/d896187f8e18/640x640.jpg","uri":"tidal://song/196548947","trackType":"tidal","seek":0,"duration":299,"samplerate":"44.1 KHz","bitdepth":"16 bit","random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":66,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"tidal","updatedb":false,"volatile":false,"service":"tidal"} May 13 18:48:17 motivo volumio[9096]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 18:48:17 motivo volumio[9096]: ReferenceError: parsedStateString is not defined May 13 18:48:17 motivo volumio[9096]: at serialInputs.onChangedState (/volumio/app/plugins/music_service/inputs/index.js:1671:23) May 13 18:48:17 motivo volumio[9096]: at Socket. (/volumio/app/plugins/music_service/inputs/index.js:1613:14) May 13 18:48:17 motivo volumio[9096]: at Socket.Emitter.emit (/volumio/app/plugins/music_service/inputs/node_modules/component-emitter/index.js:133:20) May 13 18:48:17 motivo volumio[9096]: at Socket.onevent (/volumio/app/plugins/music_service/inputs/node_modules/socket.io-client/lib/socket.js:270:10) May 13 18:48:17 motivo volumio[9096]: at Socket.onpacket (/volumio/app/plugins/music_service/inputs/node_modules/socket.io-client/lib/socket.js:228:12) May 13 18:48:17 motivo volumio[9096]: at Manager. (/volumio/app/plugins/music_service/inputs/node_modules/component-bind/index.js:21:15) May 13 18:48:17 motivo volumio[9096]: at Manager.Emitter.emit (/volumio/app/plugins/music_service/inputs/node_modules/component-emitter/index.js:133:20) May 13 18:48:17 motivo volumio[9096]: at Manager.ondecoded (/volumio/app/plugins/music_service/inputs/node_modules/socket.io-client/lib/manager.js:332:8) May 13 18:48:17 motivo volumio[9096]: at Decoder. (/volumio/app/plugins/music_service/inputs/node_modules/component-bind/index.js:21:15) May 13 18:48:17 motivo volumio[9096]: at Decoder.Emitter.emit (/volumio/app/plugins/music_service/inputs/node_modules/socket.io-parser/node_modules/component-emitter/index.js:134:20) May 13 18:48:17 motivo volumio[9096]: at Decoder.add (/volumio/app/plugins/music_service/inputs/node_modules/socket.io-parser/index.js:246:12) May 13 18:48:17 motivo volumio[9096]: at Manager.ondata (/volumio/app/plugins/music_service/inputs/node_modules/socket.io-client/lib/manager.js:322:16) May 13 18:48:17 motivo volumio[9096]: at Socket. (/volumio/app/plugins/music_service/inputs/node_modules/component-bind/index.js:21:15) May 13 18:48:17 motivo volumio[9096]: at Socket.Emitter.emit (/volumio/app/plugins/music_service/inputs/node_modules/component-emitter/index.js:133:20) May 13 18:48:17 motivo volumio[9096]: at Socket.onPacket (/volumio/app/plugins/music_service/inputs/node_modules/engine.io-client/lib/socket.js:451:14) May 13 18:48:17 motivo volumio[9096]: at XHR. (/volumio/app/plugins/music_service/inputs/node_modules/engine.io-client/lib/socket.js:268:10) May 13 18:48:17 motivo volumio[9096]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 13 18:48:17 motivo sudo[9397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-13 18:47 May 13 18:48:17 motivo sudo[9397]: 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="f369932bd5d09b9dd487ca855ac180907702d5dd" VOLUMIO_FE_VERSION="4046dddc0717a69457a40270e2fcbc46f9ee4c24" VOLUMIO_FE3_VERSION="19db4344fa87f8eaaab1560f7319710ca849c94e" VOLUMIO_BE_VERSION="437399a0cf0d1d39f1016560887242f6a32ebd7e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="motivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 13 May 2024 04:37:53 PM CEST" VOLUMIO_VERSION="3.673" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Motivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Motivo" VOLUMIO_HASH="84f51602ba028a4cd1bca352ebf339fe"