-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Tue 2025-06-10 11:10:16 CST. -- Jun 10 11:09:13 volumio systemd-timedated[1362]: Changed local time to Tue Jun 10 11:09:13 2025 Jun 10 11:09:13 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:13 volumio volumio-time-update[762]: volumio-time-update-util: System time updated successfully. Jun 10 11:09:13 volumio systemd[1]: Started Volumio Time Update Utility. Jun 10 11:09:13 volumio systemd[1]: Starting Daily apt download activities... Jun 10 11:09:13 volumio volumio-remote-updater[779]: [2025-06-10 11:09:13] [connect] Successful connection Jun 10 11:09:14 volumio volumio[1112]: info: Loading plugin "network"... Jun 10 11:09:14 volumio volumio[1112]: info: Refreshing Cached IP Addresses Jun 10 11:09:14 volumio volumio[1112]: info: Loading plugin "services"... Jun 10 11:09:14 volumio volumio[1112]: info: Loading plugin "alsa_controller"... Jun 10 11:09:14 volumio sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 11:09:14 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 11:09:14 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:14 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 11:09:14 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:14 volumio sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:14 volumio sudo[1391]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:14 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:14 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 11:09:14 volumio volumio[1112]: info: Loading plugin "wizard"... Jun 10 11:09:14 volumio volumio[1112]: info: Loading plugin "networkfs"... Jun 10 11:09:14 volumio volumio[1112]: info: Starting Udev Watcher for removable devices Jun 10 11:09:14 volumio volumio[1112]: info: Mounting Device 3762-0A9A Jun 10 11:09:14 volumio sudo[1425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/3762-0A9A -o noatime Jun 10 11:09:14 volumio sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:15 volumio systemd[1]: apt-daily.service: Succeeded. Jun 10 11:09:15 volumio systemd[1]: Started Daily apt download activities. Jun 10 11:09:15 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jun 10 11:09:15 volumio mount.exfat[1446]: volume was not unmounted cleanly Jun 10 11:09:16 volumio sudo[1425]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:16 volumio volumio[1112]: WARN: volume was not unmounted cleanly. Jun 10 11:09:16 volumio volumio[1112]: info: Ignoring mount for partition: boot Jun 10 11:09:16 volumio volumio[1112]: info: Ignoring mount for partition: volumio Jun 10 11:09:16 volumio volumio[1112]: info: Ignoring mount for partition: volumio_data Jun 10 11:09:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "volumio_command_line_client"... Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "upnp"... Jun 10 11:09:16 volumio volumio[1112]: info: [1749524956061] Starting Upmpd Daemon Jun 10 11:09:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "my_music"... Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "mpd"... Jun 10 11:09:16 volumio volumio[1112]: info: Plugin upnp_browser is not enabled Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "alarm-clock"... Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "airplay_emulation"... Jun 10 11:09:16 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "last_100"... Jun 10 11:09:16 volumio volumio[1112]: info: Loading plugin "webradio"... Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "i2s_dacs"... Jun 10 11:09:17 volumio volumio[1112]: info: I2S DAC not set, start Auto-detection Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "volumiodiscovery"... Jun 10 11:09:17 volumio volumio[1112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 11:09:17 volumio volumio[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 11:09:17 volumio volumio[1112]: *** WARNING *** For more information see Jun 10 11:09:17 volumio volumio[1112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 11:09:17 volumio node[1112]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 11:09:17 volumio volumio[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 11:09:17 volumio volumio[1112]: *** WARNING *** For more information see Jun 10 11:09:17 volumio node[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 11:09:17 volumio node[1112]: *** WARNING *** For more information see Jun 10 11:09:17 volumio node[1112]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 11:09:17 volumio node[1112]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 11:09:17 volumio node[1112]: *** WARNING *** For more information see Jun 10 11:09:17 volumio volumio[1112]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 11:09:17 volumio volumio[1112]: info: Discovery: Started advertising with name: Volumio Jun 10 11:09:17 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "peppy_screensaver"... Jun 10 11:09:17 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jun 10 11:09:17 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 10 11:09:17 volumio kernel: usb 1-3.3: USB disconnect, device number 3 Jun 10 11:09:17 volumio volumio[1112]: info: Applying required configuration parameters for plugin peppy_screensaver Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "outputs"... Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "albumart"... Jun 10 11:09:17 volumio volumio[1112]: info: Plugin example_plugin is not enabled Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "inputs"... Jun 10 11:09:17 volumio volumio[1112]: info: Loading plugin "updater_comm"... Jun 10 11:09:17 volumio volumio[1112]: Forking 3 albumart workers Jun 10 11:09:18 volumio volumio[1112]: info: Plugin mpdemulation is not enabled Jun 10 11:09:18 volumio volumio[1112]: info: Loading plugin "rest_api"... Jun 10 11:09:18 volumio volumio[1112]: info: Loading plugin "websocket"... Jun 10 11:09:18 volumio kernel: usb 1-3.3: new full-speed USB device number 5 using xhci_hcd Jun 10 11:09:18 volumio volumio[1112]: info: Starting Socket.io Server version 2.3.0 Jun 10 11:09:18 volumio volumio[1112]: info: Loading i18n strings for locale zh Jun 10 11:09:18 volumio volumio[1112]: Updating browse sources language Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 11:09:18 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:09:18 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:09:18 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:09:18 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:09:18 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::initPlayerControls Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: Express server listening on port 3000 Jun 10 11:09:18 volumio volumio[1112]: [Metrics] WebUI: 8s 937.99ms Jun 10 11:09:18 volumio volumio[1112]: info: Setting Device type: x86 Jun 10 11:09:18 volumio volumio[1112]: info: CoreStateMachine::resetVolumioState Jun 10 11:09:18 volumio volumio[1112]: info: CoreStateMachine::getcurrentVolume Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:18 volumio volumio[1112]: Starting albumart workers Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:18 volumio volumio[1112]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jun 10 11:09:18 volumio volumio[1112]: Starting albumart workers Jun 10 11:09:18 volumio volumio[1112]: info: Completed loading Core Plugins Jun 10 11:09:18 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jun 10 11:09:18 volumio volumio[1112]: info: Volumio Network Manager: Network status updated: 2 Jun 10 11:09:18 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 11:09:18 volumio volumio[1112]: Starting albumart workers Jun 10 11:09:18 volumio volumio[1112]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jun 10 11:09:18 volumio volumio[1112]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 10 11:09:18 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jun 10 11:09:18 volumio volumio-remote-updater[779]: [2025-06-10 11:09:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749524953 101 Jun 10 11:09:18 volumio volumio[1112]: 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: 1 Jun 10 11:09:18 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jun 10 11:09:18 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:18 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:18 volumio volumio[1112]: info: CoreStateMachine::updateTrackBlock Jun 10 11:09:18 volumio volumio[1112]: info: CorePlayQueue::getTrackBlock Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:18 volumio volumio[1112]: info: Jun 10 11:09:18 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:18 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:09:18 volumio volumio[1112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 10 11:09:18 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 11:09:19 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:09:19 volumio volumio[1112]: info: Reloading queue from file Jun 10 11:09:19 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jun 10 11:09:19 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:19 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:19 volumio volumio[1112]: info: CoreStateMachine::setRepeat false single undefined Jun 10 11:09:19 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:19 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:19 volumio volumio[1112]: info: CoreStateMachine::setRandom false Jun 10 11:09:19 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:19 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:19 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Jun 10 11:09:19 volumio volumio[1112]: info: Output device has changed, restarting MPD Jun 10 11:09:19 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:09:19 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio volumio[1112]: info: Output device has changed, restarting Shairport Sync Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:19 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:09:19 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:19 volumio volumio[1112]: info: ___________ START PLUGINS ___________ Jun 10 11:09:19 volumio volumio[1112]: info: ControllerMpd::onStart: Initializing MPD Jun 10 11:09:19 volumio volumio[1112]: info: Creating MPD Configuration file Jun 10 11:09:19 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 11:09:19 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:09:19 volumio sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio sudo[1668]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:09:19 volumio sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio systemd[1]: mpd.service: Succeeded. Jun 10 11:09:19 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 11:09:19 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio systemd[1]: Starting Music Player Daemon... Jun 10 11:09:19 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 11:09:19 volumio volumio[1112]: info: [1749524959418] CoreMusicLibrary::Adding element Last_100 Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 11:09:19 volumio volumio[1112]: info: [1749524959423] CoreMusicLibrary::Adding element Webradio Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 11:09:19 volumio volumio[1112]: info: Initializing BBC Radios Jun 10 11:09:19 volumio volumio[1112]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio sudo[1676]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 11:09:19 volumio sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio sudo[1676]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: Loading i18n strings for locale zh Jun 10 11:09:19 volumio volumio[1112]: info: /tmp/myfifo created Jun 10 11:09:19 volumio volumio[1112]: info: /tmp/myfifosa created Jun 10 11:09:19 volumio sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jun 10 11:09:19 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:19 volumio volumio[1112]: info: snd-dummy loaded Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:19 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:19 volumio sudo[1719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Jun 10 11:09:19 volumio sudo[1719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:19 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:19 volumio sudo[1719]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:19 volumio volumio[1112]: info: Volumio Calling Home Jun 10 11:09:19 volumio sudo[1398]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio volumio[1112]: info: MPD Permissions set Jun 10 11:09:20 volumio volumio[1112]: info: MPD Permissions set Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:20 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:20 volumio volumio[1112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: Jun 10 11:09:20 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:09:20 volumio sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jun 10 11:09:20 volumio sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:20 volumio sudo[1743]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:09:20 volumio volumio[1112]: info: Preparing to save Alsa Options, stopping services first Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:20 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::volumioPause Jun 10 11:09:20 volumio volumio[1112]: info: CoreStateMachine::pause Jun 10 11:09:20 volumio volumio[1112]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:20 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:20 volumio volumio[1112]: info: Setting mixer xCORE USB Audio 2.0 for card xCORE USB Audio 2.0 Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 11:09:20 volumio volumio[1112]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 11:09:20 volumio volumio[1112]: info: Disabling external Volume Control Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:20 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jun 10 11:09:20 volumio volumio[1112]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 10 11:09:20 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jun 10 11:09:20 volumio mpd[1688]: Jun 10 11:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jun 10 11:09:20 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:20 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:20 volumio sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:09:20 volumio sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:20 volumio sudo[1758]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:20 volumio sudo[1760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:20 volumio sudo[1760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:09:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:09:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:20 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:20 volumio sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:20 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:20 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:20 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:20 volumio sudo[1760]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:20 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:09:20 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 10 11:09:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:09:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:20 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:20 volumio systemd[1]: Started Music Player Daemon. Jun 10 11:09:20 volumio sudo[1670]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:09:20 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:20 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 10 11:09:20 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:09:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:20 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio volumio[1112]: Error adding Membership: Error: addMembership EINVAL Jun 10 11:09:20 volumio volumio[1112]: info: MPD Permissions set Jun 10 11:09:20 volumio volumio[1112]: info: VolumeController:: Volume=52 Mute =false Jun 10 11:09:20 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:20 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:20 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 11:09:20 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:09:21 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:21 volumio volumio[1112]: info: Completed starting Core Plugins Jun 10 11:09:21 volumio volumio[1112]: info: ------------------------------------------- Jun 10 11:09:21 volumio volumio[1112]: info: ----- MyVolumio plugins startup ---- Jun 10 11:09:21 volumio volumio[1112]: info: ------------------------------------------- Jun 10 11:09:21 volumio volumio[1112]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 10 11:09:21 volumio volumio[1112]: info: Volumio called home Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:21 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 10 11:09:21 volumio volumio[1112]: error: MPD error: The expression evaluated to a falsy value: Jun 10 11:09:21 volumio volumio[1112]: assert.ok(self.idling) Jun 10 11:09:21 volumio volumio[1112]: error: The expression evaluated to a falsy value: Jun 10 11:09:21 volumio volumio[1112]: assert.ok(self.idling) Jun 10 11:09:21 volumio volumio[1112]: info: MPD running with PID1688 Jun 10 11:09:21 volumio volumio[1112]: ,establishing connection Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 10 11:09:21 volumio volumio[1112]: error: updateQueue error: null Jun 10 11:09:21 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:09:21 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:21 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Jun 10 11:09:21 volumio volumio[1112]: info: Output device has changed, restarting MPD Jun 10 11:09:21 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 11:09:21 volumio systemd[1]: mpd.service: Succeeded. Jun 10 11:09:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 11:09:21 volumio volumio[1112]: info: Output device has changed, restarting Shairport Sync Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:21 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:09:21 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:21 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:21 volumio sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:09:21 volumio systemd[1]: Starting Music Player Daemon... Jun 10 11:09:21 volumio sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:21 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 10 11:09:21 volumio systemd[1]: mpd.service: Succeeded. Jun 10 11:09:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 11:09:21 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:21 volumio volumio[1112]: error: updateQueue error: null Jun 10 11:09:21 volumio systemd[1]: Starting Music Player Daemon... Jun 10 11:09:21 volumio volumio[1112]: info: MPD Permissions set Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:21 volumio sudo[1812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:21 volumio sudo[1812]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:21 volumio sudo[1804]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 11:09:21 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:21 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:09:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:09:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:21 volumio sudo[1812]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:21 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:21 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:21 volumio volumio[1112]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 10 11:09:22 volumio mpd[1815]: Jun 10 11:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 11:09:22 volumio kernel: usb 1-3.3: USB disconnect, device number 5 Jun 10 11:09:22 volumio volumio[1112]: info: Jun 10 11:09:22 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:09:22 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:09:22 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:22 volumio systemd[1]: Started Music Player Daemon. Jun 10 11:09:22 volumio sudo[1797]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:22 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:22 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:09:22 volumio volumio[1112]: error: MPD error: The expression evaluated to a falsy value: Jun 10 11:09:22 volumio volumio[1112]: assert.ok(self.idling) Jun 10 11:09:22 volumio volumio[1112]: error: The expression evaluated to a falsy value: Jun 10 11:09:22 volumio volumio[1112]: assert.ok(self.idling) Jun 10 11:09:22 volumio volumio[1112]: error: updateQueue error: null Jun 10 11:09:22 volumio kernel: usb 1-3.3: new full-speed USB device number 6 using xhci_hcd Jun 10 11:09:22 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 11:09:23 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:09:23 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:09:23 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:09:23 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:09:23 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:09:23 volumio volumio[1112]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 10 11:09:23 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 10 11:09:24 volumio volumio[1112]: info: CoreStateMachine::getcurrentVolume Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:24 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 11:09:24 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jun 10 11:09:24 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:24 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:24 volumio volumio[1112]: info: CoreStateMachine::updateTrackBlock Jun 10 11:09:24 volumio volumio[1112]: info: CorePlayQueue::getTrackBlock Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:24 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 11:09:24 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jun 10 11:09:24 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:24 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:24 volumio volumio[1112]: info: Jun 10 11:09:24 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:09:24 volumio volumio[1112]: info: Preparing to save Alsa Options, stopping services first Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:24 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::volumioPause Jun 10 11:09:24 volumio volumio[1112]: info: CoreStateMachine::pause Jun 10 11:09:24 volumio volumio[1112]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:24 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:24 volumio volumio[1112]: info: Setting mixer xCORE USB Audio 2.0 for card xCORE USB Audio 2.0 Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 11:09:25 volumio volumio[1112]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 11:09:25 volumio volumio[1112]: info: Disabling external Volume Control Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:25 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jun 10 11:09:25 volumio volumio[1112]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 10 11:09:25 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioGetVisibleSources Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:25 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 10 11:09:25 volumio volumio[1112]: info: Received Get System Info Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 11:09:25 volumio volumio[1112]: info: Discovery: Getting this device information Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:25 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:25 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:25 volumio volumio[1112]: info: Listing playlists Jun 10 11:09:25 volumio volumio[1112]: info: VolumeController:: Volume=52 Mute =false Jun 10 11:09:25 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:25 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:25 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Jun 10 11:09:25 volumio volumio[1112]: info: Output device has changed, restarting MPD Jun 10 11:09:25 volumio volumio[1112]: info: Output device has changed, restarting Shairport Sync Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:25 volumio sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:09:25 volumio sudo[2042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:25 volumio sudo[2042]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:25 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:09:25 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:25 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 11:09:25 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:25 volumio systemd[1]: mpd.service: Succeeded. Jun 10 11:09:25 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 11:09:25 volumio volumio[1112]: info: MPD Permissions set Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio systemd[1]: Starting Music Player Daemon... Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:25 volumio sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:25 volumio sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:09:25 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:09:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:25 volumio sudo[2050]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 11:09:25 volumio sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:25 volumio sudo[2050]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:25 volumio sudo[2058]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:25 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 11:09:25 volumio volumio[1112]: info: Received Get System Info Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 10 11:09:25 volumio volumio[1112]: info: Discovery: Getting this device information Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:25 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:25 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:26 volumio sudo[2066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 11:09:26 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:26 volumio sudo[2068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 11:09:26 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio volumio[1112]: error: MPD error: Error: This socket has been ended by the other party Jun 10 11:09:26 volumio volumio[1112]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 10 11:09:26 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:26 volumio sudo[2068]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:26 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 10 11:09:26 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:26 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 10 11:09:26 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:26 volumio volumio[1112]: info: Upmpdcli Daemon Started Jun 10 11:09:26 volumio mpd[2062]: Jun 10 11:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 11:09:26 volumio systemd[1]: Started Music Player Daemon. Jun 10 11:09:26 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:26 volumio volumio[1112]: error: updateQueue error: null Jun 10 11:09:26 volumio volumio[2076]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 10 11:09:27 volumio volumio[1112]: info: Discovery: adding a4a38f5c-3c06-4b6c-addf-1f4213f4058a Jun 10 11:09:27 volumio volumio[1112]: info: Discovery: Found device Volumio Jun 10 11:09:27 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:27 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:27 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 10 11:09:27 volumio kernel: usb 1-3.3: USB disconnect, device number 6 Jun 10 11:09:27 volumio volumio[1112]: info: Jun 10 11:09:27 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:09:27 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:09:27 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:27 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:09:27 volumio kernel: usb 1-3.3: new full-speed USB device number 7 using xhci_hcd Jun 10 11:09:28 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:09:28 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:09:28 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:09:28 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:09:28 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:09:28 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 10 11:09:29 volumio volumio[1112]: info: CoreStateMachine::getcurrentVolume Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:29 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 11:09:29 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jun 10 11:09:29 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:29 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:29 volumio volumio[1112]: info: CoreStateMachine::updateTrackBlock Jun 10 11:09:29 volumio volumio[1112]: info: CorePlayQueue::getTrackBlock Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:29 volumio volumio[1112]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 10 11:09:29 volumio volumio[1112]: info: VolumeController:: Volume=undefined Mute =false Jun 10 11:09:29 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:29 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:29 volumio volumio[1112]: info: Jun 10 11:09:29 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:09:29 volumio volumio[1112]: info: Preparing to save Alsa Options, stopping services first Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:29 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::volumioPause Jun 10 11:09:29 volumio volumio[1112]: info: CoreStateMachine::pause Jun 10 11:09:29 volumio volumio[1112]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:29 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:29 volumio volumio[1112]: info: Setting mixer xCORE USB Audio 2.0 for card xCORE USB Audio 2.0 Jun 10 11:09:30 volumio volumio[1112]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 11:09:30 volumio volumio[1112]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 11:09:30 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 11:09:30 volumio volumio[1112]: info: Disabling external Volume Control Jun 10 11:09:30 volumio volumio[2076]: ............................................................................................................................++++ Jun 10 11:09:30 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:30 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin bluetooth to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin multiroom to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin metavolumio to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin cd_controller to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 10 11:09:30 volumio volumio[1112]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 10 11:09:31 volumio volumio[1112]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 10 11:09:31 volumio volumio[1112]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 10 11:09:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:31 volumio volumio[1112]: info: Starting MyVolumio Remote Streaming Endpoints Jun 10 11:09:31 volumio volumio[1112]: info: MyVolumio not started Jun 10 11:09:31 volumio volumio[1112]: info: Initializing device activation check Jun 10 11:09:31 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:31 volumio volumio[1112]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 10 11:09:31 volumio volumio[1112]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 10 11:09:32 volumio volumio[1112]: info: Starting Streaming Service Transparent Proxy Jun 10 11:09:32 volumio volumio[1112]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 10 11:09:32 volumio volumio[1112]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 10 11:09:32 volumio volumio[1112]: info: Streaming services startup Jun 10 11:09:32 volumio volumio[1112]: info: Starting Streaming Daemon Jun 10 11:09:32 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 11:09:32 volumio sudo[2123]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:32 volumio sudo[2123]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:32 volumio volumio[1112]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 10 11:09:32 volumio volumio[1112]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 10 11:09:32 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jun 10 11:09:32 volumio volumio[1112]: error: Cannot start Volumio Streaming Daemon Jun 10 11:09:32 volumio volumio[1112]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 10 11:09:32 volumio volumio[1112]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:09:32 volumio volumio[1112]: info: VolumeController:: Volume=52 Mute =false Jun 10 11:09:32 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:32 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:32 volumio volumio[1112]: info: Asound.conf file unchanged, so no further update is needed Jun 10 11:09:32 volumio volumio[1112]: info: Output device has changed, restarting MPD Jun 10 11:09:32 volumio sudo[2136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 11:09:32 volumio volumio[1112]: info: Output device has changed, restarting Shairport Sync Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:32 volumio sudo[2136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:32 volumio sudo[2136]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:32 volumio sudo[2138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 11:09:32 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:32 volumio volumio[1112]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 11:09:32 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 11:09:32 volumio volumio[1112]: info: MPD Permissions set Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio systemd[1]: mpd.service: Succeeded. Jun 10 11:09:32 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 11:09:32 volumio systemd[1]: Starting Music Player Daemon... Jun 10 11:09:32 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:32 volumio volumio[1112]: info: Starting Shairport Sync Jun 10 11:09:32 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 11:09:32 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 11:09:33 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 10 11:09:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:33 volumio sudo[2145]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 11:09:33 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:33 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 11:09:33 volumio sudo[2152]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:33 volumio volumio[1112]: info: Shairport-Sync Started Jun 10 11:09:33 volumio volumio[1112]: STREAMING PROXY: Starting server on port 3245 Jun 10 11:09:33 volumio volumio[1112]: Node JS runtime: 14 Jun 10 11:09:33 volumio volumio[2076]: ........................................................................................................................++++ Jun 10 11:09:33 volumio volumio[2076]: e is 65537 (0x010001) Jun 10 11:09:33 volumio volumio[2076]: writing RSA key Jun 10 11:09:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:33 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 10 11:09:33 volumio mpd[2156]: Jun 10 11:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 11:09:34 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 10 11:09:34 volumio volumio[1112]: info: CoreStateMachine::getcurrentVolume Jun 10 11:09:34 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:34 volumio systemd[1]: Started Music Player Daemon. Jun 10 11:09:34 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Jun 10 11:09:34 volumio volumio[1112]: info: VolumeController:: Volume=52 Mute =false Jun 10 11:09:34 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:34 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:34 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:34 volumio volumio[1112]: info: CoreStateMachine::updateTrackBlock Jun 10 11:09:34 volumio volumio[1112]: info: CorePlayQueue::getTrackBlock Jun 10 11:09:34 volumio volumio[1112]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 11:09:34 volumio volumio[1112]: error: updateQueue error: null Jun 10 11:09:34 volumio volumio[1112]: info: VolumeController:: Volume=52 Mute =false Jun 10 11:09:34 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:34 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:34 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:41 volumio volumio[1112]: info: Retrieving Cloud Streaming UI Jun 10 11:09:41 volumio volumio[1112]: info: Getting Tidal Cloud Configuration Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:41 volumio volumio[1112]: info: Getting Qobuz Cloud Configuration Jun 10 11:09:41 volumio volumio[1112]: info: Asking plugin for UI Config Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:41 volumio volumio[1112]: info: Getting Spotify Cloud Configuration Jun 10 11:09:41 volumio volumio[1112]: info: Asking plugin for UI Config Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:41 volumio volumio[1112]: info: Saving Spotify Acccount Jun 10 11:09:41 volumio volumio[1112]: info: Got Tidal Cloud Configuration Jun 10 11:09:41 volumio volumio[1112]: info: Got it Jun 10 11:09:41 volumio volumio[1112]: info: Got it Jun 10 11:09:41 volumio volumio[1112]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:09:41 volumio volumio[1112]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:09:41 volumio volumio[1112]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:09:42 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 10 11:09:42 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 10 11:09:43 volumio systemd[1]: systemd-timedated.service: Succeeded. Jun 10 11:09:46 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 11:09:46 volumio volumio[1112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 10 11:09:46 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:09:46 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:49 volumio volumio[1112]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 10 11:09:49 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:49 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 11:09:49 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:49 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 10 11:09:50 volumio systemd[1]: Started My Shell Script. Jun 10 11:09:50 volumio systemd[1]: Reached target Multi-User System. Jun 10 11:09:50 volumio systemd[1]: Reached target Graphical Interface. Jun 10 11:09:50 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 10 11:09:50 volumio sudo[2215]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/bash /home/volumio/scripts/peppy.sh Jun 10 11:09:50 volumio sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:09:50 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 10 11:09:50 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 10 11:09:50 volumio systemd[1]: Startup finished in 8.015s (firmware) + 3.092s (loader) + 10.381s (kernel) + 1min 3.717s (userspace) = 1min 25.206s. Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 11:09:51 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::volumioPlay Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::play index undefined Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 10 11:09:52 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::startPlaybackTimer Jun 10 11:09:52 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::clearAddPlayTracks USB/3762-0A9A/陈曦《光阴的故事 HQII（限量版）》「正版CD低速原抓WAV+CUE」/陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand stop Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand stop took 3 milliseconds Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand clear Jun 10 11:09:52 volumio volumio[1112]: info: Jun 10 11:09:52 volumio volumio[1112]: ---------------------------- MPD announces system playlist update Jun 10 11:09:52 volumio volumio[1112]: info: Ignoring MPD Status Update Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand clear took 5 milliseconds Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand add "USB/3762-0A9A/陈曦《光阴的故事 HQII（限量版）》「正版CD低速原抓WAV+CUE」/陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav" Jun 10 11:09:52 volumio volumio[1112]: error: updateQueue error: null Jun 10 11:09:52 volumio volumio[1112]: info: ------------------------------ 8ms Jun 10 11:09:52 volumio volumio[1112]: info: Jun 10 11:09:52 volumio volumio[1112]: ---------------------------- MPD announces system playlist update Jun 10 11:09:52 volumio volumio[1112]: info: Ignoring MPD Status Update Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand add "USB/3762-0A9A/陈曦《光阴的故事 HQII（限量版）》「正版CD低速原抓WAV+CUE」/陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav" took 6 milliseconds Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand play Jun 10 11:09:52 volumio volumio[1112]: info: ------------------------------ 7ms Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand play took 5 milliseconds Jun 10 11:09:52 volumio volumio[1112]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 10 11:09:52 volumio volumio[1112]: info: Completed starting MyVolumio Plugin Jun 10 11:09:52 volumio volumio[1112]: [Metrics] CommandRouter: 42s 176.23ms Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::volumiosetStartupVolume Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:09:52 volumio volumio[1112]: info: Jun 10 11:09:52 volumio volumio[1112]: ---------------------------- MPD announces state update: player Jun 10 11:09:52 volumio volumio[1112]: info: ControllerMpd::getState Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand status took 59 milliseconds Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::parseState Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:09:52 volumio volumio[1112]: info: Jun 10 11:09:52 volumio volumio[1112]: ---------------------------- MPD announces state update: player Jun 10 11:09:52 volumio volumio[1112]: info: ControllerMpd::getState Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand status took 3 milliseconds Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::parseState Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:09:52 volumio volumio[1112]: info: ControllerMpd::pushState Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::servicePushState Jun 10 11:09:52 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:52 volumio volumio[1112]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3119,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav","artist":null,"album":null,"uri":"USB/3762-0A9A/陈曦《光阴的故事 HQII（限量版）》「正版CD低速原抓WAV+CUE」/陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav","trackType":"wav"} Jun 10 11:09:52 volumio volumio[1112]: verbose: CURRENT POSITION 0 Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::syncState stateService play Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::syncState currentStatus stop Jun 10 11:09:52 volumio volumio[1112]: info: ------------------------------ 85ms Jun 10 11:09:52 volumio volumio[1112]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 10 11:09:52 volumio volumio[1112]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:09:52 volumio volumio[1112]: info: ControllerMpd::pushState Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::servicePushState Jun 10 11:09:52 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:52 volumio volumio[1112]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":3119,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav","artist":null,"album":null,"uri":"USB/3762-0A9A/陈曦《光阴的故事 HQII（限量版）》「正版CD低速原抓WAV+CUE」/陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav","trackType":"wav"} Jun 10 11:09:52 volumio volumio[1112]: verbose: CURRENT POSITION 0 Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::syncState stateService play Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::syncState currentStatus play Jun 10 11:09:52 volumio volumio[1112]: info: Received an update from plugin. extracting info from payload Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:52 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:52 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:09:52 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:09:52 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:09:52 volumio volumio[1112]: info: ------------------------------ 55ms Jun 10 11:09:53 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 10 11:09:53 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:09:53 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 10 11:09:57 volumio ntpd[979]: Soliciting pool server 193.182.111.12 Jun 10 11:09:58 volumio ntpd[979]: Soliciting pool server 95.111.202.5 Jun 10 11:09:59 volumio ntpd[979]: Soliciting pool server 81.168.83.93 Jun 10 11:09:59 volumio ntpd[979]: Soliciting pool server 193.182.111.141 Jun 10 11:09:59 volumio volumio[1112]: info: BOOT COMPLETED Jun 10 11:10:00 volumio ntpd[979]: Soliciting pool server 202.118.1.81 Jun 10 11:10:00 volumio ntpd[979]: Soliciting pool server 111.230.189.174 Jun 10 11:10:00 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:10:00 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:10:00 volumio sudo[2293]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Jun 10 11:10:00 volumio sudo[2293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 11:10:01 volumio ntpd[979]: Soliciting pool server 202.118.1.130 Jun 10 11:10:01 volumio ntpd[979]: Soliciting pool server 211.68.71.26 Jun 10 11:10:02 volumio ntpd[979]: Soliciting pool server 162.159.200.1 Jun 10 11:10:02 volumio ntpd[979]: Soliciting pool server 162.159.200.123 Jun 10 11:10:03 volumio ntpd[979]: Soliciting pool server 240e:982:2000:c::371:3cc Jun 10 11:10:03 volumio ntpd[979]: Soliciting pool server 193.182.111.143 Jun 10 11:10:04 volumio ntpd[979]: Soliciting pool server 117.80.112.205 Jun 10 11:10:05 volumio ntpd[979]: Soliciting pool server 5.79.108.34 Jun 10 11:10:05 volumio volumio[1112]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 10 11:10:05 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:10:05 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:10:05 volumio ntpd[979]: receive: Unexpected origin timestamp 0xebf2208d.08cc1af2 does not match aorg 0000000000.00000000 from server@193.182.111.141 xmt 0xebf2208d.b8f814a4 Jun 10 11:10:10 volumio kernel: usb 1-3.3: USB disconnect, device number 7 Jun 10 11:10:10 volumio volumio[1112]: info: Jun 10 11:10:10 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:10:10 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:10:10 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:10 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:10:10 volumio kernel: usb 1-3.3: new full-speed USB device number 8 using xhci_hcd Jun 10 11:10:10 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:10:10 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:10:10 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:10:10 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:10:10 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:10:10 volumio kernel: usb 1-3.3: 1:1: cannot set freq 96000 (v2/v3): err -71 Jun 10 11:10:10 volumio kernel: usb 1-3.3: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 10 11:10:10 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:10:10 volumio kernel: usb 1-3.3: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 10 11:10:10 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 1 (-71) Jun 10 11:10:11 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 2 (-71) Jun 10 11:10:11 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 0 (-71) Jun 10 11:10:11 volumio kernel: usb 1-3.3: 10:0: cannot get min/max values for control 2 (id 10) Jun 10 11:10:11 volumio kernel: usb 1-3.3: 10:0: cannot get min/max values for control 2 (id 10) Jun 10 11:10:11 volumio kernel: usb 1-3.3: USB disconnect, device number 8 Jun 10 11:10:11 volumio systemd-udevd[2339]: controlC5: Failed to open ATTR{/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.3/1-3.3:1.0/sound/card5/controlC5/../uevent} for writing: No such file or directory Jun 10 11:10:11 volumio volumio[1112]: info: Jun 10 11:10:11 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:10:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:10:11 volumio volumio[1112]: error: API:pushError: TypeError: Cannot read property 'name' of null Jun 10 11:10:11 volumio volumio[1112]: info: Jun 10 11:10:11 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:10:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:10:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:11 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:10:11 volumio kernel: usb 1-3.3: new full-speed USB device number 9 using xhci_hcd Jun 10 11:10:11 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:10:11 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:10:11 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:10:11 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:10:11 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:10:12 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:10:12 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 1 (-71) Jun 10 11:10:12 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 2 (-71) Jun 10 11:10:12 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 0 (-71) Jun 10 11:10:12 volumio kernel: usb 1-3.3: 10:0: cannot get min/max values for control 2 (id 10) Jun 10 11:10:12 volumio kernel: usb 1-3.3: 10:0: cannot get min/max values for control 2 (id 10) Jun 10 11:10:12 volumio kernel: usb 1-3.3: USB disconnect, device number 9 Jun 10 11:10:12 volumio systemd-udevd[2338]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 19. Jun 10 11:10:12 volumio volumio[1112]: info: Jun 10 11:10:12 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:10:12 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:10:12 volumio volumio[1112]: error: API:pushError: TypeError: Cannot read property 'name' of null Jun 10 11:10:13 volumio volumio[1112]: info: Jun 10 11:10:13 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:10:13 volumio kernel: usb 1-3.3: new full-speed USB device number 10 using xhci_hcd Jun 10 11:10:13 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:10:13 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:10:13 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:10:13 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:10:13 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:10:13 volumio kernel: usb 1-3.3: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 10 11:10:13 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:10:13 volumio kernel: usb 1-3.3: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Jun 10 11:10:13 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 1 (-71) Jun 10 11:10:13 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 2 (-71) Jun 10 11:10:13 volumio kernel: usb 1-3.3: 10:0: failed to get current value for ch 0 (-71) Jun 10 11:10:13 volumio kernel: usb 1-3.3: 10:0: cannot get min/max values for control 2 (id 10) Jun 10 11:10:13 volumio kernel: usb 1-3.3: 10:0: cannot get min/max values for control 2 (id 10) Jun 10 11:10:13 volumio kernel: usb 1-3.3: USB disconnect, device number 10 Jun 10 11:10:13 volumio volumio[1112]: info: Jun 10 11:10:13 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:10:13 volumio volumio[1112]: error: API:pushError: TypeError: Cannot read property 'name' of null Jun 10 11:10:13 volumio volumio[1112]: info: Jun 10 11:10:13 volumio volumio[1112]: ---------------------------- USB Audio Device Detached Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:13 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 10 11:10:14 volumio kernel: usb 1-3.3: new full-speed USB device number 11 using xhci_hcd Jun 10 11:10:14 volumio kernel: usb 1-3.3: not running at top speed; connect to a high speed hub Jun 10 11:10:14 volumio kernel: usb 1-3.3: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Jun 10 11:10:14 volumio kernel: usb 1-3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 10 11:10:14 volumio kernel: usb 1-3.3: Product: xCORE USB Audio 2.0 Jun 10 11:10:14 volumio kernel: usb 1-3.3: Manufacturer: XMOS Jun 10 11:10:14 volumio kernel: usb 1-3.3: 1:3 : unsupported format bits 0x100000000 Jun 10 11:10:15 volumio volumio[1112]: info: Jun 10 11:10:15 volumio volumio[1112]: ---------------------------- USB Audio Device Attached Jun 10 11:10:15 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::Close All Modals sent Jun 10 11:10:16 volumio volumio[1112]: info: Preparing to save Alsa Options, stopping services first Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::volumioGetState Jun 10 11:10:16 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::volumioPause Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::pause Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::stPlaybackTimer Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::servicePause Jun 10 11:10:16 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::servicePause Jun 10 11:10:16 volumio volumio[1112]: info: ControllerMpd::pause Jun 10 11:10:16 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand pause Jun 10 11:10:16 volumio volumio[1112]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:16 volumio volumio[1112]: info: Setting mixer xCORE USB Audio 2.0 for card xCORE USB Audio 2.0 Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 10 11:10:16 volumio volumio[1112]: info: Updating Volume Controller Parameters: Device: 5 Name: xCORE USB Audio 2.0 Mixer: xCORE USB Audio 2.0 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 10 11:10:16 volumio volumio[1112]: info: Disabling external Volume Control Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 11:10:16 volumio volumio[1112]: info: Preparing to generate the ALSA configuration file Jun 10 11:10:16 volumio volumio[1112]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 10 11:10:16 volumio volumio[1112]: info: Reading ALSA contributions from plugins. Jun 10 11:10:16 volumio volumio[1112]: info: Jun 10 11:10:16 volumio volumio[1112]: ---------------------------- MPD announces state update: player Jun 10 11:10:16 volumio volumio[1112]: info: sendMpdCommand pause took 223 milliseconds Jun 10 11:10:16 volumio volumio[1112]: info: ControllerMpd::getState Jun 10 11:10:16 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand status Jun 10 11:10:16 volumio volumio[1112]: info: sendMpdCommand status took 20 milliseconds Jun 10 11:10:16 volumio volumio[1112]: verbose: ControllerMpd::parseState Jun 10 11:10:16 volumio volumio[1112]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 10 11:10:16 volumio volumio[1112]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 10 11:10:16 volumio volumio[1112]: verbose: ControllerMpd::parseTrackInfo Jun 10 11:10:16 volumio volumio[1112]: info: ControllerMpd::pushState Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::servicePushState Jun 10 11:10:16 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:10:16 volumio volumio[1112]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":23654,"duration":3119,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav","artist":null,"album":null,"uri":"USB/3762-0A9A/陈曦《光阴的故事 HQII（限量版）》「正版CD低速原抓WAV+CUE」/陈曦 - 光阴的故事 [HQCDⅡ] 限量版.wav","trackType":"wav"} Jun 10 11:10:16 volumio volumio[1112]: verbose: CURRENT POSITION 0 Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::syncState stateService pause Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::syncState currentStatus pause Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::pushState Jun 10 11:10:16 volumio volumio[1112]: info: CorePlayQueue::getTrack 0 Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::volumioPushState Jun 10 11:10:16 volumio volumio[1112]: info: CoreStateMachine::stPlaybackTimer Jun 10 11:10:16 volumio volumio[1112]: info: ------------------------------ 50ms Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 10 11:10:16 volumio volumio[1112]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 10 11:10:16 volumio volumio[1112]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 11:10:16 volumio volumio[1112]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jun 10 11:10:16 volumio volumio[1112]: at Object.readdirSync (fs.js:1048:3) Jun 10 11:10:16 volumio volumio[1112]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jun 10 11:10:16 volumio volumio[1112]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jun 10 11:10:16 volumio volumio[1112]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jun 10 11:10:16 volumio volumio[1112]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jun 10 11:10:16 volumio volumio[1112]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jun 10 11:10:16 volumio volumio[1112]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jun 10 11:10:16 volumio volumio[1112]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jun 10 11:10:16 volumio volumio[1112]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jun 10 11:10:16 volumio volumio[1112]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) { Jun 10 11:10:16 volumio volumio[1112]: errno: -20, Jun 10 11:10:16 volumio volumio[1112]: syscall: 'scandir', Jun 10 11:10:16 volumio volumio[1112]: code: 'ENOTDIR', Jun 10 11:10:16 volumio volumio[1112]: path: '/tmp/peppyrunning' Jun 10 11:10:16 volumio volumio[1112]: } Jun 10 11:10:16 volumio volumio[1112]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 11:10:16 volumio sudo[2426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 11:09 Jun 10 11:10:16 volumio sudo[2426]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"