-- 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"