-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2025-01-13 20:24:46 CET. --
Jan 13 20:23:00 volumio volumio-remote-updater[514]: [2025-01-13 20:23:00] [info] asio async_connect error: system:111 (Connection refused)
Jan 13 20:23:00 volumio volumio-remote-updater[514]: [2025-01-13 20:23:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Jan 13 20:23:00 volumio volumio-remote-updater[514]: [2025-01-13 20:23:00] [error] handle_connect error: Underlying Transport Error
Jan 13 20:23:03 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:03 volumio volumio[883]: info: -----            Volumio3              ----
Jan 13 20:23:03 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:03 volumio volumio[883]: info: -----          System startup          ----
Jan 13 20:23:03 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:03 volumio systemd[1]: systemd-fsckd.service: Succeeded.
Jan 13 20:23:04 volumio systemd[1]: apt-daily.service: Succeeded.
Jan 13 20:23:04 volumio systemd[1]: Started Daily apt download activities.
Jan 13 20:23:04 volumio systemd[1]: Starting Daily apt upgrade and clean activities...
Jan 13 20:23:04 volumio sh[449]: timed out
Jan 13 20:23:04 volumio dhcpcd[474]: timed out
Jan 13 20:23:04 volumio sh[449]: dhcpcd exited
Jan 13 20:23:04 volumio dhcpcd[474]: dhcpcd exited
Jan 13 20:23:05 volumio sh[449]: ifup: failed to bring up eth0
Jan 13 20:23:05 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE
Jan 13 20:23:05 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'.
Jan 13 20:23:05 volumio volumio[883]: info: MYVOLUMIO Environment detected
Jan 13 20:23:06 volumio kernel: hwmon hwmon1: Voltage normalised
Jan 13 20:23:06 volumio volumio[883]: info: Plugin folders cleanup
Jan 13 20:23:06 volumio volumio[883]: info: Scanning into folder /volumio/app/plugins/
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category audio_interface
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category miscellanea
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category music_service
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category plugins.json
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category system_controller
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category user_interface
Jan 13 20:23:06 volumio volumio[883]: info: Scanning into folder /data/plugins/
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category music_service
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category system_controller
Jan 13 20:23:06 volumio volumio[883]: info: Scanning category user_interface
Jan 13 20:23:06 volumio volumio[883]: info: Plugin folders cleanup completed
Jan 13 20:23:06 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:06 volumio volumio[883]: info: -----      Core plugins startup        ----
Jan 13 20:23:06 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:06 volumio volumio[883]: info: Loading plugins from folder /volumio/app/plugins/
Jan 13 20:23:06 volumio volumio[883]: info: Adding plugin upnp to MyMusic Plugins
Jan 13 20:23:06 volumio volumio[883]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jan 13 20:23:06 volumio volumio[883]: info: Adding plugin upnp_browser to MyMusic Plugins
Jan 13 20:23:06 volumio volumio[883]: info: Loading plugins from folder /data/plugins/
Jan 13 20:23:06 volumio volumio[883]: info: Loading plugin "system"...
Jan 13 20:23:06 volumio volumio[883]: info: Loading plugin "appearance"...
Jan 13 20:23:08 volumio systemd[1]: apt-daily-upgrade.service: Succeeded.
Jan 13 20:23:08 volumio systemd[1]: Started Daily apt upgrade and clean activities.
Jan 13 20:23:08 volumio systemd[1]: Startup finished in 15.612s (kernel) + 42.913s (userspace) = 58.525s.
Jan 13 20:23:09 volumio volumio[883]: info: Loading plugin "network"...
Jan 13 20:23:09 volumio volumio[883]: info: Refreshing Cached IP Addresses
Jan 13 20:23:09 volumio sudo[1046]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jan 13 20:23:09 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:09 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:09 volumio volumio[883]: info: Loading plugin "services"...
Jan 13 20:23:09 volumio sudo[1048]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jan 13 20:23:09 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:09 volumio volumio[883]: info: Loading plugin "alsa_controller"...
Jan 13 20:23:09 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:09 volumio sudo[1056]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Jan 13 20:23:09 volumio sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:09 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:23:09 volumio volumio[883]: info: Loading plugin "wizard"...
Jan 13 20:23:09 volumio volumio[883]: info: Loading plugin "networkfs"...
Jan 13 20:23:09 volumio volumio[883]: info: Starting Udev Watcher for removable devices
Jan 13 20:23:10 volumio volumio[883]: info: Ignoring mount for partition: boot
Jan 13 20:23:10 volumio volumio[883]: info: Ignoring mount for partition: volumio
Jan 13 20:23:10 volumio volumio[883]: info: Ignoring mount for partition: volumio_data
Jan 13 20:23:10 volumio volumio[883]: info: Mounting Device Lexar
Jan 13 20:23:10 volumio sudo[1077]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Lexar -o noatime
Jan 13 20:23:10 volumio sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:10 volumio volumio-remote-updater[514]: [2025-01-13 20:23:10] [connect] Successful connection
Jan 13 20:23:10 volumio mount.exfat[1079]: volume was not unmounted cleanly
Jan 13 20:23:10 volumio sudo[1077]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:10 volumio volumio[883]: WARN: volume was not unmounted cleanly.
Jan 13 20:23:10 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jan 13 20:23:10 volumio volumio[883]: info: Loading plugin "volumio_command_line_client"...
Jan 13 20:23:10 volumio volumio[883]: info: Plugin upnp is not enabled
Jan 13 20:23:10 volumio volumio[883]: info: Loading plugin "my_music"...
Jan 13 20:23:10 volumio volumio[883]: info: Loading plugin "mpd"...
Jan 13 20:23:11 volumio volumio[883]: info: Plugin upnp_browser is not enabled
Jan 13 20:23:11 volumio volumio[883]: info: Loading plugin "alarm-clock"...
Jan 13 20:23:12 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jan 13 20:23:12 volumio volumio[883]: info: Plugin airplay_emulation is not enabled
Jan 13 20:23:12 volumio volumio[883]: info: Loading plugin "last_100"...
Jan 13 20:23:12 volumio volumio[883]: info: Loading plugin "webradio"...
Jan 13 20:23:13 volumio volumio[883]: info: Loading plugin "i2s_dacs"...
Jan 13 20:23:13 volumio volumio[883]: info: Loading plugin "volumiodiscovery"...
Jan 13 20:23:13 volumio volumio[883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jan 13 20:23:13 volumio volumio[883]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 13 20:23:13 volumio node[883]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jan 13 20:23:13 volumio volumio[883]: *** WARNING *** For more information see 
Jan 13 20:23:13 volumio volumio[883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jan 13 20:23:13 volumio volumio[883]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 13 20:23:13 volumio volumio[883]: *** WARNING *** For more information see 
Jan 13 20:23:13 volumio node[883]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 13 20:23:13 volumio node[883]: *** WARNING *** For more information see 
Jan 13 20:23:13 volumio node[883]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jan 13 20:23:13 volumio node[883]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jan 13 20:23:13 volumio node[883]: *** WARNING *** For more information see 
Jan 13 20:23:13 volumio volumio[883]: info: Applying required configuration parameters for plugin volumiodiscovery
Jan 13 20:23:13 volumio volumio[883]: info: Discovery: Started advertising with name: Volumio
Jan 13 20:23:13 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jan 13 20:23:13 volumio volumio[883]: info: Loading plugin "calmradio"...
Jan 13 20:23:15 volumio sudo[1056]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:16 volumio volumio[883]: info: Loading plugin "soundcloud"...
Jan 13 20:23:18 volumio volumio[883]: info: Loading plugin "youtube2"...
Jan 13 20:23:22 volumio volumio[883]: info: Plugin ytcr is not enabled
Jan 13 20:23:22 volumio volumio[883]: info: Loading plugin "autostart"...
Jan 13 20:23:23 volumio volumio[883]: info: Applying required configuration parameters for plugin autostart
Jan 13 20:23:23 volumio volumio[883]: info: AutoStart - onVolumioStart -  read config.json
Jan 13 20:23:23 volumio volumio[883]: info: Loading plugin "outputs"...
Jan 13 20:23:23 volumio volumio[883]: info: Loading plugin "albumart"...
Jan 13 20:23:23 volumio volumio[883]: info: Plugin example_plugin is not enabled
Jan 13 20:23:23 volumio volumio[883]: info: Loading plugin "inputs"...
Jan 13 20:23:23 volumio volumio[883]: info: Loading plugin "updater_comm"...
Jan 13 20:23:23 volumio volumio[883]: info: Plugin mpdemulation is not enabled
Jan 13 20:23:23 volumio volumio[883]: info: Loading plugin "rest_api"...
Jan 13 20:23:24 volumio volumio[883]: info: Loading plugin "websocket"...
Jan 13 20:23:24 volumio volumio[883]: info: Starting Socket.io Server version 2.3.0
Jan 13 20:23:24 volumio volumio[883]: info: Loading plugin "80s80s"...
Jan 13 20:23:24 volumio volumio[883]: Forking 3 albumart workers
Jan 13 20:23:25 volumio volumio[883]: info: Applying required configuration parameters for plugin 80s80s
Jan 13 20:23:25 volumio volumio[883]: info: [1736796205256] [80s80s] API delay: 30
Jan 13 20:23:25 volumio volumio[883]: info: Loading plugin "motherearthradio"...
Jan 13 20:23:25 volumio volumio-remote-updater[514]: [2025-01-13 20:23:25] [connect] Successful connection
Jan 13 20:23:26 volumio volumio[883]: info: Applying required configuration parameters for plugin motherearthradio
Jan 13 20:23:26 volumio volumio[883]: info: [1736796206466] [MotherEarth] API delay: 5
Jan 13 20:23:26 volumio volumio[883]: info: Loading plugin "podcast"...
Jan 13 20:23:26 volumio volumio[883]: Starting albumart workers
Jan 13 20:23:26 volumio volumio[883]: Starting albumart workers
Jan 13 20:23:27 volumio volumio[883]: Starting albumart workers
Jan 13 20:23:27 volumio volumio[883]: info: ControllerPodcast::constructor
Jan 13 20:23:27 volumio volumio[883]: info: Loading plugin "radio_paradise"...
Jan 13 20:23:28 volumio volumio[883]: info: Applying required configuration parameters for plugin radio_paradise
Jan 13 20:23:28 volumio volumio[883]: info: [1736796208850] [RadioParadise] API delay: 5
Jan 13 20:23:28 volumio volumio[883]: info: Loading plugin "audiophonicsonoff"...
Jan 13 20:23:29 volumio systemd[1]: systemd-timedated.service: Succeeded.
Jan 13 20:23:29 volumio volumio[883]: info: Applying required configuration parameters for plugin audiophonicsonoff
Jan 13 20:23:29 volumio volumio[883]: info: Audiophonics on/off initiated
Jan 13 20:23:29 volumio volumio[883]: info: Loading plugin "backup_restore"...
Jan 13 20:23:30 volumio volumio[883]: info: Applying required configuration parameters for plugin backup_restore
Jan 13 20:23:30 volumio volumio[883]: info: Loading plugin "randomizer"...
Jan 13 20:23:31 volumio volumio[883]: info: Applying required configuration parameters for plugin randomizer
Jan 13 20:23:31 volumio kernel: hwmon hwmon1: Voltage normalised
Jan 13 20:23:31 volumio volumio[883]: info: Loading plugin "touch_display"...
Jan 13 20:23:31 volumio volumio[883]: info: Applying required configuration parameters for plugin touch_display
Jan 13 20:23:32 volumio volumio[883]: info: Loading i18n strings for locale fr
Jan 13 20:23:32 volumio volumio[883]: Updating browse sources language
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::initPlayerControls
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jan 13 20:23:32 volumio volumio[883]: Express server listening on port 3000
Jan 13 20:23:32 volumio volumio[883]:  [Metrics]  WebUI:  32s 493.33ms 
Jan 13 20:23:32 volumio volumio[883]: info: CoreStateMachine::resetVolumioState
Jan 13 20:23:32 volumio volumio[883]: info: CoreStateMachine::getcurrentVolume
Jan 13 20:23:32 volumio volumio[883]: info: CoreCommandRouter::volumioRetrievevolume
Jan 13 20:23:33 volumio volumio[883]: info: Volumio Network Manager: Network status updated: 2
Jan 13 20:23:33 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1
Jan 13 20:23:33 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2
Jan 13 20:23:33 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3
Jan 13 20:23:33 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4
Jan 13 20:23:33 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5
Jan 13 20:23:33 volumio volumio[883]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Jan 13 20:23:33 volumio volumio[883]: info: Reloading queue from file
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:23:33 volumio volumio[883]: info: Setting Device type: Raspberry PI
Jan 13 20:23:33 volumio volumio[883]: info: CoreStateMachine::setRepeat false single undefined
Jan 13 20:23:33 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:23:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:23:33 volumio volumio[883]: info: CoreStateMachine::setRandom true
Jan 13 20:23:33 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:23:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:23:33 volumio volumio[883]: info: Completed loading Core Plugins
Jan 13 20:23:33 volumio volumio[883]: info: Preparing to generate the ALSA configuration file
Jan 13 20:23:33 volumio volumio[883]: info: VolumeController:: Volume=100 Mute =false
Jan 13 20:23:33 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:23:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:23:33 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock
Jan 13 20:23:33 volumio volumio[883]: info: CorePlayQueue::getTrackBlock
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::volumioRetrievevolume
Jan 13 20:23:33 volumio volumio[883]: info: Discovery: adding c18fb131-64f2-44dd-b9de-e698c3aac86b
Jan 13 20:23:33 volumio volumio[883]: info: Discovery: Found device Volumio
Jan 13 20:23:33 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:33 volumio volumio[883]: info: Asound.conf file unchanged, so no further update is needed
Jan 13 20:23:33 volumio volumio[883]: info: Output device has changed, restarting MPD
Jan 13 20:23:33 volumio volumio[883]: info: ___________ START PLUGINS ___________
Jan 13 20:23:33 volumio sudo[1187]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 13 20:23:33 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:33 volumio volumio[883]: info: ControllerMpd::onStart: Initializing MPD
Jan 13 20:23:33 volumio volumio[883]: info: Creating MPD Configuration file
Jan 13 20:23:33 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:33 volumio sudo[1189]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 13 20:23:33 volumio sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214016] CoreMusicLibrary::Adding element Last_100
Jan 13 20:23:34 volumio sudo[1193]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jan 13 20:23:34 volumio systemd[1]: Stopping Music Player Daemon...
Jan 13 20:23:34 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214033] CoreMusicLibrary::Adding element Webradio
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:23:34 volumio volumio[883]: info: Initializing BBC Radios
Jan 13 20:23:34 volumio sudo[1195]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jan 13 20:23:34 volumio sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:23:34 volumio volumio[883]: info: Adding Calm Radio to Browse Sources
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214258] CoreMusicLibrary::Adding element Calm Radio
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio systemd[1]: mpd.service: Succeeded.
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214294] CoreMusicLibrary::Adding element SoundCloud
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio systemd[1]: Stopped Music Player Daemon.
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source SoundCloud
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214327] CoreMusicLibrary::Adding element YouTube2
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source SoundCloud
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source YouTube2
Jan 13 20:23:34 volumio volumio[883]: info: AutoStart - onStart
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214353] CoreMusicLibrary::Adding element 80s80s Radio
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source SoundCloud
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source YouTube2
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source 80s80s Radio
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214371] CoreMusicLibrary::Adding element Mother Earth Radio
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source SoundCloud
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source YouTube2
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source 80s80s Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Mother Earth Radio
Jan 13 20:23:34 volumio systemd[1]: Starting Music Player Daemon...
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214417] CoreMusicLibrary::Adding element Podcast
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source SoundCloud
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source YouTube2
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source 80s80s Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Mother Earth Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Podcast
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jan 13 20:23:34 volumio volumio[883]: info: [1736796214436] CoreMusicLibrary::Adding element Radio Paradise
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Calm Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source SoundCloud
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source YouTube2
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source 80s80s Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Mother Earth Radio
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Podcast
Jan 13 20:23:34 volumio volumio[883]: Cannot find translation for source Radio Paradise
Jan 13 20:23:34 volumio volumio[883]: info: Configuring GPIO pins
Jan 13 20:23:34 volumio volumio[883]: error: Plugin audiophonicsonoff failed to start! Error: EINVAL: invalid argument, write
Jan 13 20:23:34 volumio kernel: export_store: invalid GPIO 4
Jan 13 20:23:34 volumio volumio[883]: info: Loading i18n strings for locale fr
Jan 13 20:23:34 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:23:34 volumio volumio[883]: info: Volumio Calling Home
Jan 13 20:23:34 volumio sudo[1208]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Jan 13 20:23:34 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:34 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:34 volumio sudo[1215]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Jan 13 20:23:34 volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:34 volumio systemd[1]: Reloading.
Jan 13 20:23:35 volumio volumio[883]: info: VolumeController:: Volume=100 Mute =false
Jan 13 20:23:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:23:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:23:35 volumio volumio[883]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.13 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/27.0 Chrome/125.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Jan 13 20:23:35 volumio volumio[883]: info: MPD Permissions set
Jan 13 20:23:35 volumio volumio[883]: info: MPD Permissions set
Jan 13 20:23:35 volumio kernel: hwmon hwmon1: Undervoltage detected!
Jan 13 20:23:35 volumio volumio[883]: 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 13 20:23:35 volumio volumio[883]: 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 13 20:23:35 volumio volumio[883]: info: Volumio called home
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue
Jan 13 20:23:35 volumio volumio[883]: info: CoreStateMachine::getQueue
Jan 13 20:23:35 volumio volumio[883]: info: CorePlayQueue::getQueue
Jan 13 20:23:35 volumio volumio[883]: info: Listing playlists
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 13 20:23:35 volumio volumio[883]: info: Received Get System Info
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 13 20:23:35 volumio volumio[883]: info: Discovery: Getting this device information
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 13 20:23:35 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:36 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Jan 13 20:23:36 volumio volumio[883]: info: touch_display: Raspberry Pi Foundation touch screen detected.
Jan 13 20:23:36 volumio volumio[883]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected.
Jan 13 20:23:36 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:36 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:37 volumio sudo[1272]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness
Jan 13 20:23:37 volumio systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Jan 13 20:23:37 volumio sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:37 volumio sudo[1272]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:37 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed.
Jan 13 20:23:37 volumio volumio[883]: info: touch_display: File permissions for backlight brightness control set.
Jan 13 20:23:37 volumio sudo[1215]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:37 volumio volumio[883]: info: touch_display: systemctl daemon-reload succeeded.
Jan 13 20:23:37 volumio sudo[1283]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Jan 13 20:23:37 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:23:37 volumio systemd[1]: Started Volumio Kiosk.
Jan 13 20:23:37 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:37 volumio volumio[883]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Jan 13 20:23:37 volumio volumio[883]: info: touch_display: Volumio Kiosk started
Jan 13 20:23:37 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:37 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:38 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:38 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:38 volumio volumio[883]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X
Jan 13 20:23:38 volumio volumio[883]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Jan 13 20:23:38 volumio startx[1285]: X.Org X Server 1.20.4
Jan 13 20:23:38 volumio startx[1285]: X Protocol Version 11, Revision 0
Jan 13 20:23:38 volumio startx[1285]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian
Jan 13 20:23:38 volumio startx[1285]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l
Jan 13 20:23:38 volumio startx[1285]: Kernel command line: coherent_pool=1M snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA0,115200 console=tty1 imgpart=UUID=aad048ad-7eb3-4549-8020-f5742baed86d imgfile=/volumio_current.sqsh bootpart=UUID=5883-B9C7 datapart=UUID=cfbe554b-d5ac-4dfd-b89b-effce104ffff uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no
Jan 13 20:23:38 volumio startx[1285]: Build Date: 07 February 2023  08:17:48AM
Jan 13 20:23:38 volumio startx[1285]: xorg-server 2:1.20.4-1+rpt4+deb10u8 (https://www.debian.org/support)
Jan 13 20:23:38 volumio startx[1285]: Current version of pixman: 0.36.0
Jan 13 20:23:38 volumio startx[1285]:         Before reporting problems, check http://wiki.x.org
Jan 13 20:23:38 volumio startx[1285]:         to make sure that you have the latest version.
Jan 13 20:23:38 volumio startx[1285]: Markers: (--) probed, (**) from config file, (==) default setting,
Jan 13 20:23:38 volumio startx[1285]:         (++) from command line, (!!) notice, (II) informational,
Jan 13 20:23:38 volumio startx[1285]:         (WW) warning, (EE) error, (NI) not implemented, (??) unknown.
Jan 13 20:23:38 volumio startx[1285]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Jan 13 20:23:38 2025
Jan 13 20:23:38 volumio startx[1285]: (==) Using config directory: "/etc/X11/xorg.conf.d"
Jan 13 20:23:38 volumio startx[1285]: (==) Using system config directory "/usr/share/X11/xorg.conf.d"
Jan 13 20:23:38 volumio volumio[883]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Jan 13 20:23:38 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:23:38 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:23:38 volumio volumio[883]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Jan 13 20:23:38 volumio mpd[1212]: Jan 13 20:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jan 13 20:23:39 volumio systemd[1]: Started Music Player Daemon.
Jan 13 20:23:39 volumio sudo[1189]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:39 volumio sudo[1195]: pam_unix(sudo:session): session closed for user root
Jan 13 20:23:39 volumio volumio[883]: info: Completed starting Core Plugins
Jan 13 20:23:39 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:39 volumio volumio[883]: info: -----    MyVolumio plugins startup     ----
Jan 13 20:23:39 volumio volumio[883]: info: -------------------------------------------
Jan 13 20:23:39 volumio volumio[883]: info: [MyVolumio PluginManager] Fetching plans data....
Jan 13 20:23:40 volumio volumio[883]: error: MPD error: The expression evaluated to a falsy value:
Jan 13 20:23:40 volumio volumio[883]:   assert.ok(self.idling)
Jan 13 20:23:40 volumio volumio[883]: error: The expression evaluated to a falsy value:
Jan 13 20:23:40 volumio volumio[883]:   assert.ok(self.idling)
Jan 13 20:23:40 volumio volumio[883]: info: MPD running with PID1212
Jan 13 20:23:40 volumio volumio[883]:  ,establishing connection
Jan 13 20:23:40 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:23:40 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:23:40 volumio volumio-remote-updater[514]: [2025-01-13 20:23:40] [connect] Successful connection
Jan 13 20:23:40 volumio volumio-remote-updater[514]: [2025-01-13 20:23:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736796220 101
Jan 13 20:23:40 volumio volumio[883]: 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: 10
Jan 13 20:23:41 volumio volumio[883]: info: touch_display: Setting screensaver timeout to 80 seconds.
Jan 13 20:23:43 volumio kernel: hwmon hwmon1: Voltage normalised
Jan 13 20:23:44 volumio ntpd[709]: Soliciting pool server 212.83.158.83
Jan 13 20:23:45 volumio ntpd[709]: Soliciting pool server 82.64.42.185
Jan 13 20:23:46 volumio ntpd[709]: Soliciting pool server 37.59.63.125
Jan 13 20:23:47 volumio ntpd[709]: Soliciting pool server 54.36.61.42
Jan 13 20:23:48 volumio ntpd[709]: Soliciting pool server 129.151.225.244
Jan 13 20:23:49 volumio ntpd[709]: Soliciting pool server 82.67.62.62
Jan 13 20:23:49 volumio ntpd[709]: Soliciting pool server 162.159.200.1
Jan 13 20:23:50 volumio ntpd[709]: Soliciting pool server 45.13.105.44
Jan 13 20:23:50 volumio ntpd[709]: Soliciting pool server 5.39.80.51
Jan 13 20:23:50 volumio ntpd[709]: Soliciting pool server 212.83.187.62
Jan 13 20:23:51 volumio ntpd[709]: Soliciting pool server 82.64.230.205
Jan 13 20:23:51 volumio ntpd[709]: Soliciting pool server 82.66.40.79
Jan 13 20:23:51 volumio ntpd[709]: receive: Unexpected origin timestamp 0xeb2fe6c7.3c3f9ecb does not match aorg 0000000000.00000000 from server@82.64.42.185 xmt 0xeb2fe6c7.f7d10b71
Jan 13 20:23:51 volumio ntpd[709]: receive: Unexpected origin timestamp 0xeb2fe6c7.3c3791c1 does not match aorg 0000000000.00000000 from server@54.36.61.42 xmt 0xeb2fe6c7.f89befef
Jan 13 20:23:51 volumio ntpd[709]: receive: Unexpected origin timestamp 0xeb2fe6c7.3c22836b does not match aorg 0000000000.00000000 from server@5.39.80.51 xmt 0xeb2fe6c7.f9141048
Jan 13 20:23:52 volumio dbus-daemon[501]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.16' (uid=1000 pid=1384 comm="/usr/lib/chromium-browser/chromium-browser-v7 --en")
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Jan 13 20:23:52 volumio volumio[883]: info: Adding plugin bluetooth to MyMusic Plugins
Jan 13 20:23:52 volumio volumio[883]: info: Adding plugin multiroom to MyMusic Plugins
Jan 13 20:23:52 volumio volumio[883]: info: Adding plugin metavolumio to MyMusic Plugins
Jan 13 20:23:52 volumio volumio[883]: info: Adding plugin cd_controller to MyMusic Plugins
Jan 13 20:23:52 volumio systemd[1]: Starting Bluetooth service...
Jan 13 20:23:52 volumio volumio[883]: info: Adding plugin smart_inputs to MyMusic Plugins
Jan 13 20:23:52 volumio volumio[883]: info: Adding plugin tidalconnect to MyMusic Plugins
Jan 13 20:23:52 volumio volumio[883]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Jan 13 20:23:52 volumio bluetoothd[1467]: Bluetooth daemon 5.50
Jan 13 20:23:52 volumio kernel: Bluetooth: Core ver 2.22
Jan 13 20:23:52 volumio kernel: NET: Registered PF_BLUETOOTH protocol family
Jan 13 20:23:52 volumio kernel: Bluetooth: HCI device and connection manager initialized
Jan 13 20:23:52 volumio kernel: Bluetooth: HCI socket layer initialized
Jan 13 20:23:52 volumio kernel: Bluetooth: L2CAP socket layer initialized
Jan 13 20:23:52 volumio kernel: Bluetooth: SCO socket layer initialized
Jan 13 20:23:52 volumio bluetoothd[1467]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Jan 13 20:23:52 volumio dbus-daemon[501]: [system] Successfully activated service 'org.bluez'
Jan 13 20:23:52 volumio systemd[1]: Started Bluetooth service.
Jan 13 20:23:52 volumio bluetoothd[1467]: Starting SDP server
Jan 13 20:23:52 volumio bluetoothd[1467]: Excluding (cli) sap
Jan 13 20:23:52 volumio ntpd[709]: Soliciting pool server 51.255.95.80
Jan 13 20:23:52 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jan 13 20:23:52 volumio kernel: Bluetooth: BNEP filters: protocol multicast
Jan 13 20:23:52 volumio kernel: Bluetooth: BNEP socket layer initialized
Jan 13 20:23:52 volumio bluetoothd[1467]: Bluetooth management interface 1.22 initialized
Jan 13 20:23:52 volumio dbus-daemon[501]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.17' (uid=0 pid=1467 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Jan 13 20:23:53 volumio systemd[1]: Starting Hostname Service...
Jan 13 20:23:53 volumio dbus-daemon[501]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jan 13 20:23:53 volumio systemd[1]: Started Hostname Service.
Jan 13 20:23:57 volumio volumio[883]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Jan 13 20:23:57 volumio volumio[883]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Jan 13 20:23:57 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:23:57 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:23:57 volumio volumio[883]: info: Starting MyVolumio Remote Streaming Endpoints
Jan 13 20:23:57 volumio volumio[883]: info: MyVolumio login type: Token
Jan 13 20:23:57 volumio volumio[883]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Jan 13 20:23:57 volumio volumio[883]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Jan 13 20:24:00 volumio volumio[883]: info: Starting Streaming Service Transparent Proxy
Jan 13 20:24:00 volumio volumio[883]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Jan 13 20:24:00 volumio volumio[883]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Jan 13 20:24:00 volumio volumio[883]: info: Streaming services startup
Jan 13 20:24:00 volumio volumio[883]: info: Starting Streaming Daemon
Jan 13 20:24:01 volumio volumio[883]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Jan 13 20:24:01 volumio sudo[1557]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 13 20:24:01 volumio sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:24:01 volumio sudo[1557]: pam_unix(sudo:session): session closed for user root
Jan 13 20:24:01 volumio volumio[883]: info: AutoStart - Plugin is starting
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::volumioGetQueue
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::getQueue
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getQueue
Jan 13 20:24:01 volumio volumio[883]: info: AutoStart - start playing
Jan 13 20:24:01 volumio volumio[883]: info: AutoStart - start playing with no specific position
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::volumioPlay
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::play index 0
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::stop
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::play index undefined
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand stop took 103 milliseconds
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear
Jan 13 20:24:01 volumio volumio[883]: error: Cannot start Volumio Streaming Daemon
Jan 13 20:24:01 volumio volumio[883]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 13 20:24:01 volumio volumio[883]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:01 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand clear took 73 milliseconds
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand consume 1
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:01 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:01 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:01 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces state update: options
Jan 13 20:24:01 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:01 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 139ms
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand consume 1 took 128 milliseconds
Jan 13 20:24:01 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 128ms
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 125ms
Jan 13 20:24:01 volumio volumio[883]: info: [1736796241536] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flac
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flac"
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces state update: options
Jan 13 20:24:01 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces state update: options
Jan 13 20:24:01 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:01 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand status took 68 milliseconds
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand add "https://stream.radioparadise.com/flac" took 65 milliseconds
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand status took 61 milliseconds
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand status took 58 milliseconds
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play
Jan 13 20:24:01 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:01 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:01 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:01 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:01 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:01 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:01 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 242ms
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 267ms
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 264ms
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:01 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:01 volumio volumio[883]: info:
Jan 13 20:24:01 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:01 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 282ms
Jan 13 20:24:01 volumio volumio[883]: info: sendMpdCommand play took 231 milliseconds
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 67ms
Jan 13 20:24:01 volumio volumio[883]: info: ------------------------------ 37ms
Jan 13 20:24:01 volumio volumio[883]: info: [1736796241883] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0
Jan 13 20:24:02 volumio volumio[883]: error: MyVolumio Custom Token format not valid, refreshing it
Jan 13 20:24:02 volumio volumio[883]: info: [1736796242991] [RadioParadise] received new metadata: {"time":166,"artist":"Father John Misty","title":"She Cleans Up","album":"Mahashmashana","year":"2024","cover":"https://img.radioparadise.com/covers/l/19408_1e81d35c-f071-40b7-a1c3-70396174ab41.jpg","cover_med":"https://img.radioparadise.com/covers/m/19408_1e81d35c-f071-40b7-a1c3-70396174ab41.jpg","cover_small":"https://img.radioparadise.com/covers/s/19408_1e81d35c-f071-40b7-a1c3-70396174ab41.jpg"}
Jan 13 20:24:03 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:03 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:03 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:03 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/19408_1e81d35c-f071-40b7-a1c3-70396174ab41.jpg","uri":"https://stream.radioparadise.com/flac","name":"She Cleans Up","title":"She Cleans Up","artist":"Father John Misty","album":"Mahashmashana","streaming":true,"disableUiControls":true,"duration":171,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
Jan 13 20:24:03 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:03 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:03 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus stop
Jan 13 20:24:03 volumio volumio[883]: info: [1736796243010] [RadioParadise] setting new timer with duration of 171000 seconds.
Jan 13 20:24:03 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:03 volumio volumio[883]: info: CorePlayQueue::getTrack 1
Jan 13 20:24:03 volumio volumio[883]: info: MyVolumio login type: Token
Jan 13 20:24:04 volumio volumio[883]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Jan 13 20:24:04 volumio volumio[883]: info: MyVolumio token set successfully
Jan 13 20:24:04 volumio volumio[883]: info: MYVOLUMIO: Adding device
Jan 13 20:24:04 volumio volumio[883]: info: MYVOLUMIO: Evaluating Server
Jan 13 20:24:04 volumio volumio[883]: info:
Jan 13 20:24:04 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:04 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:04 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:04 volumio volumio[883]: info:
Jan 13 20:24:04 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:04 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:04 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:04 volumio volumio[883]: info:
Jan 13 20:24:04 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:04 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:04 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:04 volumio volumio[883]: info: sendMpdCommand status took 19 milliseconds
Jan 13 20:24:04 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:04 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand status took 31 milliseconds
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand status took 27 milliseconds
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand playlistinfo took 18 milliseconds
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:05 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:05 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:05 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:05 volumio volumio[883]: info: ------------------------------ 77ms
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:05 volumio volumio[883]: STREAMING PROXY: Starting server on port 3245
Jan 13 20:24:05 volumio volumio[883]: Node JS runtime: 14
Jan 13 20:24:05 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:24:05 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:05 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:05 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand status took 332 milliseconds
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand playlistinfo took 315 milliseconds
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand playlistinfo took 315 milliseconds
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand status took 288 milliseconds
Jan 13 20:24:05 volumio volumio[883]: info: sendMpdCommand status took 286 milliseconds
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:05 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:05 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:05 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:05 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:05 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:05 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:05 volumio volumio[883]: info: ------------------------------ 404ms
Jan 13 20:24:05 volumio volumio[883]: info: ------------------------------ 398ms
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:05 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:05 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:05 volumio volumio[883]: info:
Jan 13 20:24:05 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:05 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:05 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:06 volumio volumio[883]: info: MyVolumio status changed
Jan 13 20:24:06 volumio volumio[883]: info: Streaming services startup
Jan 13 20:24:06 volumio volumio[883]: info: Starting Streaming Daemon
Jan 13 20:24:06 volumio volumio[883]: info: Removing browser output: myVolumio user plan is not superstar
Jan 13 20:24:06 volumio volumio[883]: info: Removing audio output:
Jan 13 20:24:06 volumio volumio[883]: info: Stoppping Tunnel 1
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jan 13 20:24:06 volumio volumio-remote-updater[514]: Test mode enabled
Jan 13 20:24:06 volumio volumio-remote-updater[514]: No alpha test mode
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1215ms
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand status took 1209 milliseconds
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1197 milliseconds
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1191 milliseconds
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand playlistinfo took 1191 milliseconds
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1173ms
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand status took 1168 milliseconds
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1164ms
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand status took 1159 milliseconds
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:06 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:06 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:06 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:06 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:06 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:06 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:06 volumio sudo[1599]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jan 13 20:24:06 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:06 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:06 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:06 volumio sudo[1601]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1604ms
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1549ms
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1544ms
Jan 13 20:24:06 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:24:06 volumio sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jan 13 20:24:06 volumio sudo[1599]: pam_unix(sudo:session): session closed for user root
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand playlistinfo took 145 milliseconds
Jan 13 20:24:06 volumio volumio[883]: info: sendMpdCommand playlistinfo took 142 milliseconds
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:06 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:06 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:06 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:06 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:06 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:06 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:06 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:06 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:06 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:06 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1401ms
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1357ms
Jan 13 20:24:06 volumio volumio[883]: info: ------------------------------ 1347ms
Jan 13 20:24:06 volumio volumio[883]: info: Setting Geolocation for MyVolumio to eu4
Jan 13 20:24:06 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:06 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:06 volumio volumio[883]: error: Cannot start Volumio Streaming Daemon
Jan 13 20:24:06 volumio volumio[883]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Jan 13 20:24:06 volumio volumio[883]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Jan 13 20:24:06 volumio volumio[883]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\nImproved translations
\nFix for Allo Piano DAC on RPi with kernel 6.6.x
\n
\nNEW ADDITIONS
\n\nAdd support for Raspberry Pi Display V2 on RPi
\nBump kernel to 6.6.62 on RPi
\nAdd DSD-quirk for Luxman DA-250 on Rivo and Primo2
\nAdd DSD-quirk for Musical Fidelity M6X on Rivo and Primo2
\nAdd DSD-quirk for several USB DACs on RPi
\nEnable install-to-NVME on CM5 and newer RPi5 revisions
\n\n","title":"Update v3.788","updateavailable":true}
Jan 13 20:24:07 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jan 13 20:24:07 volumio volumio[883]: info: Remote SSH Stopped
Jan 13 20:24:07 volumio volumio[883]: info: Updating MyVolumio device info
Jan 13 20:24:07 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:08 volumio volumio[883]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 13 20:24:08 volumio volumio[883]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 13 20:24:09 volumio volumio[883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Jan 13 20:24:12 volumio volumio[883]: info: MYVOLUMIO: Adding device
Jan 13 20:24:12 volumio volumio[883]: info: MYVOLUMIO: Evaluating Server
Jan 13 20:24:12 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 13 20:24:12 volumio volumio[883]: 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 13 20:24:13 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:13 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:13 volumio volumio[883]: info: Setting Geolocation for MyVolumio to eu4
Jan 13 20:24:13 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:13 volumio volumio[883]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Jan 13 20:24:13 volumio volumio[883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Jan 13 20:24:14 volumio volumio[883]: info: Updating MyVolumio device info
Jan 13 20:24:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:14 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:14 volumio volumio[883]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::volumioGetBrowseSources
Jan 13 20:24:17 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:24:17 volumio volumio[883]: info: Preload queue cleared
Jan 13 20:24:18 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jan 13 20:24:18 volumio volumio[883]: info: Preload queue cleared
Jan 13 20:24:18 volumio volumio[883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Jan 13 20:24:19 volumio volumio[883]: info: Preload queue cleared
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::ClearQueue
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::stop
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::stPlaybackTimer
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrackBlock
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::serviceStop
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::serviceStop
Jan 13 20:24:19 volumio volumio[883]: info: ControllerMpd::stop
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::clearPlayQueue
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::saveQueue
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushQueue
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::addQueueItems
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::addQueueItems
Jan 13 20:24:19 volumio volumio[883]: info: Preload queue cleared
Jan 13 20:24:19 volumio volumio[883]: info: Adding Item to queue: http://direct.fipradio.fr/live/fip-hifi.aac
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushQueue
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::saveQueue
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::updateTrackBlock
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrackBlock
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPlay
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::play index 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::stop
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::play index undefined
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: [1736796259261] ControllerWebradio::clearAddPlayTrack
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop
Jan 13 20:24:19 volumio volumio[883]: info:
Jan 13 20:24:19 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand stop took 174 milliseconds
Jan 13 20:24:19 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand stop took 54 milliseconds
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received radio_paradise
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear
Jan 13 20:24:19 volumio volumio[883]: info:
Jan 13 20:24:19 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:19 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:19 volumio volumio[883]: info:
Jan 13 20:24:19 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:19 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:19 volumio volumio[883]: info:
Jan 13 20:24:19 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:19 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand status took 101 milliseconds
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand clear took 60 milliseconds
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand status took 35 milliseconds
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand status took 32 milliseconds
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand load "http://direct.fipradio.fr/live/fip-hifi.aac"
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:19 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Jan 13 20:24:19 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:19 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:19 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:19 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Jan 13 20:24:19 volumio volumio[883]: info: ------------------------------ 160ms
Jan 13 20:24:19 volumio volumio[883]: info: ------------------------------ 157ms
Jan 13 20:24:19 volumio volumio[883]: info:
Jan 13 20:24:19 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:19 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:19 volumio volumio[883]: info:
Jan 13 20:24:19 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:19 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:19 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:19 volumio volumio[883]: info: ------------------------------ 179ms
Jan 13 20:24:19 volumio volumio[883]: info: sendMpdCommand playlistinfo took 169 milliseconds
Jan 13 20:24:19 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:19 volumio volumio[883]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jan 13 20:24:19 volumio volumio[883]: info: ------------------------------ 287ms
Jan 13 20:24:21 volumio volumio[883]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Jan 13 20:24:21 volumio volumio[883]: info: Completed starting MyVolumio Plugin
Jan 13 20:24:21 volumio volumio[883]:  [Metrics]  CommandRouter:  77s 161.96ms 
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumiosetStartupVolume
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jan 13 20:24:21 volumio volumio[883]: info: VolumeController:: Setting startup Volume 100
Jan 13 20:24:21 volumio volumio[883]: info: VolumeController::SetAlsaVolume100
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::Close All Modals sent
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::Close All Modals sent
Jan 13 20:24:21 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:21 volumio volumio[883]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 13 20:24:21 volumio volumio[883]: info: Received Get System Info
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 13 20:24:21 volumio volumio[883]: info: Discovery: Getting this device information
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: Listing playlists
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 13 20:24:21 volumio volumio[883]: info: Received Get System Info
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 13 20:24:21 volumio volumio[883]: info: Discovery: Getting this device information
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jan 13 20:24:21 volumio volumio[883]: info: Received Get System Info
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 13 20:24:21 volumio volumio[883]: info: Discovery: Getting this device information
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetVisibleSources
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jan 13 20:24:21 volumio volumio[883]: info: Received Get System Info
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 13 20:24:21 volumio volumio[883]: info: Discovery: Getting this device information
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:21 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:21 volumio volumio[883]: info: Listing playlists
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 13 20:24:21 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jan 13 20:24:21 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:21 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand add "http://direct.fipradio.fr/live/fip-hifi.aac"
Jan 13 20:24:21 volumio volumio[883]: info: ------------------------------ 2417ms
Jan 13 20:24:21 volumio volumio[883]: info: ------------------------------ 2413ms
Jan 13 20:24:21 volumio volumio[883]: info:
Jan 13 20:24:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:21 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:21 volumio volumio[883]: info: sendMpdCommand add "http://direct.fipradio.fr/live/fip-hifi.aac" took 14 milliseconds
Jan 13 20:24:21 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService mpd
Jan 13 20:24:21 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play
Jan 13 20:24:21 volumio volumio[883]: info:
Jan 13 20:24:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:21 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:21 volumio volumio[883]: info:
Jan 13 20:24:21 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:21 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:21 volumio volumio[883]: info: ------------------------------ 22ms
Jan 13 20:24:21 volumio volumio[883]: info: sendMpdCommand play took 17 milliseconds
Jan 13 20:24:22 volumio volumio[883]: info: ------------------------------ 16ms
Jan 13 20:24:22 volumio volumio[883]: info: ------------------------------ 12ms
Jan 13 20:24:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jan 13 20:24:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jan 13 20:24:22 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jan 13 20:24:23 volumio volumio[883]: info:
Jan 13 20:24:23 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:23 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:23 volumio volumio[883]: info:
Jan 13 20:24:23 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:23 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:23 volumio volumio[883]: info:
Jan 13 20:24:23 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:23 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clearerror
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand status took 72 milliseconds
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clearerror
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand status took 53 milliseconds
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clearerror
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand status took 52 milliseconds
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand clearerror took 83 milliseconds
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand clearerror took 87 milliseconds
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand clearerror took 83 milliseconds
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand playlistinfo took 37 milliseconds
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand playlistinfo took 32 milliseconds
Jan 13 20:24:23 volumio volumio[883]: info: sendMpdCommand playlistinfo took 29 milliseconds
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:23 volumio systemd[1]: systemd-hostnamed.service: Succeeded.
Jan 13 20:24:23 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:23 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:23 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip-hifi.aac","artist":null,"album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:23 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::syncState   stateService pause
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus stop
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:23 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:23 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip-hifi.aac","artist":null,"album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:23 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::syncState   stateService pause
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus stop
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:23 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:23 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:23 volumio volumio[883]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"fip-hifi.aac","artist":null,"album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:23 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::syncState   stateService pause
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus stop
Jan 13 20:24:23 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:23 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:23 volumio volumio[883]: info: ------------------------------ 360ms
Jan 13 20:24:23 volumio volumio[883]: info: ------------------------------ 336ms
Jan 13 20:24:23 volumio volumio[883]: info: ------------------------------ 332ms
Jan 13 20:24:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jan 13 20:24:24 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jan 13 20:24:28 volumio volumio[883]: info: BOOT COMPLETED
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jan 13 20:24:32 volumio volumio[883]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jan 13 20:24:32 volumio volumio[883]: info: Received Get System Version
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jan 13 20:24:32 volumio volumio[883]: info: Received Get System Info
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jan 13 20:24:32 volumio volumio[883]: info: Discovery: Getting this device information
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::volumioGetState
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jan 13 20:24:32 volumio volumio[883]: info: CoreCommandRouter::volumioPlay
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::play index 0
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::stop
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::play index undefined
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService undefined
Jan 13 20:24:32 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:32 volumio volumio[883]: info: CoreStateMachine::startPlaybackTimer
Jan 13 20:24:32 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:32 volumio volumio[883]: info: [1736796272588] ControllerWebradio::clearAddPlayTrack
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand stop
Jan 13 20:24:32 volumio volumio[883]: info:
Jan 13 20:24:32 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:32 volumio volumio[883]: info: sendMpdCommand stop took 11 milliseconds
Jan 13 20:24:32 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand clear
Jan 13 20:24:32 volumio volumio[883]: info:
Jan 13 20:24:32 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:32 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:32 volumio volumio[883]: info: sendMpdCommand status took 10 milliseconds
Jan 13 20:24:32 volumio volumio[883]: info: sendMpdCommand clear took 10 milliseconds
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand load "http://direct.fipradio.fr/live/fip-hifi.aac"
Jan 13 20:24:32 volumio volumio[883]: info:
Jan 13 20:24:32 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:32 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:32 volumio volumio[883]: info:
Jan 13 20:24:32 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:32 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:32 volumio volumio[883]: error: updateQueue error: null
Jan 13 20:24:32 volumio volumio[883]: info: ------------------------------ 24ms
Jan 13 20:24:32 volumio volumio[883]: info: sendMpdCommand playlistinfo took 18 milliseconds
Jan 13 20:24:32 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:32 volumio volumio[883]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Jan 13 20:24:32 volumio volumio[883]: info: ------------------------------ 41ms
Jan 13 20:24:33 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand add "http://direct.fipradio.fr/live/fip-hifi.aac"
Jan 13 20:24:33 volumio volumio[883]: info: sendMpdCommand status took 1041 milliseconds
Jan 13 20:24:33 volumio volumio[883]: info: sendMpdCommand status took 1037 milliseconds
Jan 13 20:24:33 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:33 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:33 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:33 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:33 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:33 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:33 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:33 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Jan 13 20:24:33 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:33 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:33 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:33 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:33 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:33 volumio volumio[883]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 1113ms
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 1110ms
Jan 13 20:24:33 volumio volumio[883]: info:
Jan 13 20:24:33 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:33 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:33 volumio volumio[883]: info:
Jan 13 20:24:33 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:33 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:33 volumio volumio[883]: info:
Jan 13 20:24:33 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:33 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:33 volumio volumio[883]: info: sendMpdCommand add "http://direct.fipradio.fr/live/fip-hifi.aac" took 88 milliseconds
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 12ms
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 10ms
Jan 13 20:24:33 volumio volumio[883]: info: CoreStateMachine::setConsumeUpdateService mpd
Jan 13 20:24:33 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand play
Jan 13 20:24:33 volumio volumio[883]: info:
Jan 13 20:24:33 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:33 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:33 volumio volumio[883]: info:
Jan 13 20:24:33 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:33 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 43ms
Jan 13 20:24:33 volumio volumio[883]: info: sendMpdCommand play took 39 milliseconds
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 35ms
Jan 13 20:24:33 volumio volumio[883]: info: ------------------------------ 32ms
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand status took 22 milliseconds
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:35 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand status took 31 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand status took 28 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand playlistinfo took 13 milliseconds
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"fip-hifi.aac","artist":"fip-hifi.aac","album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:35 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus stop
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 53ms
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:35 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces system playlist update
Jan 13 20:24:35 volumio volumio[883]: info: Ignoring MPD Status Update
Jan 13 20:24:35 volumio volumio[883]: info:
Jan 13 20:24:35 volumio volumio[883]: ---------------------------- MPD announces state update: player
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::getState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand status
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 39ms
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand status took 36 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand playlistinfo took 31 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand playlistinfo took 29 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 20ms
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand status took 16 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 15ms
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand status took 11 milliseconds
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseState
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":609,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"fip-hifi.aac","artist":"fip-hifi.aac","album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:35 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus play
Jan 13 20:24:35 volumio volumio[883]: info: Received an update from plugin. extracting info from payload
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":609,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"fip-hifi.aac","artist":"fip-hifi.aac","album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:35 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus play
Jan 13 20:24:35 volumio volumio[883]: info: Received an update from plugin. extracting info from payload
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 224ms
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 223ms
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand playlistinfo took 156 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand playlistinfo took 154 milliseconds
Jan 13 20:24:35 volumio volumio[883]: info: sendMpdCommand playlistinfo took 153 milliseconds
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:35 volumio volumio[883]: verbose: ControllerMpd::parseTrackInfo
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"fip-hifi.aac","artist":"fip-hifi.aac","album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:35 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus play
Jan 13 20:24:35 volumio volumio[883]: info: Received an update from plugin. extracting info from payload
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"fip-hifi.aac","artist":"fip-hifi.aac","album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:35 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus play
Jan 13 20:24:35 volumio volumio[883]: info: Received an update from plugin. extracting info from payload
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: ControllerMpd::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::servicePushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"181 Kbps","isStreaming":false,"title":"fip-hifi.aac","artist":"fip-hifi.aac","album":null,"uri":"http://direct.fipradio.fr/live/fip-hifi.aac","trackType":"aac"}
Jan 13 20:24:35 volumio volumio[883]: verbose: CURRENT POSITION 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   stateService play
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::syncState   currentStatus play
Jan 13 20:24:35 volumio volumio[883]: info: Received an update from plugin. extracting info from payload
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CorePlayQueue::getTrack 0
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreStateMachine::pushState
Jan 13 20:24:35 volumio volumio[883]: info: CoreCommandRouter::volumioPushState
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 468ms
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 448ms
Jan 13 20:24:35 volumio volumio[883]: info: ------------------------------ 443ms
Jan 13 20:24:36 volumio volumio[883]: info: touch_display: Setting screensaver timeout to 0 seconds.
Jan 13 20:24:45 volumio volumio[883]: info: ___________ PLUGINS: Run Shutdown Tasks ___________
Jan 13 20:24:45 volumio volumio[883]: info: PLUGIN onShutdown : networkfs
Jan 13 20:24:45 volumio volumio[883]: info: PLUGIN onShutdown : audiophonicsonoff
Jan 13 20:24:45 volumio volumio[883]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 13 20:24:45 volumio volumio[883]: TypeError: Cannot read property 'writeSync' of undefined
Jan 13 20:24:45 volumio volumio[883]:     at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25)
Jan 13 20:24:45 volumio volumio[883]:     at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30)
Jan 13 20:24:45 volumio volumio[883]:     at HashMap. (/volumio/app/pluginmanager.js:621:31)
Jan 13 20:24:45 volumio volumio[883]:     at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10)
Jan 13 20:24:45 volumio volumio[883]:     at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7)
Jan 13 20:24:45 volumio volumio[883]:     at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20)
Jan 13 20:24:45 volumio volumio[883]:     at CoreCommandRouter.shutdown (/volumio/app/index.js:1332:24)
Jan 13 20:24:45 volumio volumio[883]:     at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33)
Jan 13 20:24:45 volumio volumio[883]:     at Socket.emit (events.js:315:20)
Jan 13 20:24:45 volumio volumio[883]:     at /volumio/node_modules/socket.io/lib/socket.js:528:12
Jan 13 20:24:45 volumio volumio[883]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jan 13 20:24:46 volumio sudo[1928]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 20:23
Jan 13 20:24:46 volumio sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 09 Jan 2025 10:53:32 AM CET"
VOLUMIO_VERSION="3.787"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="a8dde41a0a74a6944b1f5d9c8dec1590"