-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Sun 2024-09-01 18:22:07 UTC. -- Sep 01 18:21:24 integro ntpd[3261]: receive: Unexpected origin timestamp 0xea5f8157.e893f569 does not match aorg 0000000000.00000000 from server@88.198.226.248 xmt 0xea7f2f24.a0a45c2f Sep 01 18:21:24 integro ntpd[3261]: receive: Unexpected origin timestamp 0xea5f8157.e88e6dce does not match aorg 0000000000.00000000 from server@159.69.245.253 xmt 0xea7f2f24.a0663a04 Sep 01 18:21:24 integro ntpd[3261]: receive: Unexpected origin timestamp 0xea5f8157.e88b21d5 does not match aorg 0000000000.00000000 from server@85.215.93.134 xmt 0xea7f2f24.a120a389 Sep 01 18:21:24 integro ntpd[3261]: receive: Unexpected origin timestamp 0xea5f8157.e89a1b51 does not match aorg 0000000000.00000000 from server@31.209.85.242 xmt 0xea7f2f24.a116f055 Sep 01 18:21:24 integro systemd[1]: Starting Daily man-db regeneration... Sep 01 18:21:24 integro systemd[1]: Starting Daily apt download activities... Sep 01 18:21:25 integro volumio[3265]: info: Starting Streaming Service Transparent Proxy Sep 01 18:21:25 integro volumio[3265]: info: Plugin tidalconnect is not enabled Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "webradio"... Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro systemd[1]: man-db.service: Succeeded. Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro systemd[1]: Started Daily man-db regeneration. Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "i2s_dacs"... Sep 01 18:21:25 integro volumio[3265]: info: I2S DAC not set, start Auto-detection Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "volumiodiscovery"... Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro volumio[3265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 18:21:25 integro volumio[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:21:25 integro node[3265]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 01 18:21:25 integro volumio[3265]: *** WARNING *** For more information see Sep 01 18:21:25 integro volumio[3265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 18:21:25 integro volumio[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:21:25 integro volumio[3265]: *** WARNING *** For more information see Sep 01 18:21:25 integro node[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:21:25 integro node[3265]: *** WARNING *** For more information see Sep 01 18:21:25 integro node[3265]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 01 18:21:25 integro node[3265]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 01 18:21:25 integro node[3265]: *** WARNING *** For more information see Sep 01 18:21:25 integro volumio[3265]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 01 18:21:25 integro volumio[3265]: info: Discovery: Started advertising with name: Integro Sep 01 18:21:25 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "autostart"... Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 8 Sep 01 18:21:25 integro volumio[3265]: info: Applying required configuration parameters for plugin autostart Sep 01 18:21:25 integro volumio[3265]: info: Plugin multiroom is not enabled Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "outputs"... Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "albumart"... Sep 01 18:21:25 integro volumio[3265]: info: Plugin example_plugin is not enabled Sep 01 18:21:25 integro volumio[3265]: info: Loading plugin "hdmi_arc"... Sep 01 18:21:25 integro kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04e1bb000 drv_status 0x0 e_type 69 e_states 0 Sep 01 18:21:25 integro sudo[3317]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:26 integro volumio[3265]: info: Loading plugin "hi_res_audio"... Sep 01 18:21:26 integro volumio[3265]: Forking 3 albumart workers Sep 01 18:21:26 integro volumio[3265]: STREAMING PROXY: Starting server on port 3245 Sep 01 18:21:26 integro volumio[3265]: Node JS runtime: 14 Sep 01 18:21:27 integro volumio[3265]: Starting albumart workers Sep 01 18:21:27 integro volumio[3265]: Starting albumart workers Sep 01 18:21:27 integro volumio[3265]: Starting albumart workers Sep 01 18:21:27 integro systemd[1]: apt-daily.service: Succeeded. Sep 01 18:21:27 integro systemd[1]: Started Daily apt download activities. Sep 01 18:21:27 integro systemd[1]: Starting Daily apt upgrade and clean activities... Sep 01 18:21:28 integro systemd[1]: systemd-fsckd.service: Succeeded. Sep 01 18:21:28 integro volumio[3265]: info: Applying required configuration parameters for plugin hi_res_audio Sep 01 18:21:28 integro volumio[3265]: info: Loading plugin "inputs"... Sep 01 18:21:29 integro volumio[3265]: info: Loading plugin "qobuz"... Sep 01 18:21:29 integro systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 01 18:21:29 integro systemd[1]: Started Daily apt upgrade and clean activities. Sep 01 18:21:30 integro sh[2754]: timed out Sep 01 18:21:30 integro sh[2754]: dhcpcd exited Sep 01 18:21:30 integro dhcpcd[2800]: timed out Sep 01 18:21:30 integro systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Sep 01 18:21:30 integro sh[2754]: ifup: failed to bring up eth0 Sep 01 18:21:30 integro dhcpcd[2800]: dhcpcd exited Sep 01 18:21:30 integro systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Sep 01 18:21:31 integro volumio[3265]: info: Loading plugin "smart_inputs"... Sep 01 18:21:31 integro volumio[3265]: info: Loading plugin "tidal"... Sep 01 18:21:33 integro volumio[3265]: info: Loading plugin "integrocontrol"... Sep 01 18:21:33 integro volumio[3265]: info: Adding this device properties Sep 01 18:21:33 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Sep 01 18:21:33 integro volumio[3265]: info: Setting Additional Device Volumio Properties: [object Object] Sep 01 18:21:33 integro volumio[3265]: info: Loading plugin "updater_comm"... Sep 01 18:21:33 integro volumio[3265]: info: Plugin mpdemulation is not enabled Sep 01 18:21:33 integro volumio[3265]: info: Loading plugin "rest_api"... Sep 01 18:21:33 integro volumio[3265]: info: Loading plugin "websocket"... Sep 01 18:21:33 integro volumio[3265]: info: Starting Socket.io Server version 2.3.0 Sep 01 18:21:33 integro volumio[3265]: info: Plugin fusiondsp is not enabled Sep 01 18:21:33 integro volumio[3265]: info: Loading plugin "radio_paradise"... Sep 01 18:21:34 integro volumio[3265]: info: Applying required configuration parameters for plugin radio_paradise Sep 01 18:21:34 integro volumio[3265]: info: [1725214894222] [RadioParadise] API delay: 5 Sep 01 18:21:34 integro volumio[3265]: info: Loading i18n strings for locale de Sep 01 18:21:34 integro volumio[3265]: Updating browse sources language Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::initPlayerControls Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 01 18:21:34 integro volumio[3265]: Express server listening on port 3000 Sep 01 18:21:34 integro volumio[3265]: [Metrics] WebUI: 16s 875.91ms Sep 01 18:21:34 integro volumio[3265]: info: CoreStateMachine::resetVolumioState Sep 01 18:21:34 integro volumio[3265]: info: CoreStateMachine::getcurrentVolume Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:34 integro volumio[3265]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 01 18:21:34 integro volumio[3265]: info: Completed loading Core Plugins Sep 01 18:21:34 integro volumio[3265]: info: Preparing to generate the ALSA configuration file Sep 01 18:21:34 integro volumio[3265]: Unable to parse: Sep 01 18:21:34 integro volumio[3265]: Simple mixer control 'Audio hdmi-out mute',0 Sep 01 18:21:34 integro volumio[3265]: Capabilities: pswitch pswitch-joined Sep 01 18:21:34 integro volumio[3265]: Playback channels: Mono Sep 01 18:21:34 integro volumio[3265]: Mono: Playback [on] Sep 01 18:21:34 integro volumio[3265]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 01 18:21:34 integro volumio[3265]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 01 18:21:34 integro volumio[3265]: Unable to parse: Sep 01 18:21:34 integro volumio[3265]: Simple mixer control 'Audio hdmi-out mute',0 Sep 01 18:21:34 integro volumio[3265]: Capabilities: pswitch pswitch-joined Sep 01 18:21:34 integro volumio[3265]: Playback channels: Mono Sep 01 18:21:34 integro volumio[3265]: Mono: Playback [on] Sep 01 18:21:34 integro volumio[3265]: info: VolumeController:: Volume=undefined Mute =false Sep 01 18:21:34 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:34 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:34 integro volumio[3265]: info: CoreStateMachine::updateTrackBlock Sep 01 18:21:34 integro volumio[3265]: info: CorePlayQueue::getTrackBlock Sep 01 18:21:34 integro volumio[3265]: info: CoreCommandRouter::volumioRetrievevolume Sep 01 18:21:35 integro volumio[3265]: info: Reloading queue from file Sep 01 18:21:35 integro volumio[3265]: Unable to parse: Sep 01 18:21:35 integro volumio[3265]: Simple mixer control 'Audio hdmi-out mute',0 Sep 01 18:21:35 integro volumio[3265]: Capabilities: pswitch pswitch-joined Sep 01 18:21:35 integro volumio[3265]: Playback channels: Mono Sep 01 18:21:35 integro volumio[3265]: Mono: Playback [on] Sep 01 18:21:35 integro volumio[3265]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 01 18:21:35 integro volumio[3265]: info: Asound.conf file unchanged, so no further update is needed Sep 01 18:21:35 integro volumio[3265]: info: Output device has changed, restarting MPD Sep 01 18:21:35 integro sudo[3519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:21:35 integro sudo[3519]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro volumio[3265]: info: ___________ START PLUGINS ___________ Sep 01 18:21:35 integro volumio[3265]: info: ControllerMpd::onStart: Initializing MPD Sep 01 18:21:35 integro volumio[3265]: info: Creating MPD Configuration file Sep 01 18:21:35 integro sudo[3519]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro sudo[3522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:21:35 integro sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 01 18:21:35 integro sudo[3526]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:21:35 integro volumio[3265]: info: [1725214895211] CoreMusicLibrary::Adding element Last_100 Sep 01 18:21:35 integro systemd[1]: Listening on mpd.socket. Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:35 integro sudo[3526]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro systemd[1]: Starting Music Player Daemon... Sep 01 18:21:35 integro volumio[3265]: info: Streaming services startup Sep 01 18:21:35 integro volumio[3265]: info: Starting Streaming Daemon Sep 01 18:21:35 integro sudo[3529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 01 18:21:35 integro sudo[3529]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 18:21:35 integro sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:21:35 integro volumio[3265]: info: [1725214895292] CoreMusicLibrary::Adding element Webradio Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:21:35 integro sudo[3532]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 01 18:21:35 integro systemd[1]: mpd.service: Succeeded. Sep 01 18:21:35 integro volumio[3265]: info: Initializing BBC Radios Sep 01 18:21:35 integro systemd[1]: Stopped Music Player Daemon. Sep 01 18:21:35 integro systemd[1]: Starting Music Player Daemon... Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:35 integro volumio[3265]: info: Starting CEC Infrastructure Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Sep 01 18:21:35 integro volumio[3265]: info: Additional UI Settings Added for plugin music_service/hdmi_arc Sep 01 18:21:35 integro volumio[3265]: error: Hi Res Audio Failed Login: Missing Login Data Sep 01 18:21:35 integro volumio[3265]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 01 18:21:35 integro volumio[3265]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 01 18:21:35 integro volumio[3265]: info: Initializing Serial Communication on port /dev/ttyS3 Sep 01 18:21:35 integro volumio[3265]: info: Touch Event Listener Process Starting Sep 01 18:21:35 integro kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Sep 01 18:21:35 integro kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Sep 01 18:21:35 integro kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Sep 01 18:21:35 integro sudo[3539]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 01 18:21:35 integro sudo[3539]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro volumio[3265]: info: Refreshing QOBUZ token Sep 01 18:21:35 integro sudo[3539]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 01 18:21:35 integro sudo[3539]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Sep 01 18:21:35 integro sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro volumio[3265]: info: Adding inputs REST Endpoints Sep 01 18:21:35 integro volumio[3265]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Sep 01 18:21:35 integro volumio[3265]: info: Scanning Audio Inputs Sep 01 18:21:35 integro sudo[3554]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro volumio[3265]: info: Checking against Known Cards name Sep 01 18:21:35 integro volumio[3265]: info: Checking against Known Cards name Sep 01 18:21:35 integro volumio[3265]: info: Checking against Known Cards name Sep 01 18:21:35 integro volumio[3265]: info: Checking against Known Cards name Sep 01 18:21:35 integro volumio[3265]: info: Checking against Known Cards name Sep 01 18:21:35 integro volumio[3265]: info: Adding Server instance for streaming Sep 01 18:21:35 integro sudo[3563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Sep 01 18:21:35 integro sudo[3563]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3563]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro sudo[3566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Sep 01 18:21:35 integro sudo[3566]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3566]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro sudo[3569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Sep 01 18:21:35 integro sudo[3569]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3569]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Sep 01 18:21:35 integro sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3572]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro volumio[3265]: info: MP1 Muting HDMI Output Sep 01 18:21:35 integro volumio[3265]: info: Apply VIM3L Onboard LEDs Settings Sep 01 18:21:35 integro volumio[3265]: info: Turning On Onboard LEDs Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:21:35 integro volumio[3265]: info: [1725214895854] CoreMusicLibrary::Adding element Radio Paradise Sep 01 18:21:35 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:35 integro volumio[3265]: Cannot find translation for source Radio Paradise Sep 01 18:21:35 integro volumio[3265]: info: Volumio Calling Home Sep 01 18:21:35 integro sudo[3578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Sep 01 18:21:35 integro sudo[3578]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3578]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:35 integro sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Sep 01 18:21:35 integro sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:35 integro sudo[3583]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:36 integro volumio[3265]: info: Stopping AccessToken refresher cron Sep 01 18:21:36 integro volumio[3265]: info: AccessToken refresher cron started Sep 01 18:21:36 integro volumio[3265]: info: Adding TIDAL REST API Endpoints Sep 01 18:21:36 integro volumio[3265]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 01 18:21:36 integro volumio[3265]: info: CoreStateMachine::setRepeat false single undefined Sep 01 18:21:36 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:36 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:36 integro volumio[3265]: info: CoreStateMachine::setRandom undefined Sep 01 18:21:36 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:36 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:36 integro volumio[3265]: info: Serial port opened successfully Sep 01 18:21:36 integro volumio[3265]: info: Sending serial start messages Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: Touch Event Listener Process Closed Sep 01 18:21:36 integro volumio[3265]: error: Cannot start Volumio Streaming Daemon Sep 01 18:21:36 integro volumio[3265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 18:21:36 integro volumio[3265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 18:21:36 integro volumio[3265]: info: MPD Permissions set Sep 01 18:21:36 integro volumio[3265]: info: MPD Permissions set Sep 01 18:21:36 integro volumio[3265]: Unable to parse: Sep 01 18:21:36 integro volumio[3265]: Simple mixer control 'Audio hdmi-out mute',0 Sep 01 18:21:36 integro volumio[3265]: Capabilities: pswitch pswitch-joined Sep 01 18:21:36 integro volumio[3265]: Playback channels: Mono Sep 01 18:21:36 integro volumio[3265]: Mono: Playback [on] Sep 01 18:21:36 integro volumio[3265]: info: VolumeController:: Volume=undefined Mute =false Sep 01 18:21:36 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:36 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:36 integro volumio[3265]: info: Volumio called home Sep 01 18:21:36 integro volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Sep 01 18:21:36 integro volumio[3265]: info: Setting Device Volume Override Sep 01 18:21:36 integro volumio[3265]: info: Applying Volume Override Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 01 18:21:36 integro volumio[3265]: info: Updating Volume Controller Parameters: Device: 0,0 Name: I2S Mixer: Audio hdmi-out mute Max Vol: 90 Vol Curve; logarithmic Vol Steps: 1 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 01 18:21:36 integro volumio[3265]: info: Enabling external Volume Control Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:36 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:36 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:36 integro volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 01 18:21:36 integro volumio[3265]: info: Discovery: adding 89ccb1c0-abd4-4598-bd3a-d5d4d59b28a3 Sep 01 18:21:36 integro volumio[3265]: info: Discovery: Found device Integro Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:36 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Sep 01 18:21:36 integro volumio[3265]: info: Setting Additional System Software info: Hardware Revision: 1.3 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Sep 01 18:21:36 integro volumio[3265]: info: Setting HW Firmware info: undefined Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Sep 01 18:21:36 integro volumio[3265]: info: Setting HW Version info: 1.3 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Sep 01 18:21:36 integro volumio[3265]: info: Setting Additional System Software info: Hardware Revision: 1.3, Firmware Version: 0.6.4 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Sep 01 18:21:36 integro volumio[3265]: info: Setting HW Firmware info: 0.6.4 Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Sep 01 18:21:36 integro volumio[3265]: info: Setting HW Version info: 1.3 Sep 01 18:21:36 integro volumio[3265]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 01 18:21:36 integro volumio[3265]: info: MCU Signalled Headphone Mode Disabled Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 01 18:21:36 integro volumio[3265]: info: MCU Signalled Sleep Mode Disabled Sep 01 18:21:36 integro volumio[3265]: info: Enabling Advanced system settings configuration Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Sep 01 18:21:36 integro volumio[3265]: info: Additional UI Settings Added for plugin music_service/inputs Sep 01 18:21:36 integro volumio[3265]: info: MCU Signalled Auto Boot Mode On Power Disabled Sep 01 18:21:36 integro sudo[3604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Sep 01 18:21:36 integro sudo[3604]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:36 integro sudo[3604]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:36 integro volumio[3265]: error: Serial API: Failed to decode command: MAXVOL, message: 90 Sep 01 18:21:36 integro volumio[3265]: error: Serial API: Failed to decode command: MAXVOL, message: 90 Sep 01 18:21:36 integro volumio[3265]: info: Access Token successfully retrieved Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:21:36 integro volumio[3265]: info: [1725214896803] CoreMusicLibrary::Adding element QOBUZ Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:36 integro volumio[3265]: Cannot find translation for source Radio Paradise Sep 01 18:21:36 integro volumio[3265]: Cannot find translation for source QOBUZ Sep 01 18:21:36 integro volumio[3265]: info: Stopping AccessToken refresher cron for QOBUZ Sep 01 18:21:36 integro volumio[3265]: info: AccessToken refresher cron started for QOBUZ Sep 01 18:21:36 integro volumio[3265]: info: Adding QOBUZ REST API Endpoints Sep 01 18:21:36 integro volumio[3265]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 01 18:21:36 integro sudo[3607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Sep 01 18:21:36 integro sudo[3607]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:36 integro sudo[3607]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:36 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:36 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:37 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:37 integro volumio[3265]: info: Adding Inputs via Serial API Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 01 18:21:37 integro volumio[3265]: info: [1725214897042] CoreMusicLibrary::Adding element Inputs Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:37 integro volumio[3265]: Cannot find translation for source Radio Paradise Sep 01 18:21:37 integro volumio[3265]: Cannot find translation for source QOBUZ Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:37 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:37 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received inputs Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:37 integro volumio[3265]: Cannot find translation for source Radio Paradise Sep 01 18:21:37 integro volumio[3265]: Cannot find translation for source QOBUZ Sep 01 18:21:37 integro volumio[3265]: info: MCU Signalled Playback Inactive Sep 01 18:21:37 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:37 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:37 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:37 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:37 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:37 integro volumio-remote-updater[2819]: [2024-09-01 18:21:37] [connect] Successful connection Sep 01 18:21:37 integro volumio-remote-updater[2819]: [2024-09-01 18:21:37] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725214897 101 Sep 01 18:21:37 integro volumio[3265]: 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: 3 Sep 01 18:21:38 integro volumio[3265]: info: Turning Off Onboard LEDs Sep 01 18:21:38 integro mpd[3556]: Sep 01 18:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 01 18:21:38 integro sudo[3612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Sep 01 18:21:38 integro sudo[3612]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:38 integro systemd[1]: Started Music Player Daemon. Sep 01 18:21:38 integro sudo[3612]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:38 integro sudo[3529]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:38 integro sudo[3522]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:38 integro sudo[3617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Sep 01 18:21:38 integro sudo[3617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:38 integro volumio[3265]: info: Completed starting Core Plugins Sep 01 18:21:38 integro volumio[3265]: info: ------------------------------------------- Sep 01 18:21:38 integro volumio[3265]: info: ----- MyVolumio plugins startup ---- Sep 01 18:21:38 integro volumio[3265]: info: ------------------------------------------- Sep 01 18:21:38 integro volumio[3265]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 01 18:21:38 integro sudo[3617]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:38 integro volumio[3265]: info: Onboard LEDs initialized Sep 01 18:21:38 integro volumio[3265]: error: MPD error: The expression evaluated to a falsy value: Sep 01 18:21:38 integro volumio[3265]: assert.ok(self.idling) Sep 01 18:21:38 integro volumio[3265]: error: The expression evaluated to a falsy value: Sep 01 18:21:38 integro volumio[3265]: assert.ok(self.idling) Sep 01 18:21:38 integro volumio[3265]: error: updateQueue error: null Sep 01 18:21:38 integro volumio[3265]: info: MPD running with PID3556 Sep 01 18:21:38 integro volumio[3265]: ,establishing connection Sep 01 18:21:38 integro volumio[3265]: error: updateQueue error: null Sep 01 18:21:38 integro volumio[3265]: info: Initializing I2S Bus Sep 01 18:21:38 integro kernel: aml_tdm_open Sep 01 18:21:38 integro kernel: Not init audio effects Sep 01 18:21:38 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Sep 01 18:21:38 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Sep 01 18:21:38 integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d306018, id(1), clksel(1) Sep 01 18:21:38 integro kernel: aml_dai_set_tdm_fmt(), fmt not change Sep 01 18:21:38 integro kernel: dump_pcm_setting(ffffffc03d306018) Sep 01 18:21:38 integro kernel: pcm_mode(1) Sep 01 18:21:38 integro kernel: sysclk(11289600) Sep 01 18:21:38 integro kernel: sysclk_bclk_ratio(4) Sep 01 18:21:38 integro kernel: bclk(2822400) Sep 01 18:21:38 integro kernel: bclk_lrclk_ratio(64) Sep 01 18:21:38 integro kernel: lrclk(44100) Sep 01 18:21:38 integro kernel: tx_mask(0x3) Sep 01 18:21:38 integro kernel: rx_mask(0x3) Sep 01 18:21:38 integro kernel: slots(2) Sep 01 18:21:38 integro kernel: slot_width(32) Sep 01 18:21:38 integro kernel: lane_mask_in(0x2) Sep 01 18:21:38 integro kernel: lane_mask_out(0x1) Sep 01 18:21:38 integro kernel: lane_oe_mask_in(0x0) Sep 01 18:21:38 integro kernel: lane_oe_mask_out(0x0) Sep 01 18:21:38 integro kernel: lane_lb_mask_in(0x0) Sep 01 18:21:38 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Sep 01 18:21:38 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Sep 01 18:21:38 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Sep 01 18:21:38 integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Sep 01 18:21:38 integro kernel: aml_dai_set_bclk_ratio, select I2S mode Sep 01 18:21:38 integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Sep 01 18:21:38 integro kernel: aml_tdm_prepare(), reset fddr Sep 01 18:21:38 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Sep 01 18:21:38 integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Sep 01 18:21:38 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Sep 01 18:21:38 integro kernel: tdm playback mute: 0, lane_cnt = 8 Sep 01 18:21:38 integro kernel: asoc-aml-card auge_sound: tdm playback enable Sep 01 18:21:38 integro kernel: spdif_a is set to enable Sep 01 18:21:39 integro volumio[3265]: info: Setting up CEC listeners Sep 01 18:21:39 integro volumio[3265]: info: CEC Infrastructure started successfully Sep 01 18:21:40 integro kernel: asoc-aml-card auge_sound: tdm playback stop Sep 01 18:21:40 integro kernel: spdif_a is set to disable Sep 01 18:21:40 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Sep 01 18:21:40 integro kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Sep 01 18:21:40 integro kernel: tdm playback mute: 1, lane_cnt = 8 Sep 01 18:21:40 integro kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Sep 01 18:21:40 integro volumio[3265]: info: Successfully initialized Integro I2S Bus Sep 01 18:21:40 integro systemd[1]: systemd-hostnamed.service: Succeeded. Sep 01 18:21:43 integro volumio[3265]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 01 18:21:44 integro kernel: fb: mem_free_work, free memory: addr:800000 Sep 01 18:21:45 integro volumio[3265]: info: AutoStart - getting queue Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::volumioGetQueue Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::getQueue Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getQueue Sep 01 18:21:45 integro volumio[3265]: info: AutoStart - start playing -> queue is not empty Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::volumioPlay Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::play index undefined Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::startPlaybackTimer Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand stop took 16 milliseconds Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:45 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand clear took 4 milliseconds Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:45 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:45 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:45 integro volumio[3265]: error: updateQueue error: null Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces state update: options Sep 01 18:21:45 integro volumio[3265]: error: updateQueue error: null Sep 01 18:21:45 integro volumio[3265]: error: updateQueue error: null Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 14ms Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand consume 1 took 10 milliseconds Sep 01 18:21:45 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 12ms Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 9ms Sep 01 18:21:45 integro volumio[3265]: info: [1725214905851] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac" Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces state update: options Sep 01 18:21:45 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces state update: options Sep 01 18:21:45 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand status took 13 milliseconds Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:45 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 37ms Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:45 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 33 milliseconds Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand status took 31 milliseconds Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand status took 29 milliseconds Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand play Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:45 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:45 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:45 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:45 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:45 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:45 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 73ms Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 72ms Sep 01 18:21:45 integro volumio[3265]: info: Checking for updated MCU Firmware Sep 01 18:21:45 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:21:45 integro volumio[3265]: info: Firware on device is on latest version, no need to update Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:45 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:45 integro volumio[3265]: info: Sep 01 18:21:45 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:45 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 81ms Sep 01 18:21:45 integro volumio[3265]: info: sendMpdCommand play took 73 milliseconds Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 25ms Sep 01 18:21:45 integro volumio[3265]: info: ------------------------------ 23ms Sep 01 18:21:45 integro volumio[3265]: info: [1725214905967] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Sep 01 18:21:46 integro volumio[3265]: info: [1725214906631] [RadioParadise] received new metadata: {"time":144,"artist":"David Gilmour","title":"On an Island","album":"On an Island","year":"2006","cover":"https://img.radioparadise.com/covers/l/18481_fc3cc3d9-709a-433f-b517-643e1d34dd9e.jpg","cover_med":"https://img.radioparadise.com/covers/m/18481_fc3cc3d9-709a-433f-b517-643e1d34dd9e.jpg","cover_small":"https://img.radioparadise.com/covers/s/18481_fc3cc3d9-709a-433f-b517-643e1d34dd9e.jpg"} Sep 01 18:21:46 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:46 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:46 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:46 integro volumio[3265]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18481_fc3cc3d9-709a-433f-b517-643e1d34dd9e.jpg","uri":"https://stream.radioparadise.com/flac","name":"On an Island","title":"On an Island","artist":"David Gilmour","album":"On an Island","streaming":true,"disableUiControls":true,"duration":149,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 01 18:21:46 integro volumio[3265]: verbose: CURRENT POSITION 0 Sep 01 18:21:46 integro volumio[3265]: info: CoreStateMachine::syncState stateService play Sep 01 18:21:46 integro volumio[3265]: info: CoreStateMachine::syncState currentStatus stop Sep 01 18:21:46 integro volumio[3265]: info: [1725214906638] [RadioParadise] setting new timer with duration of 149000 seconds. Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 01 18:21:46 integro volumio[3265]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 01 18:21:47 integro kernel: aml_tdm_open Sep 01 18:21:47 integro kernel: Not init audio effects Sep 01 18:21:47 integro kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Sep 01 18:21:47 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Sep 01 18:21:47 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Sep 01 18:21:47 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Sep 01 18:21:47 integro kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d306018, id(1), clksel(1) Sep 01 18:21:47 integro kernel: aml_dai_set_tdm_fmt(), fmt not change Sep 01 18:21:47 integro kernel: dump_pcm_setting(ffffffc03d306018) Sep 01 18:21:47 integro kernel: pcm_mode(1) Sep 01 18:21:47 integro kernel: sysclk(11289600) Sep 01 18:21:47 integro kernel: sysclk_bclk_ratio(4) Sep 01 18:21:47 integro kernel: bclk(2822400) Sep 01 18:21:47 integro kernel: bclk_lrclk_ratio(64) Sep 01 18:21:47 integro kernel: lrclk(44100) Sep 01 18:21:47 integro kernel: tx_mask(0x3) Sep 01 18:21:47 integro kernel: rx_mask(0x3) Sep 01 18:21:47 integro kernel: slots(2) Sep 01 18:21:47 integro kernel: slot_width(32) Sep 01 18:21:47 integro kernel: lane_mask_in(0x2) Sep 01 18:21:47 integro kernel: lane_mask_out(0x1) Sep 01 18:21:47 integro kernel: lane_oe_mask_in(0x0) Sep 01 18:21:47 integro kernel: lane_oe_mask_out(0x0) Sep 01 18:21:47 integro kernel: lane_lb_mask_in(0x0) Sep 01 18:21:47 integro kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Sep 01 18:21:47 integro kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Sep 01 18:21:47 integro kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Sep 01 18:21:47 integro kernel: aml_dai_set_clkdiv, div 4, clksel(1) Sep 01 18:21:47 integro kernel: aml_dai_set_bclk_ratio, select I2S mode Sep 01 18:21:47 integro kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Sep 01 18:21:47 integro kernel: aml_tdm_prepare(), reset fddr Sep 01 18:21:47 integro kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Sep 01 18:21:47 integro kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Sep 01 18:21:47 integro kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Sep 01 18:21:47 integro kernel: tdm playback mute: 0, lane_cnt = 8 Sep 01 18:21:47 integro kernel: asoc-aml-card auge_sound: tdm playback enable Sep 01 18:21:47 integro kernel: spdif_a is set to enable Sep 01 18:21:48 integro volumio[3265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 01 18:21:48 integro volumio[3265]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:48 integro volumio[3265]: info: Starting MyVolumio Remote Streaming Endpoints Sep 01 18:21:48 integro volumio[3265]: info: MyVolumio login type: Token Sep 01 18:21:48 integro volumio[3265]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 01 18:21:48 integro volumio[3265]: 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' Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces state update: player Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces state update: player Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces state update: player Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:48 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces state update: player Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand status took 32 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand status took 30 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand status took 28 milliseconds Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:48 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces state update: player Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces system playlist update Sep 01 18:21:48 integro volumio[3265]: info: Ignoring MPD Status Update Sep 01 18:21:48 integro volumio[3265]: info: Sep 01 18:21:48 integro volumio[3265]: ---------------------------- MPD announces state update: player Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::getState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand status Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 26ms Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand status took 24 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 17ms Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand status took 15 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 14ms Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand status took 12 milliseconds Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseState Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:48 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:48 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:48 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:48 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:48 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:48 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 78ms Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 76ms Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 74ms Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 01 18:21:48 integro volumio[3265]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:21:48 integro volumio[3265]: verbose: ControllerMpd::parseTrackInfo Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:48 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:48 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:48 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:48 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:48 integro volumio[3265]: info: ControllerMpd::pushState Sep 01 18:21:48 integro volumio[3265]: info: CoreCommandRouter::servicePushState Sep 01 18:21:48 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:48 integro volumio[3265]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 61ms Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 50ms Sep 01 18:21:48 integro volumio[3265]: info: ------------------------------ 46ms Sep 01 18:21:48 integro volumio[3265]: error: MyVolumio Custom Token format not valid, refreshing it Sep 01 18:21:49 integro volumio[3265]: info: MyVolumio login type: Token Sep 01 18:21:49 integro volumio[3265]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:21:50 integro volumio[3265]: info: Discovery: Getting this device information Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:50 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:21:50 integro volumio[3265]: verbose: New Socket.io Connection to 192.168.3.190:3000 from 192.168.3.198 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:50 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 01 18:21:50 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 01 18:21:50 integro volumio[3265]: info: MyVolumio token set successfully Sep 01 18:21:50 integro volumio[3265]: info: MYVOLUMIO: Adding device Sep 01 18:21:50 integro volumio[3265]: info: MYVOLUMIO: Evaluating Server Sep 01 18:21:51 integro volumio[3265]: info: MyVolumio status changed Sep 01 18:21:51 integro volumio[3265]: info: Streaming services startup Sep 01 18:21:51 integro volumio[3265]: info: Starting Streaming Daemon Sep 01 18:21:51 integro volumio[3265]: info: Removing browser output: myVolumio user plan is not superstar Sep 01 18:21:51 integro volumio[3265]: info: Removing audio output: Sep 01 18:21:51 integro volumio[3265]: info: Stoppping Tunnel 1 Sep 01 18:21:51 integro sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 01 18:21:51 integro sudo[3654]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:51 integro sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 01 18:21:51 integro sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:51 integro sudo[3654]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:51 integro volumio[3265]: error: Cannot start Volumio Streaming Daemon Sep 01 18:21:51 integro volumio[3265]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 01 18:21:51 integro volumio[3265]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 01 18:21:51 integro sudo[3656]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:51 integro volumio[3265]: info: Remote SSH Stopped Sep 01 18:21:51 integro volumio[3265]: info: Setting Geolocation for MyVolumio to eu6 Sep 01 18:21:51 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:51 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:51 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:51 integro sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:21:51 integro sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:51 integro sudo[3660]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:51 integro sudo[3662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:21:51 integro sudo[3662]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:51 integro sudo[3662]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:52 integro volumio[3265]: verbose: New Socket.io Connection to 192.168.3.190 from 192.168.3.198 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Sep 01 18:21:52 integro sudo[3668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 01 18:21:52 integro sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:52 integro sudo[3668]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:52 integro sudo[3670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 01 18:21:52 integro sudo[3670]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 01 18:21:52 integro sudo[3670]: pam_unix(sudo:session): session closed for user root Sep 01 18:21:52 integro volumio[3265]: verbose: New Socket.io Connection to 192.168.3.190 from 192.168.3.198 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::volumioGetVisibleSources Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:52 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Sep 01 18:21:52 integro volumio[3265]: info: Received Get System Info Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:21:52 integro volumio[3265]: info: Discovery: Getting this device information Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:52 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:52 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:52 integro volumio[3265]: info: Listing playlists Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:21:52 integro volumio[3265]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 01 18:21:52 integro volumio[3265]: info: Updating MyVolumio device info Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:52 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:53 integro volumio[3265]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 18:21:53 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:21:53 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:21:53 integro volumio[3265]: info: Discovery: Getting this device information Sep 01 18:21:53 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:53 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:53 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:21:53 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 01 18:21:54 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 18:21:54 integro volumio[3265]: info: Received Get System Info Sep 01 18:21:54 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:21:54 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:21:54 integro volumio[3265]: info: Discovery: Getting this device information Sep 01 18:21:54 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:54 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:54 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:21:54 integro volumio[3265]: info: CoreCommandRouter::volumioGetQueue Sep 01 18:21:54 integro volumio[3265]: info: CoreStateMachine::getQueue Sep 01 18:21:54 integro volumio[3265]: info: CorePlayQueue::getQueue Sep 01 18:21:55 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 01 18:21:55 integro volumio[3265]: info: Received Get System Info Sep 01 18:21:55 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 01 18:21:55 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 01 18:21:55 integro volumio[3265]: info: Discovery: Getting this device information Sep 01 18:21:55 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:21:55 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:55 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 01 18:21:56 integro volumio[3265]: info: MYVOLUMIO: Adding device Sep 01 18:21:56 integro volumio[3265]: info: MYVOLUMIO: Evaluating Server Sep 01 18:21:57 integro volumio[3265]: info: Setting Geolocation for MyVolumio to eu4 Sep 01 18:21:57 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:57 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:57 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:57 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Sep 01 18:21:57 integro volumio[3265]: info: handleBrowseUri took 127 milliseconds Sep 01 18:21:57 integro volumio[3265]: info: Preload queue cleared Sep 01 18:21:57 integro volumio[3265]: info: Preload queue cleared Sep 01 18:21:57 integro volumio[3265]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 01 18:21:58 integro volumio[3265]: info: Updating MyVolumio device info Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:58 integro volumio[3265]: error: MyVolumio Plugin failed to start in a timely fashion Sep 01 18:21:58 integro volumio[3265]: info: BOOT COMPLETED Sep 01 18:21:58 integro volumio[3265]: [Metrics] CommandRouter: 39s 618.21ms Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::volumiosetStartupVolume Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 01 18:21:58 integro volumio[3265]: info: VolumeController:: Setting startup Volume 35 Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::Close All Modals sent Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::Close All Modals sent Sep 01 18:21:58 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:58 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::volumioGetQueue Sep 01 18:21:58 integro volumio[3265]: info: CoreStateMachine::getQueue Sep 01 18:21:58 integro volumio[3265]: info: CorePlayQueue::getQueue Sep 01 18:21:58 integro volumio[3265]: info: Signalling Playback active due to playback status change Sep 01 18:21:58 integro volumio[3265]: info: CoreStateMachine::pushState Sep 01 18:21:58 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 18:21:58 integro volumio[3265]: info: CoreCommandRouter::volumioPushState Sep 01 18:21:58 integro volumio[3265]: info: Signalling Playback active due to playback status change Sep 01 18:21:58 integro volumio[3265]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 01 18:21:59 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 01 18:21:59 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 01 18:21:59 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 01 18:22:02 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 01 18:22:03 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 01 18:22:03 integro volumio[3265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 01 18:22:03 integro volumio[3265]: info: CoreCommandRouter::volumioGetState Sep 01 18:22:03 integro volumio[3265]: info: CorePlayQueue::getTrack 0 Sep 01 18:22:05 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 01 18:22:05 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 01 18:22:05 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 01 18:22:06 integro volumio-remote-updater[2819]: No test mode Sep 01 18:22:06 integro volumio-remote-updater[2819]: No alpha test mode Sep 01 18:22:06 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 01 18:22:06 integro volumio[3265]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 01 18:22:06 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 01 18:22:07 integro volumio[3265]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Sep 01 18:22:07 integro volumio[3265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:22:07 integro volumio[3265]: TypeError: Cannot read property 'then' of undefined Sep 01 18:22:07 integro volumio[3265]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) Sep 01 18:22:07 integro volumio[3265]: at Socket.emit (events.js:400:28) Sep 01 18:22:07 integro volumio[3265]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 01 18:22:07 integro volumio[3265]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 01 18:22:07 integro volumio[3265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 18:22:07 integro sudo[3748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 18:21 Sep 01 18:22:07 integro sudo[3748]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="integro" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 01 Aug 2024 05:17:04 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Integro" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Integro" VOLUMIO_HASH="56b9d3932cd0f553ab78e4b3e169d42c"