-- Logs begin at Fri 2024-11-15 03:19:43 CET, end at Fri 2025-01-17 17:17:43 CET. -- Jan 17 17:16:28 primo systemd-timedated[3406]: Changed local time to Fri Jan 17 17:16:28 2025 Jan 17 17:16:28 primo sudo[3404]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:28 primo systemd[1]: Starting Daily man-db regeneration... Jan 17 17:16:28 primo volumio-time-update[2826]: volumio-time-update-util: System time updated successfully. Jan 17 17:16:28 primo systemd[1]: Starting Daily apt download activities... Jan 17 17:16:28 primo systemd[1]: Started Volumio Time Update Utility. Jan 17 17:16:28 primo systemd[1]: Reached target Multi-User System. Jan 17 17:16:28 primo systemd[1]: Reached target Graphical Interface. Jan 17 17:16:28 primo systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 17 17:16:28 primo volumio[3259]: info: MYVOLUMIO Environment detected Jan 17 17:16:28 primo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 17 17:16:28 primo systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 17 17:16:28 primo systemd[1]: man-db.service: Succeeded. Jan 17 17:16:28 primo systemd[1]: Started Daily man-db regeneration. Jan 17 17:16:28 primo volumio[3259]: info: Plugin folders cleanup Jan 17 17:16:28 primo volumio[3259]: info: Scanning into folder /volumio/app/plugins/ Jan 17 17:16:28 primo volumio[3259]: info: Scanning category audio_interface Jan 17 17:16:28 primo volumio[3259]: info: Scanning category miscellanea Jan 17 17:16:28 primo volumio[3259]: info: Scanning category music_service Jan 17 17:16:28 primo volumio[3259]: info: Scanning category plugins.json Jan 17 17:16:28 primo volumio[3259]: info: Scanning category system_controller Jan 17 17:16:28 primo volumio[3259]: info: Scanning category user_interface Jan 17 17:16:28 primo volumio[3259]: info: Scanning into folder /data/plugins/ Jan 17 17:16:28 primo volumio[3259]: info: Plugin folders cleanup completed Jan 17 17:16:28 primo volumio[3259]: info: ------------------------------------------- Jan 17 17:16:28 primo volumio[3259]: info: ----- Core plugins startup ---- Jan 17 17:16:28 primo volumio[3259]: info: ------------------------------------------- Jan 17 17:16:28 primo volumio[3259]: info: Loading plugins from folder /volumio/app/plugins/ Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin bluetooth to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin multiroom to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin upnp to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin metavolumio to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin cd_controller to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin raat to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 17 17:16:28 primo volumio[3259]: info: Loading plugins from folder /data/plugins/ Jan 17 17:16:28 primo volumio[3259]: info: Loading plugin "system"... Jan 17 17:16:28 primo volumio[3259]: info: Loading plugin "appearance"... Jan 17 17:16:29 primo systemd[1]: apt-daily.service: Succeeded. Jan 17 17:16:29 primo systemd[1]: Started Daily apt download activities. Jan 17 17:16:29 primo systemd[1]: Starting Daily apt upgrade and clean activities... Jan 17 17:16:29 primo systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 17 17:16:29 primo systemd[1]: Started Daily apt upgrade and clean activities. Jan 17 17:16:29 primo systemd[1]: Startup finished in 12.924s (kernel) + 26.716s (userspace) = 39.641s. Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "network"... Jan 17 17:16:30 primo volumio[3259]: info: Refreshing Cached IP Addresses Jan 17 17:16:30 primo sudo[3521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 17:16:30 primo sudo[3521]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:30 primo sudo[3523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 17:16:30 primo sudo[3523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "services"... Jan 17 17:16:30 primo sudo[3521]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "alsa_controller"... Jan 17 17:16:30 primo sudo[3523]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:30 primo sudo[3531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 17 17:16:30 primo sudo[3531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:30 primo kernel: wl_escan_set_scan: ioctl_ver=2 Jan 17 17:16:30 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:30 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:30 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "wizard"... Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "networkfs"... Jan 17 17:16:30 primo volumio[3259]: info: Starting Udev Watcher for removable devices Jan 17 17:16:30 primo sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=johannes,password=kousseri4,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.132.21/media/music /mnt/NAS/roonplex Jan 17 17:16:30 primo sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:30 primo volumio[3259]: info: Ignoring mount for partition: BOOT Jan 17 17:16:30 primo volumio[3259]: info: Ignoring mount for partition: volumio Jan 17 17:16:30 primo volumio[3259]: info: Ignoring mount for partition: volumio_data Jan 17 17:16:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "volumio_command_line_client"... Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "upnp"... Jan 17 17:16:30 primo volumio[3259]: info: [1737130590740] Starting Upmpd Daemon Jan 17 17:16:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "my_music"... Jan 17 17:16:30 primo volumio[3259]: info: Loading plugin "mpd"... Jan 17 17:16:30 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo volumio[3259]: info: Loading plugin "upnp_browser"... Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:31 primo volumio[3259]: info: Loading plugin "bluetooth"... Jan 17 17:16:31 primo volumio-remote-updater[2817]: [2025-01-17 17:16:31] [connect] Successful connection Jan 17 17:16:32 primo volumio[3259]: info: [1737130592117] Starting BluetoothController Jan 17 17:16:32 primo volumio[3259]: info: Loading plugin "alarm-clock"... Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:32 primo volumio[3259]: info: Loading plugin "manifestui"... Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:32 primo volumio[3259]: info: Loading plugin "metavolumio"... Jan 17 17:16:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 8 Jan 17 17:16:34 primo volumio[3259]: info: Loading plugin "airplay_emulation"... Jan 17 17:16:34 primo volumio[3259]: info: Starting Shairport Sync Jan 17 17:16:34 primo volumio[3259]: info: Loading plugin "cd_controller"... Jan 17 17:16:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce9a000 drv_status 0x0 e_type 69 e_states 0 Jan 17 17:16:34 primo sudo[3531]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:35 primo volumio[3259]: info: Loading plugin "last_100"... Jan 17 17:16:35 primo volumio[3259]: info: Loading plugin "raat"... Jan 17 17:16:35 primo volumio[3259]: info: RAAT Plugin loaded Jan 17 17:16:35 primo volumio[3259]: info: Adding restartRAATSocket REST API Endpoint Jan 17 17:16:35 primo volumio[3259]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jan 17 17:16:35 primo volumio[3259]: info: Loading plugin "streaming_services"... Jan 17 17:16:36 primo systemd[1]: systemd-fsckd.service: Succeeded. Jan 17 17:16:37 primo volumio[3259]: info: Starting Streaming Service Transparent Proxy Jan 17 17:16:37 primo volumio[3259]: info: Plugin tidalconnect is not enabled Jan 17 17:16:37 primo volumio[3259]: info: Loading plugin "webradio"... Jan 17 17:16:38 primo volumio[3259]: info: Loading plugin "i2s_dacs"... Jan 17 17:16:38 primo volumio[3259]: info: I2S DAC not set, start Auto-detection Jan 17 17:16:38 primo volumio[3259]: info: Loading plugin "volumiodiscovery"... Jan 17 17:16:38 primo volumio[3259]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 17 17:16:38 primo node[3259]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 17 17:16:38 primo node[3259]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 17:16:38 primo volumio[3259]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 17:16:38 primo volumio[3259]: *** WARNING *** For more information see Jan 17 17:16:38 primo volumio[3259]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 17 17:16:38 primo volumio[3259]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 17:16:38 primo volumio[3259]: *** WARNING *** For more information see Jan 17 17:16:38 primo node[3259]: *** WARNING *** For more information see Jan 17 17:16:38 primo node[3259]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 17 17:16:38 primo node[3259]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 17 17:16:38 primo node[3259]: *** WARNING *** For more information see Jan 17 17:16:38 primo volumio[3259]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 17 17:16:38 primo volumio[3259]: info: Discovery: Started advertising with name: Primo Jan 17 17:16:38 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 17:16:38 primo volumio[3259]: info: Loading plugin "multiroom"... Jan 17 17:16:38 primo sh[2745]: timed out Jan 17 17:16:38 primo sh[2745]: dhcpcd exited Jan 17 17:16:38 primo dhcpcd[2793]: timed out Jan 17 17:16:38 primo dhcpcd[2793]: dhcpcd exited Jan 17 17:16:38 primo sh[2745]: ifup: failed to bring up eth0 Jan 17 17:16:38 primo systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 17 17:16:38 primo systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 17 17:16:39 primo volumio[3259]: STREAMING PROXY: Starting server on port 3245 Jan 17 17:16:39 primo volumio[3259]: Node JS runtime: 14 Jan 17 17:16:40 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jan 17 17:16:40 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 Jan 17 17:16:40 primo sudo[3554]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:41 primo volumio[3259]: info: Applying required configuration parameters for plugin multiroom Jan 17 17:16:41 primo sudo[3740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 17 17:16:41 primo sudo[3740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:41 primo sudo[3740]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:41 primo volumio[3259]: info: MRS: MultiRoom plugin initialized Jan 17 17:16:41 primo volumio[3259]: info: MRS: STOPPING SNAPCLIENT Jan 17 17:16:41 primo volumio[3259]: info: MRS: Snap server stop Jan 17 17:16:41 primo sudo[3756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 17 17:16:41 primo volumio[3259]: info: MRS: STOPPING volumioStreaming Jan 17 17:16:41 primo sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:41 primo sudo[3758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 17 17:16:41 primo sudo[3758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:41 primo sudo[3756]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:41 primo sudo[3762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 17 17:16:41 primo sudo[3762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:41 primo volumio[3259]: info: Loading plugin "outputs"... Jan 17 17:16:41 primo volumio[3259]: info: Loading plugin "albumart"... Jan 17 17:16:41 primo sudo[3765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 17 17:16:41 primo sudo[3765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:41 primo sudo[3765]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:41 primo sudo[3758]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:41 primo volumio[3259]: info: Plugin example_plugin is not enabled Jan 17 17:16:41 primo volumio[3259]: info: Loading plugin "hi_res_audio"... Jan 17 17:16:41 primo sudo[3762]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:41 primo volumio[3259]: Forking 3 albumart workers Jan 17 17:16:43 primo volumio[3259]: Starting albumart workers Jan 17 17:16:43 primo volumio[3259]: Starting albumart workers Jan 17 17:16:43 primo volumio[3259]: Starting albumart workers Jan 17 17:16:43 primo volumio[3259]: info: Applying required configuration parameters for plugin hi_res_audio Jan 17 17:16:43 primo volumio[3259]: info: Loading plugin "inputs"... Jan 17 17:16:44 primo volumio[3259]: info: Loading plugin "qobuz"... Jan 17 17:16:46 primo volumio[3259]: info: Loading plugin "smart_inputs"... Jan 17 17:16:46 primo volumio[3259]: info: Loading plugin "tidal"... Jan 17 17:16:46 primo volumio-remote-updater[2817]: [2025-01-17 17:16:46] [connect] Successful connection Jan 17 17:16:48 primo volumio[3259]: info: Loading plugin "primo2rev2control"... Jan 17 17:16:48 primo volumio[3259]: info: Adding this device properties Jan 17 17:16:48 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jan 17 17:16:48 primo volumio[3259]: info: Setting Additional Device Volumio Properties: [object Object] Jan 17 17:16:48 primo volumio[3259]: info: Loading plugin "updater_comm"... Jan 17 17:16:48 primo systemd[1]: systemd-hostnamed.service: Succeeded. Jan 17 17:16:48 primo volumio[3259]: info: Loading plugin "cec_controller"... Jan 17 17:16:49 primo volumio[3259]: info: Applying required configuration parameters for plugin cec_controller Jan 17 17:16:49 primo volumio[3259]: info: Plugin mpdemulation is not enabled Jan 17 17:16:49 primo volumio[3259]: info: Loading plugin "rest_api"... Jan 17 17:16:49 primo volumio[3259]: info: Loading plugin "websocket"... Jan 17 17:16:49 primo volumio[3259]: info: Starting Socket.io Server version 2.3.0 Jan 17 17:16:49 primo volumio[3259]: info: Loading i18n strings for locale de Jan 17 17:16:49 primo volumio[3259]: Updating browse sources language Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::initPlayerControls Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:16:49 primo volumio[3259]: Express server listening on port 3000 Jan 17 17:16:49 primo volumio[3259]: [Metrics] WebUI: 24s 132.31ms Jan 17 17:16:49 primo volumio[3259]: info: CoreStateMachine::resetVolumioState Jan 17 17:16:49 primo volumio[3259]: info: CoreStateMachine::getcurrentVolume Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::volumioRetrievevolume Jan 17 17:16:49 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:49 primo sudo[3835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 17:16:49 primo sudo[3835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:49 primo sudo[3835]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:49 primo volumio[3259]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jan 17 17:16:49 primo sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 17:16:49 primo volumio[3259]: info: Completed loading Core Plugins Jan 17 17:16:49 primo sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:49 primo volumio[3259]: info: Preparing to generate the ALSA configuration file Jan 17 17:16:49 primo sudo[3837]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:49 primo volumio[3259]: info: Volumio Network Manager: Network status updated: 2 Jan 17 17:16:49 primo volumio[3259]: Unable to parse: Jan 17 17:16:49 primo volumio[3259]: Simple mixer control 'Audio hdmi-out mute',0 Jan 17 17:16:49 primo volumio[3259]: Capabilities: pswitch pswitch-joined Jan 17 17:16:49 primo volumio[3259]: Playback channels: Mono Jan 17 17:16:49 primo volumio[3259]: Mono: Playback [on] Jan 17 17:16:49 primo volumio[3259]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 17 17:16:50 primo volumio[3259]: info: MRS: Removed streaming files Jan 17 17:16:50 primo volumio[3259]: info: MRS: volumioStreaming STOPPED Jan 17 17:16:50 primo volumio[3259]: info: MRS: SNAPSERVER STOPPED Jan 17 17:16:50 primo volumio[3259]: info: MRS: SNAPCLIENT STOPPED Jan 17 17:16:50 primo volumio[3259]: info: Cannot mount NAS roonplex at system boot, trial number 1 ,retrying in 5 seconds Jan 17 17:16:50 primo volumio[3259]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jan 17 17:16:50 primo volumio[3259]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 17 17:16:50 primo volumio[3259]: info: Reading ALSA contributions from plugins. Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Jan 17 17:16:50 primo volumio-remote-updater[2817]: [2025-01-17 17:16:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737130606 101 Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 17 17:16:50 primo volumio[3259]: Unable to parse: Jan 17 17:16:50 primo volumio[3259]: Simple mixer control 'Audio hdmi-out mute',0 Jan 17 17:16:50 primo volumio[3259]: Capabilities: pswitch pswitch-joined Jan 17 17:16:50 primo volumio[3259]: Playback channels: Mono Jan 17 17:16:50 primo volumio[3259]: Mono: Playback [on] Jan 17 17:16:50 primo volumio[3259]: info: VolumeController:: Volume=undefined Mute =false Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:50 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::updateTrackBlock Jan 17 17:16:50 primo volumio[3259]: info: CorePlayQueue::getTrackBlock Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::volumioRetrievevolume Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::setRepeat null single undefined Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:50 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::setRandom null Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:50 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Jan 17 17:16:50 primo volumio[3259]: Unable to parse: Jan 17 17:16:50 primo volumio[3259]: Simple mixer control 'Audio hdmi-out mute',0 Jan 17 17:16:50 primo volumio[3259]: Capabilities: pswitch pswitch-joined Jan 17 17:16:50 primo volumio[3259]: Playback channels: Mono Jan 17 17:16:50 primo volumio[3259]: Mono: Playback [on] Jan 17 17:16:50 primo volumio[3259]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 5 Jan 17 17:16:50 primo volumio[3259]: info: Discovery: adding 27574849-2b31-4315-adb3-b1b6f8d0fdf3 Jan 17 17:16:50 primo volumio[3259]: info: Discovery: Found device Primo Jan 17 17:16:50 primo sudo[3863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 17 17:16:50 primo sudo[3863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:16:50 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:50 primo volumio[3259]: info: MRS: Pushing multiroomSync output for this device Jan 17 17:16:50 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:16:50 primo volumio[3259]: info: Adding audio output: Jan 17 17:16:50 primo volumio[3259]: info: Adding audio output: Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Jan 17 17:16:50 primo volumio[3259]: Unable to parse: Jan 17 17:16:50 primo volumio[3259]: Simple mixer control 'Audio hdmi-out mute',0 Jan 17 17:16:50 primo volumio[3259]: Capabilities: pswitch pswitch-joined Jan 17 17:16:50 primo volumio[3259]: Playback channels: Mono Jan 17 17:16:50 primo volumio[3259]: Mono: Playback [on] Jan 17 17:16:50 primo volumio[3259]: info: VolumeController:: Volume=undefined Mute =false Jan 17 17:16:50 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:50 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:50 primo systemd[1]: Started UPnP Renderer front-end to MPD. Jan 17 17:16:50 primo sudo[3863]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:50 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Jan 17 17:16:50 primo volumio[3259]: info: Upmpdcli Daemon Started Jan 17 17:16:50 primo volumio[3259]: info: Asound.conf file unchanged, so no further update is needed Jan 17 17:16:50 primo volumio[3259]: info: Output device has changed, restarting MPD Jan 17 17:16:50 primo sudo[3881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 17:16:50 primo sudo[3881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:50 primo volumio[3259]: info: Output device has changed, restarting Shairport Sync Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:50 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:50 primo sudo[3881]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:51 primo sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 17:16:51 primo sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo volumio[3259]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 17:16:51 primo volumio[3259]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:51 primo systemd[1]: Stopping Music Player Daemon... Jan 17 17:16:51 primo volumio[3259]: info: ___________ START PLUGINS ___________ Jan 17 17:16:51 primo volumio[3259]: info: ControllerMpd::onStart: Initializing MPD Jan 17 17:16:51 primo volumio[3259]: info: Creating MPD Configuration file Jan 17 17:16:51 primo kernel: aml_tdm_open Jan 17 17:16:51 primo kernel: Not init audio effects Jan 17 17:16:51 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 17 17:16:51 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jan 17 17:16:51 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jan 17 17:16:51 primo sudo[3892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 17 17:16:51 primo sudo[3892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo sudo[3892]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 17:16:51 primo volumio[3259]: info: [1737130611210] CoreMusicLibrary::Adding element Medienserver Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:16:51 primo sudo[3896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 17 17:16:51 primo sudo[3896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 17:16:51 primo systemd[1]: mpd.service: Succeeded. Jan 17 17:16:51 primo systemd[1]: Stopped Music Player Daemon. Jan 17 17:16:51 primo systemd[1]: Starting Music Player Daemon... Jan 17 17:16:51 primo volumio[3259]: info: Adding METAVOLUMIO REST API Endpoints Jan 17 17:16:51 primo volumio[3259]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 17 17:16:51 primo volumio[3259]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 17 17:16:51 primo volumio[3259]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 17 17:16:51 primo volumio[3259]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:51 primo sudo[3899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Primo Jan 17 17:16:51 primo sudo[3899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo sudo[3899]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:51 primo volumio[3259]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 17 17:16:51 primo volumio[3259]: info: Preparing CD Folders Jan 17 17:16:51 primo volumio[3259]: info: Adding CD REST API Endpoints Jan 17 17:16:51 primo volumio[3259]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 17 17:16:51 primo volumio[3259]: info: Starting UDEV Watcher for CD Jan 17 17:16:51 primo volumio[3259]: info: Detecting CD presence with UDEV Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 17 17:16:51 primo sudo[3900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 17:16:51 primo sudo[3900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo sudo[3900]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 17:16:51 primo volumio[3259]: info: [1737130611503] CoreMusicLibrary::Adding element Last_100 Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:16:51 primo volumio[3259]: info: Starting RAAT Plugin Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jan 17 17:16:51 primo volumio[3259]: info: Additional UI Settings Added for plugin music_service/raat Jan 17 17:16:51 primo volumio[3259]: info: Registering DSP Elements listener and retrieving current ones Jan 17 17:16:51 primo volumio[3259]: info: Additional DSP elements updated Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:16:51 primo volumio[3259]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 17 17:16:51 primo volumio[3259]: info: Streaming services startup Jan 17 17:16:51 primo volumio[3259]: info: Starting Streaming Daemon Jan 17 17:16:51 primo sudo[3913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 17 17:16:51 primo sudo[3913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo sudo[3913]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 17:16:51 primo volumio[3259]: info: [1737130611649] CoreMusicLibrary::Adding element Webradio Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:16:51 primo volumio[3259]: info: Initializing BBC Radios Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 17 17:16:51 primo volumio[3259]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 17 17:16:51 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:51 primo volumio[3259]: error: Hi Res Audio Failed Login: Missing Login Data Jan 17 17:16:51 primo volumio[3259]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 17 17:16:51 primo volumio[3259]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 17 17:16:51 primo volumio[3259]: info: Initializing Serial Communication on port /dev/ttyS3 Jan 17 17:16:51 primo volumio[3259]: info: Touch Event Listener Process Starting Jan 17 17:16:51 primo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jan 17 17:16:51 primo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jan 17 17:16:51 primo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jan 17 17:16:51 primo kernel: aml_tdm_open Jan 17 17:16:51 primo kernel: Not init audio effects Jan 17 17:16:51 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 17 17:16:51 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jan 17 17:16:51 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jan 17 17:16:51 primo volumio[3259]: info: Refreshing QOBUZ token Jan 17 17:16:51 primo sudo[3934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jan 17 17:16:51 primo sudo[3934]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:51 primo volumio[3259]: info: Adding inputs REST Endpoints Jan 17 17:16:51 primo volumio[3259]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 17 17:16:51 primo volumio[3259]: info: Scanning Audio Inputs Jan 17 17:16:51 primo volumio[3259]: info: Checking against Known Cards name Jan 17 17:16:52 primo volumio[3259]: info: Checking against Known Cards name Jan 17 17:16:52 primo volumio[3259]: info: Checking against Known Cards name Jan 17 17:16:52 primo volumio[3259]: info: Checking against Known Cards name Jan 17 17:16:52 primo volumio[3259]: info: Adding Server instance for streaming Jan 17 17:16:52 primo sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jan 17 17:16:52 primo sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:52 primo sudo[3942]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:52 primo sudo[3945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jan 17 17:16:52 primo sudo[3945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:52 primo sudo[3945]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:52 primo sudo[3948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jan 17 17:16:52 primo sudo[3948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:52 primo sudo[3948]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:52 primo sudo[3951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jan 17 17:16:52 primo sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:52 primo sudo[3951]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:52 primo volumio[3259]: info: MP1 Muting HDMI Output Jan 17 17:16:52 primo volumio[3259]: info: Starting CEC Event listener Jan 17 17:16:52 primo volumio[3259]: info: Volumio Calling Home Jan 17 17:16:52 primo volumio[3259]: (node:3259) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jan 17 17:16:52 primo volumio[3259]: (Use `node --trace-deprecation ...` to show where the warning was created) Jan 17 17:16:52 primo volumio[3259]: info: Stopping AccessToken refresher cron Jan 17 17:16:52 primo kernel: fb: mem_free_work, free memory: addr:800000 Jan 17 17:16:52 primo volumio[3259]: info: AccessToken refresher cron started Jan 17 17:16:52 primo volumio[3259]: info: Adding TIDAL REST API Endpoints Jan 17 17:16:52 primo volumio[3259]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 17 17:16:52 primo volumio[3259]: info: Serial port opened successfully Jan 17 17:16:52 primo volumio[3259]: info: Sending serial start messages Jan 17 17:16:52 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:52 primo volumio[3259]: info: Reporting MCU Network Status: 2 Jan 17 17:16:53 primo volumio[3259]: error: Cannot start Volumio Streaming Daemon Jan 17 17:16:53 primo volumio[3259]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 17 17:16:53 primo volumio[3259]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 17 17:16:53 primo volumio[3259]: info: RAAT Albumart path created successfully Jan 17 17:16:53 primo volumio[3259]: info: Bluetooth name changed to Primo Jan 17 17:16:53 primo volumio[3259]: info: MPD Permissions set Jan 17 17:16:53 primo volumio[3259]: info: MPD Permissions set Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jan 17 17:16:53 primo volumio[3259]: info: Setting Device Volume Override Jan 17 17:16:53 primo volumio[3259]: info: Applying Volume Override Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 17 17:16:53 primo volumio[3259]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 17 17:16:53 primo volumio[3259]: info: Enabling external Volume Control Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:53 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:53 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:16:53 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:16:53 primo volumio[3259]: info: Volumio called home Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 17 17:16:53 primo volumio[3259]: info: Setting Additional System Software info: Hardware Revision: 2.1 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 17 17:16:53 primo volumio[3259]: info: Setting HW Firmware info: undefined Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 17 17:16:53 primo volumio[3259]: info: Setting HW Version info: 2.1 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jan 17 17:16:53 primo volumio[3259]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.7 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jan 17 17:16:53 primo volumio[3259]: info: Setting HW Firmware info: 0.3.7 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jan 17 17:16:53 primo volumio[3259]: info: Setting HW Version info: 2.1 Jan 17 17:16:53 primo volumio[3259]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jan 17 17:16:53 primo volumio[3259]: info: MCU Signalled Headphone Mode Disabled Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 17 17:16:53 primo volumio[3259]: info: Reporting Headphone State: false Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:16:53 primo volumio[3259]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jan 17 17:16:53 primo volumio[3259]: info: MCU Signalled Sleep Mode Disabled Jan 17 17:16:53 primo volumio[3259]: info: Enabling Advanced system settings configuration Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jan 17 17:16:53 primo volumio[3259]: info: Additional UI Settings Added for plugin music_service/inputs Jan 17 17:16:53 primo volumio[3259]: info: MCU Signalled Auto Boot Mode On Power Disabled Jan 17 17:16:53 primo sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jan 17 17:16:53 primo sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:53 primo kernel: fb: osd[0] enable: 1 (Xorg) Jan 17 17:16:53 primo sudo[3975]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:53 primo volumio[3259]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:16:53 primo volumio[3259]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jan 17 17:16:53 primo volumio[3259]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jan 17 17:16:53 primo volumio[3259]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jan 17 17:16:53 primo sudo[3993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jan 17 17:16:53 primo sudo[3993]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:53 primo sudo[3993]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:53 primo volumio[3259]: info: Access Token successfully retrieved Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 17 17:16:53 primo volumio[3259]: info: [1737130613817] CoreMusicLibrary::Adding element QOBUZ Jan 17 17:16:53 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:16:53 primo volumio[3259]: Cannot find translation for source QOBUZ Jan 17 17:16:53 primo volumio[3259]: info: Stopping AccessToken refresher cron for QOBUZ Jan 17 17:16:53 primo volumio[3259]: info: AccessToken refresher cron started for QOBUZ Jan 17 17:16:53 primo volumio[3259]: info: Adding QOBUZ REST API Endpoints Jan 17 17:16:53 primo volumio[3259]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 17 17:16:54 primo volumio[3259]: info: Starting Shairport Sync Jan 17 17:16:54 primo volumio[3259]: info: Starting Shairport Sync Jan 17 17:16:54 primo mpd[3909]: Jan 17 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 17:16:54 primo sudo[3996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 17:16:54 primo sudo[3996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:54 primo volumio[3259]: info: Starting Shairport Sync Jan 17 17:16:54 primo sudo[3999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 17:16:54 primo sudo[3999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:54 primo volumio[3259]: info: Adding Advanced Audio Settings via Serial API Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jan 17 17:16:54 primo volumio[3259]: info: Additional UI Settings Added for plugin music_service/inputs Jan 17 17:16:54 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 17:16:54 primo systemd[1]: shairport-sync.service: Succeeded. Jan 17 17:16:54 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 17:16:54 primo volumio[3259]: info: Adding Inputs via Serial API Jan 17 17:16:54 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:54 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:54 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 17:16:54 primo sudo[3996]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:54 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:16:54 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:16:54 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:16:54 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:16:54 primo sudo[4001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 17 17:16:54 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:16:54 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:16:54 primo sudo[4001]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:54 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 17:16:54 primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 17 17:16:54 primo systemd[1]: shairport-sync.service: Succeeded. Jan 17 17:16:54 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:16:54 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:54 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 17:16:54 primo sudo[3999]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:16:54 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:54 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 17 17:16:54 primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 17 17:16:54 primo systemd[1]: shairport-sync.service: Succeeded. Jan 17 17:16:54 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 17 17:16:54 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 17 17:16:54 primo sudo[4001]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:54 primo volumio[3259]: info: Shairport-Sync Started Jan 17 17:16:54 primo volumio[3259]: Error adding Membership: Error: addMembership EINVAL Jan 17 17:16:54 primo sudo[4016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 17 17:16:54 primo sudo[4016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:54 primo volumio[3259]: info: Shairport-Sync Started Jan 17 17:16:54 primo volumio[3259]: info: Shairport-Sync Started Jan 17 17:16:54 primo systemd[1]: Started Volumio Bluetooth Module. Jan 17 17:16:54 primo sudo[4016]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:54 primo sudo[4019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 17 17:16:54 primo sudo[4019]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:54 primo volumiobt[4018]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 17 17:16:54 primo sudo[4019]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:16:54 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:16:54 primo volumio[3259]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Jan 17 17:16:54 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:16:54 primo volumio[3259]: info: CoreStateMachine::syncState stateService play Jan 17 17:16:54 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jan 17 17:16:54 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:16:54 primo volumio[3259]: Cannot find translation for source QOBUZ Jan 17 17:16:54 primo volumio[3259]: info: MCU Signalled Playback Inactive Jan 17 17:16:54 primo volumio[3259]: info: Volumio BT Module successfully started Jan 17 17:16:54 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 17 17:16:54 primo systemd[1]: mpd.service: Succeeded. Jan 17 17:16:54 primo volumio[3259]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 17 17:16:54 primo systemd[1]: Stopped Music Player Daemon. Jan 17 17:16:54 primo volumio[3259]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 17 17:16:54 primo systemd[1]: Starting Music Player Daemon... Jan 17 17:16:54 primo volumio[3259]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 17 17:16:54 primo volumio[3259]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 17 17:16:55 primo sudo[4024]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 17 17:16:55 primo sudo[4024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:55 primo sudo[4024]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:55 primo sudo[4028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=johannes,password=kousseri4,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.132.21/media/music /mnt/NAS/roonplex Jan 17 17:16:55 primo sudo[4028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:55 primo volumio[3259]: info: Initializing I2S Bus Jan 17 17:16:55 primo volumio[3259]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 10 Jan 17 17:16:55 primo kernel: aml_tdm_open Jan 17 17:16:55 primo kernel: Not init audio effects Jan 17 17:16:55 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 17 17:16:55 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jan 17 17:16:55 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050311018, id(1), clksel(1) Jan 17 17:16:55 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Jan 17 17:16:55 primo kernel: dump_pcm_setting(ffffffc050311018) Jan 17 17:16:55 primo kernel: pcm_mode(1) Jan 17 17:16:55 primo kernel: sysclk(11289600) Jan 17 17:16:55 primo kernel: sysclk_bclk_ratio(4) Jan 17 17:16:55 primo kernel: bclk(2822400) Jan 17 17:16:55 primo kernel: bclk_lrclk_ratio(64) Jan 17 17:16:55 primo kernel: lrclk(44100) Jan 17 17:16:55 primo kernel: tx_mask(0x3) Jan 17 17:16:55 primo kernel: rx_mask(0x3) Jan 17 17:16:55 primo kernel: slots(2) Jan 17 17:16:55 primo kernel: slot_width(32) Jan 17 17:16:55 primo kernel: lane_mask_in(0x2) Jan 17 17:16:55 primo kernel: lane_mask_out(0x1) Jan 17 17:16:55 primo kernel: lane_oe_mask_in(0x0) Jan 17 17:16:55 primo kernel: lane_oe_mask_out(0x0) Jan 17 17:16:55 primo kernel: lane_lb_mask_in(0x0) Jan 17 17:16:55 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jan 17 17:16:55 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jan 17 17:16:55 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jan 17 17:16:55 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jan 17 17:16:55 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Jan 17 17:16:55 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jan 17 17:16:55 primo kernel: aml_tdm_prepare(), reset fddr Jan 17 17:16:55 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jan 17 17:16:55 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 17 17:16:55 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jan 17 17:16:55 primo kernel: tdm playback mute: 0, lane_cnt = 8 Jan 17 17:16:55 primo kernel: asoc-aml-card auge_sound: tdm playback enable Jan 17 17:16:55 primo kernel: spdif_a is set to enable Jan 17 17:16:55 primo sudo[4036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 17 17:16:55 primo sudo[4036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:16:55 primo volumiobt[4018]: pulseaudio: no process found Jan 17 17:16:55 primo sudo[4036]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:55 primo dbus-daemon[2838]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=4040 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jan 17 17:16:55 primo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 17 17:16:55 primo kernel: IPVS: Creating netns size=1928 id=3 Jan 17 17:16:55 primo volumio[3259]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 17 17:16:55 primo dbus-daemon[2838]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 17 17:16:55 primo systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 17 17:16:55 primo rtkit-daemon[4041]: Successfully called chroot. Jan 17 17:16:55 primo rtkit-daemon[4041]: Successfully dropped privileges. Jan 17 17:16:55 primo rtkit-daemon[4041]: Successfully limited resources. Jan 17 17:16:55 primo rtkit-daemon[4041]: Running. Jan 17 17:16:55 primo rtkit-daemon[4041]: Watchdog thread running. Jan 17 17:16:55 primo rtkit-daemon[4041]: Canary thread running. Jan 17 17:16:55 primo dbus-daemon[2838]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=4041 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jan 17 17:16:55 primo systemd[1]: Starting Authorization Manager... Jan 17 17:16:56 primo volumio[3259]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 17 17:16:56 primo polkitd[4044]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 17 17:16:56 primo dbus-daemon[2838]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 17 17:16:56 primo systemd[1]: Started Authorization Manager. Jan 17 17:16:56 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 17:16:56 primo kernel: asoc-aml-card auge_sound: tdm playback stop Jan 17 17:16:56 primo kernel: spdif_a is set to disable Jan 17 17:16:56 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jan 17 17:16:56 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jan 17 17:16:56 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jan 17 17:16:56 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jan 17 17:16:56 primo volumio[3259]: info: Successfully initialized Primo I2S Bus Jan 17 17:16:56 primo pulseaudio[4040]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 17 17:16:56 primo pulseaudio[4040]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 17 17:16:56 primo bluetoothd[3190]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Jan 17 17:16:56 primo volumiobt[4018]: Applying permissions Jan 17 17:16:56 primo volumiobt[4018]: Setting BT discoverable and pairable Jan 17 17:16:56 primo bluetoothd[3190]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Jan 17 17:16:56 primo pulseaudio[4040]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jan 17 17:16:56 primo volumio[3259]: info: MRS: Getting audio outputs on start Jan 17 17:16:56 primo volumio[3259]: info: MRS: Requesting all other devices output Jan 17 17:16:56 primo volumiobt[4018]: Agent registered Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# -e power on Jan 17 17:16:56 primo volumiobt[4018]: Invalid command in menu main: -e Jan 17 17:16:56 primo volumiobt[4018]: Jan 17 17:16:56 primo volumiobt[4018]: Use "help" for a list of available commands in a menu. Jan 17 17:16:56 primo volumiobt[4018]: Use "menu " if you want to enter any submenu. Jan 17 17:16:56 primo volumiobt[4018]: Use "back" if you want to return to menu main. Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# agent on Jan 17 17:16:56 primo volumiobt[4018]: Agent is already registered Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# discoverable on Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# pairable on Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# agent NoInputNoOutput Jan 17 17:16:56 primo volumiobt[4018]: Agent is already registered Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# default-agent Jan 17 17:16:56 primo volumiobt[4018]: [bluetooth]# quit Jan 17 17:16:56 primo volumiobt[4018]: [59B blob data] Jan 17 17:16:57 primo mpd[4027]: Jan 17 17:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 17 17:16:58 primo volumio[3259]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 11 Jan 17 17:16:58 primo systemd[1]: systemd-timedated.service: Succeeded. Jan 17 17:16:58 primo systemd[1]: Started Music Player Daemon. Jan 17 17:16:58 primo volumio[3259]: Upnp client error: Error: This socket has been ended by the other party Jan 17 17:16:58 primo sudo[3884]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:58 primo sudo[3896]: pam_unix(sudo:session): session closed for user root Jan 17 17:16:58 primo mpd_monitor.sh[2843]: MPD restarted due to no mpc output. Jan 17 17:16:58 primo volumio[3259]: info: Completed starting Core Plugins Jan 17 17:16:58 primo volumio[3259]: info: ------------------------------------------- Jan 17 17:16:58 primo volumio[3259]: info: ----- MyVolumio plugins startup ---- Jan 17 17:16:58 primo volumio[3259]: info: ------------------------------------------- Jan 17 17:16:58 primo volumio[3259]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 17 17:16:59 primo volumio[3259]: error: MPD error: The expression evaluated to a falsy value: Jan 17 17:16:59 primo volumio[3259]: assert.ok(self.idling) Jan 17 17:16:59 primo volumio[3259]: error: The expression evaluated to a falsy value: Jan 17 17:16:59 primo volumio[3259]: assert.ok(self.idling) Jan 17 17:16:59 primo volumio[3259]: error: updateQueue error: null Jan 17 17:16:59 primo volumio[3259]: info: MPD running with PID4027 Jan 17 17:16:59 primo volumio[3259]: ,establishing connection Jan 17 17:16:59 primo volumio[3259]: error: updateQueue error: null Jan 17 17:16:59 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:00 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 17:17:00 primo volumio[3259]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 17:17:00 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:00 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:00 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:00 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:00 primo volumio[3259]: info: Listing playlists Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:00 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 17:17:00 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:00 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:00 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 17 17:17:00 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 17:17:01 primo volumio[3872]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 17 17:17:02 primo volumio[3259]: info: Checking for updated MCU Firmware Jan 17 17:17:02 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:17:02 primo volumio[3259]: info: Firware on device is on latest version, no need to update Jan 17 17:17:02 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 17 17:17:03 primo volumio[3872]: ..............................++++ Jan 17 17:17:04 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 17:17:04 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:04 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:04 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:04 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:04 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:04 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:04 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:04 primo volumio[3259]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 17 17:17:04 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 17 17:17:05 primo sudo[4028]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:05 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jan 17 17:17:05 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 Jan 17 17:17:06 primo volumio[3259]: info: Cannot mount NAS roonplex at system boot, trial number 2 ,retrying in 5 seconds Jan 17 17:17:09 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 17 17:17:11 primo volumio[3259]: Cannot compose Albumart path Jan 17 17:17:12 primo sudo[4156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=johannes,password=kousseri4,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.132.21/media/music /mnt/NAS/roonplex Jan 17 17:17:12 primo sudo[4156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 17 17:17:12 primo volumio[3259]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 17 17:17:14 primo volumio[3259]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 17 17:17:14 primo volumio[3259]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 17 17:17:14 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:14 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:14 primo volumio[3259]: info: Starting MyVolumio Remote Streaming Endpoints Jan 17 17:17:14 primo volumio[3259]: info: MyVolumio login type: Token Jan 17 17:17:14 primo volumio[3259]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 17 17:17:14 primo volumio[3259]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jan 17 17:17:14 primo volumio[3259]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 17 17:17:14 primo volumio[3872]: ...................................................................................................................................................++++ Jan 17 17:17:14 primo volumio[3872]: e is 65537 (0x010001) Jan 17 17:17:14 primo volumio[3872]: writing RSA key Jan 17 17:17:15 primo volumio[3259]: info: MyVolumio token set successfully Jan 17 17:17:15 primo volumio[3259]: info: MYVOLUMIO: Adding device Jan 17 17:17:15 primo volumio[3259]: info: MYVOLUMIO: Evaluating Server Jan 17 17:17:16 primo volumio[3259]: info: MyVolumio Plan changed: premium Jan 17 17:17:16 primo volumio[3259]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 17 17:17:16 primo volumio[3259]: info: Removing browser output: myVolumio user plan is not superstar Jan 17 17:17:16 primo volumio[3259]: info: Removing audio output: Jan 17 17:17:16 primo volumio[3259]: info: MYVOLUMIO: Adding device Jan 17 17:17:16 primo volumio[3259]: info: MYVOLUMIO: Evaluating Server Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 17:17:16 primo volumio[3259]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 17 17:17:16 primo volumio[3259]: info: Received Get System Version Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:17:16 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:16 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:16 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:16 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:16 primo volumio[3259]: info: Remote config written successfully Jan 17 17:17:16 primo volumio[3259]: info: Starting Tunnel 1 Jan 17 17:17:16 primo volumio[3259]: info: Starting Tunnel Connection Checker Jan 17 17:17:17 primo volumio[3259]: info: MYVolumio Device enabled Jan 17 17:17:17 primo volumio[3259]: info: MyVolumio status changed Jan 17 17:17:17 primo volumio[3259]: info: Streaming services startup Jan 17 17:17:17 primo volumio[3259]: info: Starting Streaming Daemon Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 17 17:17:17 primo sudo[4214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 17 17:17:17 primo sudo[4214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:17 primo volumio[3259]: info: Setting Geolocation for MyVolumio to eu4 Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:17 primo sudo[4214]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:17 primo volumio[3259]: error: Cannot start Volumio Streaming Daemon Jan 17 17:17:17 primo volumio[3259]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 17 17:17:17 primo volumio[3259]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Jan 17 17:17:17 primo volumio[3259]: info: Setting Geolocation for MyVolumio to eu10 Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:17 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:17 primo volumio[3259]: info: Successfully Added MyVolumio device Jan 17 17:17:17 primo volumio[3259]: info: Successfully Added MyVolumio device Jan 17 17:17:18 primo volumio[3259]: info: Updating MyVolumio device info Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo sudo[4217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 17:17:18 primo sudo[4217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:18 primo volumio[3259]: info: Updating MyVolumio device info Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo sudo[4217]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:18 primo sudo[4221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 17:17:18 primo sudo[4221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:18 primo volumio[3259]: info: Successfully Updated MyVolumio device Jan 17 17:17:18 primo sudo[4221]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:18 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:18 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 17:17:18 primo volumio[3259]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 17:17:18 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:18 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:18 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:18 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:18 primo volumio[3259]: info: Listing playlists Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 17:17:18 primo volumio[3259]: info: Successfully Updated MyVolumio device Jan 17 17:17:18 primo volumio[3259]: error: MyVolumio Plugin failed to start in a timely fashion Jan 17 17:17:18 primo volumio[3259]: [Metrics] CommandRouter: 52s 150.77ms Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::volumiosetStartupVolume Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:18 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:19 primo kernel: aml_tdm_open Jan 17 17:17:19 primo kernel: Not init audio effects Jan 17 17:17:19 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 17 17:17:19 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jan 17 17:17:19 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jan 17 17:17:19 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jan 17 17:17:19 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050311018, id(1), clksel(1) Jan 17 17:17:19 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Jan 17 17:17:19 primo kernel: dump_pcm_setting(ffffffc050311018) Jan 17 17:17:19 primo kernel: pcm_mode(1) Jan 17 17:17:19 primo kernel: sysclk(11289600) Jan 17 17:17:19 primo kernel: sysclk_bclk_ratio(4) Jan 17 17:17:19 primo kernel: bclk(2822400) Jan 17 17:17:19 primo kernel: bclk_lrclk_ratio(64) Jan 17 17:17:19 primo kernel: lrclk(44100) Jan 17 17:17:19 primo kernel: tx_mask(0x3) Jan 17 17:17:19 primo kernel: rx_mask(0x3) Jan 17 17:17:19 primo kernel: slots(2) Jan 17 17:17:19 primo kernel: slot_width(32) Jan 17 17:17:19 primo kernel: lane_mask_in(0x2) Jan 17 17:17:19 primo kernel: lane_mask_out(0x1) Jan 17 17:17:19 primo kernel: lane_oe_mask_in(0x0) Jan 17 17:17:19 primo kernel: lane_oe_mask_out(0x0) Jan 17 17:17:19 primo kernel: lane_lb_mask_in(0x0) Jan 17 17:17:19 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jan 17 17:17:19 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jan 17 17:17:19 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jan 17 17:17:19 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jan 17 17:17:19 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Jan 17 17:17:19 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jan 17 17:17:19 primo kernel: aml_tdm_prepare(), reset fddr Jan 17 17:17:19 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jan 17 17:17:19 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 17 17:17:19 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jan 17 17:17:19 primo kernel: tdm playback mute: 0, lane_cnt = 8 Jan 17 17:17:19 primo kernel: aml_tdm_prepare(), reset fddr Jan 17 17:17:19 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jan 17 17:17:19 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jan 17 17:17:19 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jan 17 17:17:19 primo kernel: tdm playback mute: 0, lane_cnt = 8 Jan 17 17:17:19 primo sudo[4231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 17:17:19 primo sudo[4231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:19 primo sudo[4231]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:19 primo sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 17:17:19 primo sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:19 primo sudo[4234]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:19 primo kernel: asoc-aml-card auge_sound: tdm playback enable Jan 17 17:17:19 primo kernel: spdif_a is set to enable Jan 17 17:17:19 primo volumio[3259]: verbose: New Socket.io Connection to 192.168.178.89 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.0.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:19 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 17:17:19 primo volumio[3259]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 17:17:19 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:19 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:19 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:19 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:19 primo volumio[3259]: info: Listing playlists Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 17 17:17:19 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 17 17:17:20 primo volumio[3259]: No protocol specified Jan 17 17:17:20 primo volumio[3259]: xcb_connection_has_error() returned true Jan 17 17:17:20 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 17 17:17:20 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:20 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 17:17:21 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:21 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:21 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 17 17:17:21 primo sudo[4262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 17 17:17:21 primo sudo[4262]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:21 primo systemd[1]: Started MyVolumio SSH Tunnel. Jan 17 17:17:21 primo sudo[4262]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:21 primo volumio[3259]: info: Remote SSH Started Jan 17 17:17:21 primo autossh[4268]: port set to 0, monitoring disabled Jan 17 17:17:21 primo autossh[4268]: starting ssh (count 1) Jan 17 17:17:21 primo autossh[4268]: ssh child pid is 4279 Jan 17 17:17:21 primo volumiossh-tunnel[4264]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. Jan 17 17:17:21 primo volumio[3259]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 17 17:17:21 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:21 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:21 primo volumiossh-tunnel[4264]: Warning: remote port forwarding failed for listen port 8091 Jan 17 17:17:22 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:17:22 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 17 17:17:22 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 17:17:22 primo volumio-remote-updater[2817]: No test mode Jan 17 17:17:22 primo volumio-remote-updater[2817]: No alpha test mode Jan 17 17:17:22 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 17 17:17:22 primo sudo[4156]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:22 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jan 17 17:17:22 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 Jan 17 17:17:22 primo volumio[3259]: info: Cannot mount NAS roonplex at system boot, trial number 3 ,retrying in 5 seconds Jan 17 17:17:22 primo volumio[3259]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 17 17:17:22 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 17 17:17:23 primo kernel: asoc-aml-card auge_sound: tdm playback stop Jan 17 17:17:23 primo kernel: spdif_a is set to disable Jan 17 17:17:23 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jan 17 17:17:23 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jan 17 17:17:23 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jan 17 17:17:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jan 17 17:17:24 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 17:17:24 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:24 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:24 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:24 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:24 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:24 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:24 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:25 primo volumio[3259]: info: BOOT COMPLETED Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: Not Reporting Auto name since its the default one Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jan 17 17:17:27 primo volumio[3259]: No protocol specified Jan 17 17:17:27 primo volumio[3259]: xcb_connection_has_error() returned true Jan 17 17:17:27 primo sudo[4290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jan 17 17:17:27 primo sudo[4290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:27 primo sudo[4290]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:17:27 primo volumio[3259]: info: Retrieving Cloud Streaming UI Jan 17 17:17:27 primo volumio[3259]: info: Getting Tidal Cloud Configuration Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:17:27 primo sudo[4292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jan 17 17:17:27 primo sudo[4292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:27 primo volumio[3259]: info: Getting Qobuz Cloud Configuration Jan 17 17:17:27 primo volumio[3259]: info: Asking plugin for UI Config Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:17:27 primo volumio[3259]: info: Getting Spotify Cloud Configuration Jan 17 17:17:27 primo volumio[3259]: info: Asking plugin for UI Config Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:17:27 primo volumio[3259]: info: Saving Spotify Acccount Jan 17 17:17:27 primo volumio[3259]: info: Got it Jan 17 17:17:27 primo volumio[3259]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 17 17:17:27 primo systemd[1]: Started RAAT DAEMON. Jan 17 17:17:27 primo sudo[4292]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:27 primo volumio[3259]: info: Got Tidal Cloud Configuration Jan 17 17:17:27 primo kernel: aml_tdm_open Jan 17 17:17:27 primo kernel: Not init audio effects Jan 17 17:17:27 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 17 17:17:27 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jan 17 17:17:27 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jan 17 17:17:27 primo kernel: aml_tdm_open Jan 17 17:17:27 primo kernel: Not init audio effects Jan 17 17:17:27 primo volumio[3259]: info: Raat Daemon started successfully Jan 17 17:17:27 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jan 17 17:17:27 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jan 17 17:17:27 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jan 17 17:17:27 primo volumio[3259]: info: Got it Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 17:17:27 primo volumio[3259]: info: Executing endpoint restartRAATSocket Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jan 17 17:17:27 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:17:27 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 17 17:17:27 primo sudo[4319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=johannes,password=kousseri4,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.132.21/media/music /mnt/NAS/roonplex Jan 17 17:17:27 primo sudo[4319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 17:17:27 primo volumio[3259]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 17 17:17:29 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 17:17:29 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 17 17:17:29 primo volumio[3259]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 17 17:17:29 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:29 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:30 primo volumio[3259]: info: RAAT: Requesting Headphone Status Jan 17 17:17:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jan 17 17:17:30 primo volumio[3259]: info: MCU Signalled Headphone Mode Disabled Jan 17 17:17:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jan 17 17:17:30 primo volumio[3259]: info: Reporting Headphone State: false Jan 17 17:17:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:30 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:30 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:31 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 17:17:34 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:17:35 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:17:36 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 17 17:17:37 primo volumio[3259]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 17 17:17:37 primo volumio[3259]: info: Received Get System Version Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 17 17:17:37 primo volumio[3259]: info: Received Get System Info Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 17:17:37 primo volumio[3259]: info: Discovery: Getting this device information Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::volumioGetState Jan 17 17:17:37 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 17:17:37 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 17 17:17:37 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Jan 17 17:17:37 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 Jan 17 17:17:37 primo sudo[4319]: pam_unix(sudo:session): session closed for user root Jan 17 17:17:37 primo volumio[3259]: info: Cannot mount NAS roonplex at system boot, trial number 4 ,retrying in 5 seconds Jan 17 17:17:39 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 17:17:39 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand update Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:39 primo volumio[3259]: info: Jan 17 17:17:39 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand update took 32 milliseconds Jan 17 17:17:39 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:39 primo volumio[3259]: info: Jan 17 17:17:39 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:39 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:39 primo volumio[3259]: info: Jan 17 17:17:39 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:39 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand status took 27 milliseconds Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand status took 15 milliseconds Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand status took 15 milliseconds Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand status took 13 milliseconds Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand status took 12 milliseconds Jan 17 17:17:39 primo volumio[3259]: info: sendMpdCommand status took 9 milliseconds Jan 17 17:17:39 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:40 primo volumio[3259]: info: Command Router : Notfying DB Updatetrue Jan 17 17:17:40 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:40 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:40 primo volumio[3259]: info: Command Router : Notfying DB Updatetrue Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:40 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:40 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:40 primo volumio[3259]: info: Command Router : Notfying DB Updatetrue Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:40 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:40 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:40 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus play Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::stPlaybackTimer Jan 17 17:17:40 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:40 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: No code Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:40 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: No code Jan 17 17:17:40 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:40 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:40 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:40 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:40 primo volumio[3259]: info: ------------------------------ 822ms Jan 17 17:17:40 primo volumio[3259]: info: ------------------------------ 812ms Jan 17 17:17:40 primo volumio[3259]: info: ------------------------------ 811ms Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:40 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:41 primo volumio[3259]: info: MPD Database updated - AlbumList cache refreshed Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: database Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: MPD Database updated - AlbumList cache refreshed Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: database Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 29 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 27 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 26 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 23 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 22 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 23 milliseconds Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:41 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: No code Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:41 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: No code Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:41 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: No code Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:41 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: No code Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: ------------------------------ 326ms Jan 17 17:17:41 primo volumio[3259]: info: ------------------------------ 326ms Jan 17 17:17:41 primo volumio[3259]: info: ------------------------------ 321ms Jan 17 17:17:41 primo volumio[3259]: info: ------------------------------ 321ms Jan 17 17:17:41 primo volumio[3259]: info: MPD Database updated - AlbumList cache refreshed Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: database Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand update Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: info: Jan 17 17:17:41 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 19 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 18 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand status took 17 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: sendMpdCommand update took 8 milliseconds Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:41 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: No code Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:41 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:41 primo volumio[3259]: info: No code Jan 17 17:17:41 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:41 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:41 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:41 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 174ms Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 175ms Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: Jan 17 17:17:42 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: Jan 17 17:17:42 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: Jan 17 17:17:42 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 385 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 380 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 227 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 224 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 9 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 8 milliseconds Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:42 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: No code Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:42 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: No code Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:42 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: No code Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 648ms Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 491ms Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 274ms Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: Jan 17 17:17:42 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: Jan 17 17:17:42 primo volumio[3259]: ---------------------------- MPD announces state update: update Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::getState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::sendMpdCommand status Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 300 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 299 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 30 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 28 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 28 milliseconds Jan 17 17:17:42 primo volumio[3259]: info: sendMpdCommand status took 26 milliseconds Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: Command Router : Notfying DB Updatefalse Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::Close All Modals sent Jan 17 17:17:42 primo volumio[3259]: verbose: ControllerMpd::parseState Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:42 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: No code Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:42 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: No code Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ControllerMpd::pushState Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::servicePushState Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 17 17:17:42 primo volumio[3259]: verbose: CURRENT POSITION 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState stateService stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::syncState currentStatus stop Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: No code Jan 17 17:17:42 primo volumio[3259]: info: CoreStateMachine::pushState Jan 17 17:17:42 primo volumio[3259]: info: CorePlayQueue::getTrack 0 Jan 17 17:17:42 primo volumio[3259]: info: CoreCommandRouter::volumioPushState Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output update for this device Jan 17 17:17:42 primo volumio[3259]: info: MRS: Pushing multiroomSync output Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 592ms Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 322ms Jan 17 17:17:42 primo volumio[3259]: info: ------------------------------ 320ms Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: info: Updating RAAT Signal Path Jan 17 17:17:43 primo volumio[3259]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 17:17:43 primo volumio[3259]: TypeError: Cannot read property 'replace' of undefined Jan 17 17:17:43 primo volumio[3259]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jan 17 17:17:43 primo volumio[3259]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jan 17 17:17:43 primo volumio[3259]: at listOnTimeout (internal/timers.js:557:17) Jan 17 17:17:43 primo volumio[3259]: at processTimers (internal/timers.js:500:7) Jan 17 17:17:43 primo volumio[3259]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 17:17:43 primo sudo[4419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 17:16 Jan 17 17:17:43 primo sudo[4419]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:41:47 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="d050b972c82ad0b26840d04dc6ef55b1"