-- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Mon 2026-01-12 09:07:29 GMT. -- Jan 12 09:06:07 volumiopi volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:06:07 volumiopi volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:06:07 volumiopi volumio[1249]: info: Discovery: Getting this device information Jan 12 09:06:07 volumiopi volumio[1249]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:07 volumiopi volumio[1249]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:07 volumiopi volumio[1249]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:06:07 volumiopi volumio[1249]: verbose: New Socket.io Connection to 192.168.2.112:3000 from 192.168.2.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Jan 12 09:06:08 volumiopi volumio[1249]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 09:06:08 volumiopi volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 09:06:09 volumiopi wpa_supplicant[960]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="VM55ABC45_2.4" Jan 12 09:06:09 volumiopi wpa_supplicant[960]: wlan0: Trying to associate with SSID 'VM55ABC45_2.4' Jan 12 09:06:09 volumiopi wpa_supplicant[960]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jan 12 09:06:09 volumiopi wpa_supplicant[960]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="VM55ABC45_2.4" auth_failures=3 duration=57 reason=CONN_FAILED Jan 12 09:06:20 volumiopi volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:06:20 volumiopi volumio[1249]: Error: connect ETIMEDOUT 151.101.194.53:443 Jan 12 09:06:20 volumiopi volumio[1249]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 12 09:06:20 volumiopi volumio[1249]: errno: -110, Jan 12 09:06:20 volumiopi volumio[1249]: code: 'ETIMEDOUT', Jan 12 09:06:20 volumiopi volumio[1249]: syscall: 'connect', Jan 12 09:06:20 volumiopi volumio[1249]: address: '151.101.194.53', Jan 12 09:06:20 volumiopi volumio[1249]: port: 443 Jan 12 09:06:20 volumiopi volumio[1249]: } Jan 12 09:06:20 volumiopi volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:06:21 volumiopi sudo[11444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 09:05 Jan 12 09:06:21 volumiopi sudo[11444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:21 volumiopi sudo[11444]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:21 volumiopi volumio-remote-updater[649]: [2026-01-12 09:06:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 12 09:06:21 volumiopi systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 12 09:06:21 volumiopi ntfs-3g[1351]: Unmounting /dev/sda1 (SSD-USB-1TB-WB-Blue Volume) Jan 12 09:06:21 volumiopi volumio-remote-updater[649]: [2026-01-12 09:06:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 12 09:06:21 volumiopi systemd[1]: media-SSD\x2dUSB\x2d1TB\x2dWB\x2dBlue_Volume.mount: Succeeded. Jan 12 09:06:21 volumiopi systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 12 09:06:21 volumiopi systemd[1]: Started dynamicswap service. Jan 12 09:06:21 volumiopi systemd[1]: dynamicswap.service: Succeeded. Jan 12 09:06:21 volumiopi systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 12 09:06:21 volumiopi systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 12 09:06:22 volumiopi systemd[1]: Started dynamicswap service. Jan 12 09:06:22 volumiopi systemd[1]: Stopped Volumio Backend Module. Jan 12 09:06:22 volumiopi systemd[1]: Started Volumio Backend Module. Jan 12 09:06:22 volumiopi systemd[1]: dynamicswap.service: Succeeded. Jan 12 09:06:23 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:23 volumiopi volumio[11460]: info: ----- Volumio3 ---- Jan 12 09:06:23 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:23 volumiopi volumio[11460]: info: ----- System startup ---- Jan 12 09:06:23 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:24 volumiopi volumio[11460]: info: MYVOLUMIO Environment detected Jan 12 09:06:24 volumiopi volumio[11460]: info: Plugin folders cleanup Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning into folder /volumio/app/plugins/ Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category audio_interface Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category miscellanea Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category music_service Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category plugins.json Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category system_controller Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category user_interface Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning into folder /data/plugins/ Jan 12 09:06:24 volumiopi volumio[11460]: info: Scanning category audio_interface Jan 12 09:06:24 volumiopi volumio[11460]: info: Plugin folders cleanup completed Jan 12 09:06:24 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:24 volumiopi volumio[11460]: info: ----- Core plugins startup ---- Jan 12 09:06:24 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:24 volumiopi volumio[11460]: info: Loading plugins from folder /volumio/app/plugins/ Jan 12 09:06:24 volumiopi volumio[11460]: info: Adding plugin upnp to MyMusic Plugins Jan 12 09:06:24 volumiopi volumio[11460]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 12 09:06:24 volumiopi volumio[11460]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 12 09:06:24 volumiopi volumio[11460]: info: Loading plugins from folder /data/plugins/ Jan 12 09:06:24 volumiopi volumio[11460]: info: Loading plugin "system"... Jan 12 09:06:24 volumiopi volumio[11460]: info: Loading plugin "appearance"... Jan 12 09:06:25 volumiopi volumio[11460]: info: Loading plugin "network"... Jan 12 09:06:25 volumiopi volumio[11460]: info: Refreshing Cached IP Addresses Jan 12 09:06:25 volumiopi sudo[11486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 12 09:06:25 volumiopi sudo[11486]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:25 volumiopi sudo[11488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 09:06:25 volumiopi sudo[11488]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:25 volumiopi sudo[11486]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:25 volumiopi sudo[11488]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:25 volumiopi volumio[11460]: info: Loading plugin "services"... Jan 12 09:06:25 volumiopi volumio[11460]: info: Loading plugin "alsa_controller"... Jan 12 09:06:25 volumiopi sudo[11497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 12 09:06:25 volumiopi sudo[11497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:25 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:06:25 volumiopi volumio[11460]: info: Loading plugin "wizard"... Jan 12 09:06:25 volumiopi volumio[11460]: info: Loading plugin "networkfs"... Jan 12 09:06:25 volumiopi volumio[11460]: info: Starting Udev Watcher for removable devices Jan 12 09:06:25 volumiopi volumio[11460]: info: Ignoring mount for partition: boot Jan 12 09:06:25 volumiopi volumio[11460]: info: Ignoring mount for partition: volumio Jan 12 09:06:25 volumiopi volumio[11460]: info: Ignoring mount for partition: volumio_data Jan 12 09:06:25 volumiopi volumio[11460]: info: Mounting Device SSD-USB-1TB-WB-Blue_Volume Jan 12 09:06:25 volumiopi sudo[11517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/SSD-USB-1TB-WB-Blue_Volume -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 12 09:06:25 volumiopi sudo[11517]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:26 volumiopi sudo[11497]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:26 volumiopi volumio-remote-updater[649]: [2026-01-12 09:06:26] [connect] Successful connection Jan 12 09:06:26 volumiopi ntfs-3g[11523]: Version 2017.3.23AR.3 integrated FUSE 28 Jan 12 09:06:26 volumiopi ntfs-3g[11523]: Mounted /dev/sda1 (Read-Write, label "SSD-USB-1TB-WB-Blue Volume", NTFS 3.1) Jan 12 09:06:26 volumiopi ntfs-3g[11523]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jan 12 09:06:26 volumiopi ntfs-3g[11523]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Jan 12 09:06:26 volumiopi ntfs-3g[11523]: Global ownership and permissions enforced, configuration type 7 Jan 12 09:06:26 volumiopi ntfs-3g[11523]: Unmounting /dev/sda1 (SSD-USB-1TB-WB-Blue Volume) Jan 12 09:06:26 volumiopi sudo[11517]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:26 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 09:06:26 volumiopi volumio[11460]: info: Loading plugin "volumio_command_line_client"... Jan 12 09:06:26 volumiopi volumio[11460]: info: Plugin upnp is not enabled Jan 12 09:06:26 volumiopi volumio[11460]: info: Loading plugin "my_music"... Jan 12 09:06:26 volumiopi volumio[11460]: info: Loading plugin "mpd"... Jan 12 09:06:27 volumiopi volumio[11460]: info: Plugin upnp_browser is not enabled Jan 12 09:06:27 volumiopi volumio[11460]: info: Loading plugin "alarm-clock"... Jan 12 09:06:27 volumiopi volumio[11460]: info: Plugin airplay_emulation is not enabled Jan 12 09:06:27 volumiopi volumio[11460]: info: Loading plugin "last_100"... Jan 12 09:06:27 volumiopi volumio[11460]: info: Loading plugin "webradio"... Jan 12 09:06:27 volumiopi volumio[11460]: info: Loading plugin "i2s_dacs"... Jan 12 09:06:27 volumiopi volumio[11460]: info: Loading plugin "volumiodiscovery"... Jan 12 09:06:27 volumiopi volumio[11460]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 09:06:27 volumiopi volumio[11460]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 09:06:27 volumiopi volumio[11460]: *** WARNING *** For more information see Jan 12 09:06:27 volumiopi node[11460]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 09:06:27 volumiopi volumio[11460]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 09:06:27 volumiopi volumio[11460]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 09:06:27 volumiopi volumio[11460]: *** WARNING *** For more information see Jan 12 09:06:27 volumiopi node[11460]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 09:06:27 volumiopi node[11460]: *** WARNING *** For more information see Jan 12 09:06:27 volumiopi node[11460]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 09:06:27 volumiopi node[11460]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 09:06:27 volumiopi node[11460]: *** WARNING *** For more information see Jan 12 09:06:27 volumiopi volumio[11460]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 12 09:06:29 volumiopi volumio[11460]: info: Discovery: Started advertising with name: VolumioPi Jan 12 09:06:29 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "outputs"... Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "albumart"... Jan 12 09:06:29 volumiopi volumio[11460]: info: Plugin example_plugin is not enabled Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "inputs"... Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "updater_comm"... Jan 12 09:06:29 volumiopi volumio[11460]: info: Plugin mpdemulation is not enabled Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "rest_api"... Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "websocket"... Jan 12 09:06:29 volumiopi volumio[11460]: info: Starting Socket.io Server version 2.3.0 Jan 12 09:06:29 volumiopi volumio[11460]: info: Loading plugin "fusiondsp"... Jan 12 09:06:29 volumiopi volumio[11460]: Forking 3 albumart workers Jan 12 09:06:29 volumiopi volumio[11460]: info: Applying required configuration parameters for plugin fusiondsp Jan 12 09:06:30 volumiopi volumio[11460]: info: Loading i18n strings for locale en Jan 12 09:06:30 volumiopi volumio[11460]: Updating browse sources language Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::initPlayerControls Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 09:06:30 volumiopi volumio[11460]: Express server listening on port 3000 Jan 12 09:06:30 volumiopi volumio[11460]: [Metrics] WebUI: 7s 233.13ms Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreStateMachine::resetVolumioState Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreStateMachine::getcurrentVolume Jan 12 09:06:30 volumiopi volumio[11460]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 09:06:30 volumiopi volumio[11460]: info: Volumio Network Manager: Network status updated: 1 Jan 12 09:06:30 volumiopi volumio[11460]: Starting albumart workers Jan 12 09:06:30 volumiopi volumio[11460]: Starting albumart workers Jan 12 09:06:30 volumiopi volumio[11460]: Starting albumart workers Jan 12 09:06:30 volumiopi volumio[11460]: verbose: New Socket.io Connection to 192.168.2.112:3000 from 192.168.2.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 Jan 12 09:06:30 volumiopi volumio[11460]: verbose: New Socket.io Connection to 192.168.2.114:3000 from 192.168.2.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jan 12 09:06:30 volumiopi volumio-remote-updater[649]: [2026-01-12 09:06:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1768208786 101 Jan 12 09:06:30 volumiopi volumio[11460]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:06:39 volumiopi volumio[11460]: info: Discovery: Getting this device information Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: Reloading queue from file Jan 12 09:06:39 volumiopi volumio[11460]: info: VolumeController:: Volume=15 Mute =false Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::updateTrackBlock Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrackBlock Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::setRepeat false single undefined Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::setRandom null Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:39 volumiopi volumio[11460]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 12 09:06:39 volumiopi volumio[11460]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a22082 Jan 12 09:06:39 volumiopi volumio[11460]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 12 09:06:39 volumiopi volumio[11460]: info: Setting Device type: Raspberry PI Jan 12 09:06:39 volumiopi volumio[11460]: info: Completed loading Core Plugins Jan 12 09:06:39 volumiopi volumio[11460]: info: Preparing to generate the ALSA configuration file Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:06:39 volumiopi volumio[11460]: info: Discovery: Getting this device information Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:06:39 volumiopi volumio[11460]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 12 09:06:39 volumiopi volumio[11460]: info: Reading ALSA contributions from plugins. Jan 12 09:06:39 volumiopi volumio[11460]: info: Discovery: adding d4b4de0e-5f4a-492f-98d5-1cbfd45e2e2c Jan 12 09:06:39 volumiopi volumio[11460]: info: Discovery: Found device VolumioPi Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: VolumeController:: Volume=15 Mute =false Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:39 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:39 volumiopi volumio[11460]: verbose: New Socket.io Connection to 192.168.2.112:3000 from 192.168.2.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Jan 12 09:06:39 volumiopi volumio[11460]: info: Asound.conf file unchanged, so no further update is needed Jan 12 09:06:39 volumiopi volumio[11460]: info: Output device has changed, restarting MPD Jan 12 09:06:39 volumiopi sudo[11644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 09:06:39 volumiopi volumio[11460]: info: ___________ START PLUGINS ___________ Jan 12 09:06:39 volumiopi sudo[11644]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:39 volumiopi sudo[11644]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:39 volumiopi volumio[11460]: info: ControllerMpd::onStart: Initializing MPD Jan 12 09:06:39 volumiopi volumio[11460]: info: Creating MPD Configuration file Jan 12 09:06:39 volumiopi sudo[11646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 09:06:39 volumiopi sudo[11646]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 09:06:39 volumiopi volumio[11460]: info: [1768208799551] CoreMusicLibrary::Adding element Last_100 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 09:06:39 volumiopi volumio[11460]: info: [1768208799570] CoreMusicLibrary::Adding element Webradio Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 09:06:39 volumiopi systemd[1]: Stopping Music Player Daemon... Jan 12 09:06:39 volumiopi sudo[11652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 09:06:39 volumiopi sudo[11652]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:06:39 volumiopi volumio[11460]: info: Initializing BBC Radios Jan 12 09:06:39 volumiopi sudo[11650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 09:06:39 volumiopi sudo[11650]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:39 volumiopi sudo[11650]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: Loading i18n strings for locale en Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: FusionDsp - mixtype--------------------- Hardware Jan 12 09:06:39 volumiopi volumio[11460]: info: Volumio Calling Home Jan 12 09:06:39 volumiopi systemd[1]: mpd.service: Succeeded. Jan 12 09:06:39 volumiopi systemd[1]: Stopped Music Player Daemon. Jan 12 09:06:39 volumiopi volumio[11460]: info: Preparing to generate the ALSA configuration file Jan 12 09:06:39 volumiopi systemd[1]: Starting Music Player Daemon... Jan 12 09:06:39 volumiopi volumio[11460]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 12 09:06:39 volumiopi volumio[11460]: info: Reading ALSA contributions from plugins. Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 12 09:06:39 volumiopi volumio[11460]: info: MPD Permissions set Jan 12 09:06:39 volumiopi volumio[11460]: info: MPD Permissions set Jan 12 09:06:39 volumiopi volumio[11460]: info: Volumio called home Jan 12 09:06:39 volumiopi volumio[11460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi sudo[11668]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 09:06:39 volumiopi sudo[11668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:06:39 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:39 volumiopi sudo[11668]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:40 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:40 volumiopi volumio[11460]: info: Asound.conf file unchanged, so no further update is needed Jan 12 09:06:40 volumiopi volumio[11460]: info: Output device has changed, restarting MPD Jan 12 09:06:40 volumiopi sudo[11680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 12 09:06:40 volumiopi sudo[11680]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:40 volumiopi sudo[11680]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:40 volumiopi sudo[11682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 12 09:06:40 volumiopi sudo[11682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:40 volumiopi systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 12 09:06:40 volumiopi systemd[1]: mpd.service: Succeeded. Jan 12 09:06:40 volumiopi systemd[1]: Stopped Music Player Daemon. Jan 12 09:06:40 volumiopi volumio[11460]: info: MPD Permissions set Jan 12 09:06:40 volumiopi systemd[1]: Starting Music Player Daemon... Jan 12 09:06:40 volumiopi volumio[11460]: verbose: New Socket.io Connection to 192.168.2.112:3000 from 192.168.2.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jan 12 09:06:40 volumiopi volumio[11460]: verbose: New Socket.io Connection to 192.168.2.114:3000 from 192.168.2.100 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:40 volumiopi sudo[11685]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 12 09:06:40 volumiopi sudo[11685]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:40 volumiopi sudo[11685]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:06:40 volumiopi volumio[11460]: info: Discovery: Getting this device information Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:40 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 12 09:06:40 volumiopi volumio[11460]: info: Discovery: Getting this device information Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:06:40 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:40 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:06:41 volumiopi volumio[11460]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 12 09:06:41 volumiopi volumio[11460]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 12 09:06:41 volumiopi volumio[11460]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 12 09:06:41 volumiopi volumio[11460]: info: camilladsp service started and running in background, instance 1 Jan 12 09:06:41 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:06:41 volumiopi volumio[11460]: cannot open device 'volumioHw': Device or resource busy Jan 12 09:06:41 volumiopi volumio[11460]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 12 09:06:41 volumiopi volumio[11460]: cannot open device 'volumioHw': Device or resource busy Jan 12 09:06:41 volumiopi mpd[11691]: Jan 12 09:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 12 09:06:41 volumiopi volumio[11460]: info: FusionDsp loaded Jan 12 09:06:41 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 12 09:06:41 volumiopi sudo[11710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 12 09:06:41 volumiopi sudo[11710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:41 volumiopi sudo[11710]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:41 volumiopi volumio[11460]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 12 09:06:41 volumiopi volumio[11460]: info: Adding Signal Path Element [object Object] Jan 12 09:06:41 volumiopi volumio[11460]: info: Adding fusiondspeq DSP Signal Path Element Jan 12 09:06:42 volumiopi volumio[11460]: info: FusionDsp - ---- installed callbackRead Jan 12 09:06:42 volumiopi volumio[11460]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 09:06:42 volumiopi systemd[1]: Started Music Player Daemon. Jan 12 09:06:42 volumiopi volumio[11460]: 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 12 09:06:42 volumiopi sudo[11652]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:42 volumiopi volumio[11460]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 12 09:06:42 volumiopi sudo[11682]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:42 volumiopi sudo[11646]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:42 volumiopi volumio[11460]: info: Completed starting Core Plugins Jan 12 09:06:42 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:42 volumiopi volumio[11460]: info: ----- MyVolumio plugins startup ---- Jan 12 09:06:42 volumiopi volumio[11460]: info: ------------------------------------------- Jan 12 09:06:42 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 12 09:06:42 volumiopi volumio[11460]: error: MPD error: The expression evaluated to a falsy value: Jan 12 09:06:42 volumiopi volumio[11460]: assert.ok(self.idling) Jan 12 09:06:42 volumiopi volumio[11460]: error: The expression evaluated to a falsy value: Jan 12 09:06:42 volumiopi volumio[11460]: assert.ok(self.idling) Jan 12 09:06:42 volumiopi volumio[11460]: error: MPD error: The expression evaluated to a falsy value: Jan 12 09:06:42 volumiopi volumio[11460]: assert.ok(self.idling) Jan 12 09:06:42 volumiopi volumio[11460]: error: The expression evaluated to a falsy value: Jan 12 09:06:42 volumiopi volumio[11460]: assert.ok(self.idling) Jan 12 09:06:42 volumiopi volumio[11460]: info: MPD running with PID11691 Jan 12 09:06:42 volumiopi volumio[11460]: ,establishing connection Jan 12 09:06:42 volumiopi volumio[11460]: error: updateQueue error: null Jan 12 09:06:42 volumiopi volumio[11460]: error: updateQueue error: null Jan 12 09:06:45 volumiopi sudo[11721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:06:45 volumiopi sudo[11721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:45 volumiopi sudo[11724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:06:45 volumiopi sudo[11724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:45 volumiopi systemd[1]: Stopping MPD Monitor Service... Jan 12 09:06:45 volumiopi systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 09:06:45 volumiopi systemd[1]: mpd_monitor.service: Succeeded. Jan 12 09:06:45 volumiopi systemd[1]: Stopped MPD Monitor Service. Jan 12 09:06:45 volumiopi sudo[11726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Jan 12 09:06:45 volumiopi sudo[11726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:06:45 volumiopi systemd[1]: Started MPD Monitor Service. Jan 12 09:06:45 volumiopi sudo[11721]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:45 volumiopi mpd_monitor.sh[11729]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 09:06:45 volumiopi volumio[11460]: info: Successfully started MPD Monitor Jan 12 09:06:45 volumiopi systemd[1]: Stopping MPD Monitor Service... Jan 12 09:06:45 volumiopi systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Jan 12 09:06:45 volumiopi systemd[1]: mpd_monitor.service: Succeeded. Jan 12 09:06:45 volumiopi systemd[1]: Stopped MPD Monitor Service. Jan 12 09:06:45 volumiopi systemd[1]: Started MPD Monitor Service. Jan 12 09:06:45 volumiopi sudo[11724]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:45 volumiopi sudo[11726]: pam_unix(sudo:session): session closed for user root Jan 12 09:06:45 volumiopi volumio[11460]: info: Successfully started MPD Monitor Jan 12 09:06:45 volumiopi mpd_monitor.sh[11733]: MPD Monitor Service: Starting MPD Monitor Service Jan 12 09:06:45 volumiopi volumio[11460]: info: Successfully started MPD Monitor Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand update Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: database Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: database Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: database Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: Jan 12 09:06:47 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:47 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:47 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:49 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:49 volumiopi volumio[11460]: info: Jan 12 09:06:49 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:49 volumiopi volumio[11460]: info: MPD Database updated - AlbumList cache refreshed Jan 12 09:06:49 volumiopi volumio[11460]: info: Jan 12 09:06:49 volumiopi volumio[11460]: ---------------------------- MPD announces state update: database Jan 12 09:06:49 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:49 volumiopi volumio[11460]: info: Jan 12 09:06:49 volumiopi volumio[11460]: ---------------------------- MPD announces state update: update Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand update took 1678 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:49 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1628 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:49 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:49 volumiopi volumio[11460]: info: ControllerMpd::getState Jan 12 09:06:49 volumiopi volumio[11460]: verbose: ControllerMpd::sendMpdCommand status Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1627 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1627 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1624 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1623 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1621 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1315 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1313 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1313 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1311 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1310 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1310 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1308 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1307 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: info: sendMpdCommand status took 1307 milliseconds Jan 12 09:06:49 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:57 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:57 volumiopi volumio[11460]: info: No code Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:57 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:57 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10776ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10773ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10770ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10463ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10464ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10461ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10461ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10458ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 10459ms Jan 12 09:06:58 volumiopi volumio[11460]: info: sendMpdCommand status took 9218 milliseconds Jan 12 09:06:58 volumiopi volumio[11460]: info: sendMpdCommand status took 9215 milliseconds Jan 12 09:06:58 volumiopi volumio[11460]: info: sendMpdCommand status took 9206 milliseconds Jan 12 09:06:58 volumiopi volumio[11460]: info: sendMpdCommand status took 9204 milliseconds Jan 12 09:06:58 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:58 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:58 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:58 volumiopi volumio[11460]: info: Command Router : Notfying DB Updatefalse Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:06:58 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:58 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:58 volumiopi volumio[11460]: verbose: ControllerMpd::parseState Jan 12 09:06:58 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:58 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: No code Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:58 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: No code Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: ControllerMpd::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::servicePushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 12 09:06:58 volumiopi volumio[11460]: verbose: CURRENT POSITION 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::syncState stateService stop Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::syncState currentStatus stop Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: No code Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:06:58 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:06:58 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 9498ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 9497ms Jan 12 09:06:58 volumiopi volumio[11460]: info: ------------------------------ 9498ms Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin bluetooth to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin multiroom to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin metavolumio to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin cd_controller to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 12 09:06:59 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 12 09:07:00 volumiopi volumio[11460]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 12 09:07:00 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 12 09:07:00 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:00 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:00 volumiopi volumio[11460]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 09:07:00 volumiopi volumio[11460]: info: MyVolumio login type: Token Jan 12 09:07:00 volumiopi volumio[11460]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 12 09:07:00 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 12 09:07:01 volumiopi volumio[11460]: info: Starting Streaming Service Transparent Proxy Jan 12 09:07:01 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 12 09:07:01 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 12 09:07:01 volumiopi volumio[11460]: info: Streaming services startup Jan 12 09:07:01 volumiopi volumio[11460]: info: Starting Streaming Daemon Jan 12 09:07:01 volumiopi sudo[11790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 09:07:01 volumiopi sudo[11790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:07:01 volumiopi volumio[11460]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 12 09:07:01 volumiopi sudo[11790]: pam_unix(sudo:session): session closed for user root Jan 12 09:07:02 volumiopi volumio[11460]: error: Cannot start Volumio Streaming Daemon Jan 12 09:07:02 volumiopi volumio[11460]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 09:07:02 volumiopi volumio[11460]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 09:07:02 volumiopi volumio[11460]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 12 09:07:03 volumiopi volumio[11460]: STREAMING PROXY: Starting server on port 3245 Jan 12 09:07:03 volumiopi volumio[11460]: Node JS runtime: 14 Jan 12 09:07:06 volumiopi wpa_supplicant[960]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="VM55ABC45_2.4" Jan 12 09:07:06 volumiopi wpa_supplicant[960]: wlan0: Failed to initiate sched scan Jan 12 09:07:08 volumiopi volumio[11460]: info: MyVolumio token set successfully Jan 12 09:07:08 volumiopi volumio[11460]: info: MYVOLUMIO: Adding device Jan 12 09:07:08 volumiopi volumio[11460]: info: MYVOLUMIO: Evaluating Server Jan 12 09:07:09 volumiopi volumio[11460]: info: MyVolumio status changed Jan 12 09:07:09 volumiopi volumio[11460]: info: Streaming services startup Jan 12 09:07:09 volumiopi volumio[11460]: info: Starting Streaming Daemon Jan 12 09:07:09 volumiopi volumio[11460]: info: Removing browser output: myVolumio user plan is not superstar Jan 12 09:07:09 volumiopi volumio[11460]: info: Removing audio output: Jan 12 09:07:09 volumiopi volumio[11460]: info: Stoppping Tunnel 1 Jan 12 09:07:09 volumiopi sudo[11835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 09:07:09 volumiopi sudo[11835]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:07:09 volumiopi sudo[11838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 12 09:07:09 volumiopi sudo[11838]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 09:07:09 volumiopi sudo[11835]: pam_unix(sudo:session): session closed for user root Jan 12 09:07:09 volumiopi volumio[11460]: error: Cannot start Volumio Streaming Daemon Jan 12 09:07:09 volumiopi volumio[11460]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 12 09:07:09 volumiopi volumio[11460]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 12 09:07:09 volumiopi sudo[11838]: pam_unix(sudo:session): session closed for user root Jan 12 09:07:09 volumiopi volumio[11460]: info: Remote SSH Stopped Jan 12 09:07:09 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:07:09 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:09 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 12 09:07:09 volumiopi volumio-remote-updater[649]: No test mode Jan 12 09:07:09 volumiopi volumio-remote-updater[649]: No alpha test mode Jan 12 09:07:10 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 12 09:07:10 volumiopi volumio[11460]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 12 09:07:10 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 12 09:07:12 volumiopi volumio[11460]: info: Setting Geolocation for MyVolumio to eu3 Jan 12 09:07:12 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:12 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:12 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:12 volumiopi wpa_supplicant[960]: wlan0: Failed to initiate sched scan Jan 12 09:07:12 volumiopi volumio[11460]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 12 09:07:13 volumiopi volumio[11460]: info: Updating MyVolumio device info Jan 12 09:07:13 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:14 volumiopi volumio[11460]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 12 09:07:15 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 12 09:07:15 volumiopi volumio[11460]: 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 12 09:07:15 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetState Jan 12 09:07:15 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:07:16 volumiopi volumio[11460]: info: MYVOLUMIO: Adding device Jan 12 09:07:16 volumiopi volumio[11460]: info: MYVOLUMIO: Evaluating Server Jan 12 09:07:17 volumiopi volumio[11460]: info: Setting Geolocation for MyVolumio to eu3 Jan 12 09:07:17 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:17 volumiopi volumio[11460]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 12 09:07:17 volumiopi wpa_supplicant[960]: wlan0: Failed to initiate sched scan Jan 12 09:07:18 volumiopi volumio[11460]: info: Updating MyVolumio device info Jan 12 09:07:18 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:18 volumiopi volumio[11460]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::volumioGetBrowseSources Jan 12 09:07:20 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 09:07:21 volumiopi volumio[11460]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 12 09:07:21 volumiopi volumio[11460]: info: Completed starting MyVolumio Plugin Jan 12 09:07:21 volumiopi volumio[11460]: [Metrics] CommandRouter: 58s 162.57ms Jan 12 09:07:21 volumiopi volumio[11460]: info: CoreCommandRouter::volumiosetStartupVolume Jan 12 09:07:21 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 09:07:21 volumiopi volumio[11460]: info: VolumeController:: Setting startup Volume 15 Jan 12 09:07:21 volumiopi volumio[11460]: info: VolumeController::SetAlsaVolume15 Jan 12 09:07:21 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 09:07:21 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:07:22 volumiopi volumio[11460]: info: CoreCommandRouter::Close All Modals sent Jan 12 09:07:22 volumiopi volumio[11460]: info: CoreStateMachine::pushState Jan 12 09:07:22 volumiopi volumio[11460]: info: CorePlayQueue::getTrack 0 Jan 12 09:07:22 volumiopi volumio[11460]: info: CoreCommandRouter::volumioPushState Jan 12 09:07:22 volumiopi volumio[11460]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 12 09:07:22 volumiopi volumio[11460]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 12 09:07:22 volumiopi volumio[11460]: info: camilladsp stopping service pid 11700... Jan 12 09:07:22 volumiopi volumio[11460]: info: camilladsp service terminated, instance 1 Jan 12 09:07:22 volumiopi volumio[11460]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 12 09:07:22 volumiopi volumio[11460]: info: camilladsp service started and running in background, instance 1 Jan 12 09:07:23 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 09:07:23 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 12 09:07:23 volumiopi volumio[11460]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 09:07:23 volumiopi wpa_supplicant[960]: wlan0: Failed to initiate sched scan Jan 12 09:07:28 volumiopi wpa_supplicant[960]: wlan0: Failed to initiate sched scan Jan 12 09:07:28 volumiopi volumio[11460]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:07:28 volumiopi volumio[11460]: TypeError: Cannot read property 'slice' of null Jan 12 09:07:28 volumiopi volumio[11460]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 12 09:07:28 volumiopi volumio[11460]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 12 09:07:28 volumiopi volumio[11460]: at Object.onceWrapper (events.js:422:26) Jan 12 09:07:28 volumiopi volumio[11460]: at ClientRequest.emit (events.js:315:20) Jan 12 09:07:28 volumiopi volumio[11460]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Jan 12 09:07:28 volumiopi volumio[11460]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Jan 12 09:07:28 volumiopi volumio[11460]: at TLSSocket.socketOnData (_http_client.js:509:22) Jan 12 09:07:28 volumiopi volumio[11460]: at TLSSocket.emit (events.js:315:20) Jan 12 09:07:28 volumiopi volumio[11460]: at addChunk (internal/streams/readable.js:309:12) Jan 12 09:07:28 volumiopi volumio[11460]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 12 09:07:28 volumiopi volumio[11460]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 09:07:29 volumiopi sudo[11960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-12 09:06 Jan 12 09:07:29 volumiopi sudo[11960]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"