-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Mon 2024-09-02 13:41:00 CEST. -- Sep 02 13:39:46 volumiopr ntpd[1038]: receive: Unexpected origin timestamp 0xea77031f.c200f22b does not match aorg 0000000000.00000000 from server@178.63.52.31 xmt 0xea802282.df9c6608 Sep 02 13:39:46 volumiopr ntpd[1038]: receive: Unexpected origin timestamp 0xea77031f.c1fd6933 does not match aorg 0000000000.00000000 from server@51.75.67.47 xmt 0xea802282.e04f6448 Sep 02 13:39:46 volumiopr ntpd[1038]: receive: Unexpected origin timestamp 0xea77031f.c2041dea does not match aorg 0000000000.00000000 from server@212.18.3.18 xmt 0xea802282.dfc33dfa Sep 02 13:39:46 volumiopr systemd[1]: Starting Daily apt download activities... Sep 02 13:39:46 volumiopr wireless.js[654]: trying... Sep 02 13:39:47 volumiopr sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 02 13:39:47 volumiopr sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:47 volumiopr sudo[1132]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:47 volumiopr dhcpcd[1098]: wlan0: using IPv4LL address 169.254.97.40 Sep 02 13:39:47 volumiopr avahi-daemon[690]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.97.40. Sep 02 13:39:47 volumiopr avahi-daemon[690]: New relevant interface wlan0.IPv4 for mDNS. Sep 02 13:39:47 volumiopr dhcpcd[1098]: wlan0: adding route to 169.254.0.0/16 Sep 02 13:39:47 volumiopr avahi-daemon[690]: Registering new address record for 169.254.97.40 on wlan0.IPv4. Sep 02 13:39:47 volumiopr wireless.js[654]: trying... Sep 02 13:39:48 volumiopr sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 02 13:39:48 volumiopr sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:48 volumiopr sudo[1192]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:48 volumiopr wireless.js[654]: Connected to: ----Vodafone Homespot Sep 02 13:39:48 volumiopr wireless.js[654]: ---- Sep 02 13:39:48 volumiopr sudo[1197]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:39:48 volumiopr sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:48 volumiopr sudo[1197]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:48 volumiopr wireless.js[654]: ... joined AP, wlan0 IPv4 is 169.254.97.40, ipV6 is undefined Sep 02 13:39:48 volumiopr wireless.js[654]: It's done! AP Sep 02 13:39:48 volumiopr systemd[1]: Started Wireless Services. Sep 02 13:39:48 volumiopr kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 02 13:39:48 volumiopr systemd[1]: Started Volumio Backend Module. Sep 02 13:39:48 volumiopr systemd[1]: Started Volumio Cpu Tweaker. Sep 02 13:39:48 volumiopr systemd[1]: Reached target Multi-User System. Sep 02 13:39:48 volumiopr systemd[1]: Reached target Graphical Interface. Sep 02 13:39:48 volumiopr systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 02 13:39:48 volumiopr systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 02 13:39:48 volumiopr systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: Setting RT Priority for mpd Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: pid 35's current scheduling policy: SCHED_OTHER Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: pid 35's current scheduling priority: 0 Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: Setting MPD Affinity Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: pid 3's current affinity mask: f Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 02 13:39:48 volumiopr volumio-cpu-tweak[1214]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 02 13:39:48 volumiopr systemd[1]: apt-daily.service: Succeeded. Sep 02 13:39:48 volumiopr systemd[1]: Started Daily apt download activities. Sep 02 13:39:48 volumiopr systemd[1]: Starting Daily apt upgrade and clean activities... Sep 02 13:39:48 volumiopr systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 02 13:39:48 volumiopr ntpd[1038]: Listen normally on 4 wlan0 169.254.97.40:123 Sep 02 13:39:48 volumiopr ntpd[1038]: new interface(s) found: waking up resolver Sep 02 13:39:49 volumiopr systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 02 13:39:49 volumiopr systemd[1]: Started Daily apt upgrade and clean activities. Sep 02 13:39:49 volumiopr systemd[1]: Startup finished in 17.076s (kernel) + 30.381s (userspace) = 47.457s. Sep 02 13:39:51 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:39:51 volumiopr volumio[1212]: info: ----- Volumio3 ---- Sep 02 13:39:51 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:39:51 volumiopr volumio[1212]: info: ----- System startup ---- Sep 02 13:39:51 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:39:51 volumiopr volumio-remote-updater[683]: [2024-09-02 13:39:51] [connect] Successful connection Sep 02 13:39:52 volumiopr volumio[1212]: info: MYVOLUMIO Environment detected Sep 02 13:39:52 volumiopr volumio[1212]: info: Plugin folders cleanup Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning into folder /volumio/app/plugins/ Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning category audio_interface Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning category miscellanea Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning category music_service Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning category plugins.json Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning category system_controller Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning category user_interface Sep 02 13:39:52 volumiopr volumio[1212]: info: Scanning into folder /data/plugins/ Sep 02 13:39:52 volumiopr volumio[1212]: info: Plugin folders cleanup completed Sep 02 13:39:52 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:39:52 volumiopr volumio[1212]: info: ----- Core plugins startup ---- Sep 02 13:39:52 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:39:52 volumiopr volumio[1212]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 13:39:52 volumiopr volumio[1212]: info: Adding plugin upnp to MyMusic Plugins Sep 02 13:39:52 volumiopr volumio[1212]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 13:39:52 volumiopr volumio[1212]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 13:39:52 volumiopr volumio[1212]: info: Loading plugins from folder /data/plugins/ Sep 02 13:39:52 volumiopr volumio[1212]: info: Loading plugin "system"... Sep 02 13:39:52 volumiopr volumio[1212]: info: Loading plugin "appearance"... Sep 02 13:39:53 volumiopr systemd[1]: systemd-fsckd.service: Succeeded. Sep 02 13:39:54 volumiopr volumio[1212]: info: Loading plugin "network"... Sep 02 13:39:54 volumiopr volumio[1212]: info: Refreshing Cached IP Addresses Sep 02 13:39:54 volumiopr sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 13:39:54 volumiopr sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:54 volumiopr volumio[1212]: info: Loading plugin "services"... Sep 02 13:39:54 volumiopr sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:39:54 volumiopr sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:54 volumiopr volumio[1212]: info: Loading plugin "alsa_controller"... Sep 02 13:39:54 volumiopr sudo[1298]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:54 volumiopr sudo[1300]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:54 volumiopr sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 02 13:39:54 volumiopr sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:54 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:39:54 volumiopr volumio[1212]: info: Loading plugin "wizard"... Sep 02 13:39:54 volumiopr volumio[1212]: info: Loading plugin "networkfs"... Sep 02 13:39:54 volumiopr volumio[1212]: info: Starting Udev Watcher for removable devices Sep 02 13:39:54 volumiopr volumio[1212]: info: Ignoring mount for partition: boot Sep 02 13:39:54 volumiopr volumio[1212]: info: Ignoring mount for partition: volumio Sep 02 13:39:54 volumiopr volumio[1212]: info: Ignoring mount for partition: volumio_data Sep 02 13:39:54 volumiopr volumio[1212]: info: Mounting Device 4EA4C1587F9F06D4 Sep 02 13:39:54 volumiopr sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/4EA4C1587F9F06D4 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 02 13:39:54 volumiopr sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:39:55 volumiopr sudo[1302]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:56 volumiopr ntfs-3g[1318]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 02 13:39:56 volumiopr ntfs-3g[1318]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1) Sep 02 13:39:56 volumiopr ntfs-3g[1318]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 02 13:39:56 volumiopr ntfs-3g[1318]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 02 13:39:56 volumiopr ntfs-3g[1318]: Global ownership and permissions enforced, configuration type 7 Sep 02 13:39:56 volumiopr sudo[1314]: pam_unix(sudo:session): session closed for user root Sep 02 13:39:56 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:39:56 volumiopr volumio[1212]: info: Loading plugin "volumio_command_line_client"... Sep 02 13:39:56 volumiopr volumio[1212]: info: Plugin upnp is not enabled Sep 02 13:39:56 volumiopr volumio[1212]: info: Loading plugin "my_music"... Sep 02 13:39:56 volumiopr volumio[1212]: info: Loading plugin "mpd"... Sep 02 13:39:57 volumiopr volumio[1212]: info: Plugin upnp_browser is not enabled Sep 02 13:39:57 volumiopr volumio[1212]: info: Loading plugin "alarm-clock"... Sep 02 13:39:57 volumiopr volumio[1212]: info: Plugin airplay_emulation is not enabled Sep 02 13:39:57 volumiopr volumio[1212]: info: Loading plugin "last_100"... Sep 02 13:39:57 volumiopr volumio[1212]: info: Loading plugin "webradio"... Sep 02 13:39:57 volumiopr volumio[1212]: info: Loading plugin "i2s_dacs"... Sep 02 13:39:57 volumiopr volumio[1212]: info: Loading plugin "volumiodiscovery"... Sep 02 13:39:58 volumiopr volumio[1212]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 13:39:58 volumiopr volumio[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:39:58 volumiopr volumio[1212]: *** WARNING *** For more information see Sep 02 13:39:58 volumiopr node[1212]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 13:39:58 volumiopr volumio[1212]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 13:39:58 volumiopr volumio[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:39:58 volumiopr volumio[1212]: *** WARNING *** For more information see Sep 02 13:39:58 volumiopr node[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:39:58 volumiopr node[1212]: *** WARNING *** For more information see Sep 02 13:39:58 volumiopr node[1212]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 13:39:58 volumiopr node[1212]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:39:58 volumiopr node[1212]: *** WARNING *** For more information see Sep 02 13:39:58 volumiopr volumio[1212]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 13:39:58 volumiopr volumio[1212]: info: Discovery: Started advertising with name: Volumio_pr Sep 02 13:39:58 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading plugin "outputs"... Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading plugin "albumart"... Sep 02 13:39:58 volumiopr volumio[1212]: info: Plugin example_plugin is not enabled Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading plugin "inputs"... Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading plugin "updater_comm"... Sep 02 13:39:58 volumiopr volumio[1212]: info: Plugin mpdemulation is not enabled Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading plugin "rest_api"... Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading plugin "websocket"... Sep 02 13:39:58 volumiopr volumio[1212]: info: Starting Socket.io Server version 2.3.0 Sep 02 13:39:58 volumiopr volumio[1212]: info: Loading i18n strings for locale de Sep 02 13:39:58 volumiopr volumio[1212]: Forking 3 albumart workers Sep 02 13:39:58 volumiopr volumio[1212]: Updating browse sources language Sep 02 13:39:58 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::initPlayerControls Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: Express server listening on port 3000 Sep 02 13:39:59 volumiopr volumio[1212]: [Metrics] WebUI: 8s 873.52ms Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::resetVolumioState Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::getcurrentVolume Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:39:59 volumiopr volumio[1212]: Starting albumart workers Sep 02 13:39:59 volumiopr volumio[1212]: Starting albumart workers Sep 02 13:39:59 volumiopr volumio[1212]: Starting albumart workers Sep 02 13:39:59 volumiopr volumio[1212]: Unable to parse: Sep 02 13:39:59 volumiopr volumio[1212]: Simple mixer control 'Master',0 Sep 02 13:39:59 volumiopr volumio[1212]: Capabilities: volume volume-joined Sep 02 13:39:59 volumiopr volumio[1212]: Playback channels: Mono Sep 02 13:39:59 volumiopr volumio[1212]: Capture channels: Mono Sep 02 13:39:59 volumiopr volumio[1212]: Limits: 0 - 248 Sep 02 13:39:59 volumiopr volumio[1212]: Mono: 141 [57%] Sep 02 13:39:59 volumiopr volumio[1212]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:39:59 volumiopr volumio[1212]: info: Reloading queue from file Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::setRepeat null single undefined Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::pushState Sep 02 13:39:59 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::volumioPushState Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::setRandom null Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::pushState Sep 02 13:39:59 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::volumioPushState Sep 02 13:39:59 volumiopr volumio[1212]: info: Setting Device type: Raspberry PI Sep 02 13:39:59 volumiopr volumio[1212]: info: Completed loading Core Plugins Sep 02 13:39:59 volumiopr volumio[1212]: info: Preparing to generate the ALSA configuration file Sep 02 13:39:59 volumiopr volumio[1212]: Unable to parse: Sep 02 13:39:59 volumiopr volumio[1212]: Simple mixer control 'Master',0 Sep 02 13:39:59 volumiopr volumio[1212]: Capabilities: volume volume-joined Sep 02 13:39:59 volumiopr volumio[1212]: Playback channels: Mono Sep 02 13:39:59 volumiopr volumio[1212]: Capture channels: Mono Sep 02 13:39:59 volumiopr volumio[1212]: Limits: 0 - 248 Sep 02 13:39:59 volumiopr volumio[1212]: Mono: 141 [57%] Sep 02 13:39:59 volumiopr volumio[1212]: info: VolumeController:: Volume=undefined Mute =false Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::pushState Sep 02 13:39:59 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::volumioPushState Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreStateMachine::updateTrackBlock Sep 02 13:39:59 volumiopr volumio[1212]: info: CorePlayQueue::getTrackBlock Sep 02 13:39:59 volumiopr volumio[1212]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:40:00 volumiopr volumio[1212]: info: Asound.conf file unchanged, so no further update is needed Sep 02 13:40:00 volumiopr volumio[1212]: info: Output device has changed, restarting MPD Sep 02 13:40:00 volumiopr volumio[1212]: info: ___________ START PLUGINS ___________ Sep 02 13:40:00 volumiopr volumio[1212]: info: ControllerMpd::onStart: Initializing MPD Sep 02 13:40:00 volumiopr volumio[1212]: info: Creating MPD Configuration file Sep 02 13:40:00 volumiopr sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:40:00 volumiopr sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:00 volumiopr sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:40:00 volumiopr sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:00 volumiopr sudo[1363]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:40:00 volumiopr volumio[1212]: info: [1725277200171] CoreMusicLibrary::Adding element Last_100 Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:40:00 volumiopr sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:40:00 volumiopr sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:40:00 volumiopr volumio[1212]: info: [1725277200190] CoreMusicLibrary::Adding element Webradio Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:40:00 volumiopr systemd[1]: Listening on mpd.socket. Sep 02 13:40:00 volumiopr sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:40:00 volumiopr sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:00 volumiopr systemd[1]: Starting Music Player Daemon... Sep 02 13:40:00 volumiopr sudo[1370]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:00 volumiopr volumio[1212]: info: Initializing BBC Radios Sep 02 13:40:00 volumiopr systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 02 13:40:00 volumiopr systemd[1]: mpd.service: Succeeded. Sep 02 13:40:00 volumiopr systemd[1]: Stopped Music Player Daemon. Sep 02 13:40:00 volumiopr systemd[1]: Starting Music Player Daemon... Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: Volumio Calling Home Sep 02 13:40:00 volumiopr sudo[1378]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 13:40:00 volumiopr sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:00 volumiopr sudo[1378]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 02 13:40:00 volumiopr sudo[1378]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:00 volumiopr volumio[1212]: info: Discovery: adding d7b00fe6-9e13-4d83-8f2b-6c24b65075de Sep 02 13:40:00 volumiopr volumio[1212]: info: Discovery: Found device Volumio_pr Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::volumioGetState Sep 02 13:40:00 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:00 volumiopr volumio[1212]: info: Discovery: this is already registered, d7b00fe6-9e13-4d83-8f2b-6c24b65075de Sep 02 13:40:00 volumiopr volumio[1212]: info: Discovery: Found device Volumio_pr Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::volumioGetState Sep 02 13:40:00 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:00 volumiopr volumio[1212]: info: MPD Permissions set Sep 02 13:40:00 volumiopr volumio[1212]: Unable to parse: Sep 02 13:40:00 volumiopr volumio[1212]: Simple mixer control 'Master',0 Sep 02 13:40:00 volumiopr volumio[1212]: Capabilities: volume volume-joined Sep 02 13:40:00 volumiopr volumio[1212]: Playback channels: Mono Sep 02 13:40:00 volumiopr volumio[1212]: Capture channels: Mono Sep 02 13:40:00 volumiopr volumio[1212]: Limits: 0 - 248 Sep 02 13:40:00 volumiopr volumio[1212]: Mono: 141 [57%] Sep 02 13:40:00 volumiopr volumio[1212]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 02 13:40:00 volumiopr volumio[1212]: info: Volumio called home Sep 02 13:40:00 volumiopr volumio[1212]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:00 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:01 volumiopr volumio[1212]: Unable to parse: Sep 02 13:40:01 volumiopr volumio[1212]: Simple mixer control 'Master',0 Sep 02 13:40:01 volumiopr volumio[1212]: Capabilities: volume volume-joined Sep 02 13:40:01 volumiopr volumio[1212]: Playback channels: Mono Sep 02 13:40:01 volumiopr volumio[1212]: Capture channels: Mono Sep 02 13:40:01 volumiopr volumio[1212]: Limits: 0 - 248 Sep 02 13:40:01 volumiopr volumio[1212]: Mono: 141 [57%] Sep 02 13:40:01 volumiopr volumio[1212]: info: VolumeController:: Volume=undefined Mute =false Sep 02 13:40:01 volumiopr volumio[1212]: info: CoreStateMachine::pushState Sep 02 13:40:01 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:01 volumiopr volumio[1212]: info: CoreCommandRouter::volumioPushState Sep 02 13:40:01 volumiopr volumio[1212]: info: CoreCommandRouter::volumioGetState Sep 02 13:40:01 volumiopr volumio[1212]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:01 volumiopr systemd[1]: systemd-hostnamed.service: Succeeded. Sep 02 13:40:04 volumiopr mpd[1397]: Sep 02 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 13:40:06 volumiopr volumio-remote-updater[683]: [2024-09-02 13:40:06] [connect] Successful connection Sep 02 13:40:06 volumiopr volumio-remote-updater[683]: [2024-09-02 13:40:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725277206 101 Sep 02 13:40:06 volumiopr volumio[1212]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Sep 02 13:40:09 volumiopr systemd[1]: Started Music Player Daemon. Sep 02 13:40:09 volumiopr sudo[1372]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:09 volumiopr sudo[1365]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:09 volumiopr volumio[1212]: info: Completed starting Core Plugins Sep 02 13:40:09 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:40:09 volumiopr volumio[1212]: info: ----- MyVolumio plugins startup ---- Sep 02 13:40:09 volumiopr volumio[1212]: info: ------------------------------------------- Sep 02 13:40:09 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 02 13:40:09 volumiopr volumio[1212]: error: MPD error: The expression evaluated to a falsy value: Sep 02 13:40:09 volumiopr volumio[1212]: assert.ok(self.idling) Sep 02 13:40:09 volumiopr volumio[1212]: error: The expression evaluated to a falsy value: Sep 02 13:40:09 volumiopr volumio[1212]: assert.ok(self.idling) Sep 02 13:40:09 volumiopr volumio[1212]: error: updateQueue error: null Sep 02 13:40:09 volumiopr volumio[1212]: info: MPD running with PID1397 Sep 02 13:40:09 volumiopr volumio[1212]: ,establishing connection Sep 02 13:40:09 volumiopr volumio[1212]: error: updateQueue error: null Sep 02 13:40:13 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-DISCONNECTED bssid=9e:ed:6f:9e:f2:2c reason=7 Sep 02 13:40:13 volumiopr dhcpcd[1098]: wlan0: carrier lost Sep 02 13:40:13 volumiopr wpa_supplicant[968]: wlan0: Trying to associate with SSID 'Vodafone Homespot' Sep 02 13:40:13 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Sep 02 13:40:13 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Sep 02 13:40:13 volumiopr avahi-daemon[690]: Withdrawing address record for 169.254.97.40 on wlan0. Sep 02 13:40:13 volumiopr avahi-daemon[690]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.97.40. Sep 02 13:40:13 volumiopr avahi-daemon[690]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 02 13:40:13 volumiopr dhcpcd[1098]: wlan0: deleting route to 169.254.0.0/16 Sep 02 13:40:13 volumiopr volumio[1212]: info: Discovery: A device disappeared from network Sep 02 13:40:13 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 02 13:40:14 volumiopr ntpd[1038]: Deleting interface #4 wlan0, 169.254.97.40#123, interface stats: received=0, sent=0, dropped=0, active_time=26 secs Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 02 13:40:19 volumiopr volumio[1212]: info: Adding plugin bluetooth to MyMusic Plugins Sep 02 13:40:19 volumiopr volumio[1212]: info: Adding plugin multiroom to MyMusic Plugins Sep 02 13:40:19 volumiopr volumio[1212]: info: Adding plugin metavolumio to MyMusic Plugins Sep 02 13:40:19 volumiopr volumio[1212]: info: Adding plugin cd_controller to MyMusic Plugins Sep 02 13:40:19 volumiopr volumio[1212]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 02 13:40:19 volumiopr volumio[1212]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 02 13:40:19 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 02 13:40:20 volumiopr volumio[1212]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 02 13:40:20 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 02 13:40:20 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:20 volumiopr volumio[1212]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:20 volumiopr volumio[1212]: info: Starting MyVolumio Remote Streaming Endpoints Sep 02 13:40:20 volumiopr volumio[1212]: info: MyVolumio login type: Token Sep 02 13:40:21 volumiopr volumio[1212]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 02 13:40:21 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 02 13:40:22 volumiopr volumio[1212]: info: Starting Streaming Service Transparent Proxy Sep 02 13:40:22 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 02 13:40:22 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 02 13:40:22 volumiopr volumio[1212]: info: Streaming services startup Sep 02 13:40:22 volumiopr volumio[1212]: info: Starting Streaming Daemon Sep 02 13:40:22 volumiopr sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 02 13:40:22 volumiopr sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:22 volumiopr volumio[1212]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 02 13:40:22 volumiopr sudo[1507]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:22 volumiopr volumio[1212]: error: Cannot start Volumio Streaming Daemon Sep 02 13:40:22 volumiopr volumio[1212]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 02 13:40:22 volumiopr volumio[1212]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 02 13:40:24 volumiopr volumio[1212]: STREAMING PROXY: Starting server on port 3245 Sep 02 13:40:24 volumiopr volumio[1212]: Node JS runtime: 14 Sep 02 13:40:24 volumiopr volumio[1212]: error: MyVolumio Custom Token format not valid, refreshing it Sep 02 13:40:26 volumiopr wpa_supplicant[968]: wlan0: Failed to initiate sched scan Sep 02 13:40:29 volumiopr volumio[1212]: <--- Last few GCs ---> Sep 02 13:40:29 volumiopr volumio[1212]: [1212:0x3932788] 40862 ms: Scavenge 155.2 (164.2) -> 155.1 (164.2) MB, 0.6 / 0.0 ms (average mu = 0.900, current mu = 0.926) allocation failure Sep 02 13:40:29 volumiopr volumio[1212]: [1212:0x3932788] 40963 ms: Scavenge 174.0 (183.1) -> 173.9 (183.1) MB, 0.7 / 0.0 ms (average mu = 0.900, current mu = 0.926) allocation failure Sep 02 13:40:29 volumiopr volumio[1212]: [1212:0x3932788] 41099 ms: Scavenge 193.0 (202.1) -> 193.0 (202.1) MB, 1.3 / 0.0 ms (average mu = 0.900, current mu = 0.926) allocation failure Sep 02 13:40:29 volumiopr volumio[1212]: <--- JS stacktrace ---> Sep 02 13:40:29 volumiopr volumio[1212]: FATAL ERROR: MarkCompactCollector: young object promotion failed Allocation failed - JavaScript heap out of memory Sep 02 13:40:29 volumiopr volumio-remote-updater[683]: [2024-09-02 13:40:29] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 02 13:40:29 volumiopr volumio-remote-updater[683]: [2024-09-02 13:40:29] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 02 13:40:29 volumiopr systemd[1]: volumio.service: Main process exited, code=killed, status=6/ABRT Sep 02 13:40:29 volumiopr ntfs-3g[1318]: Unmounting /dev/sda1 () Sep 02 13:40:29 volumiopr systemd[1]: media-4EA4C1587F9F06D4.mount: Succeeded. Sep 02 13:40:29 volumiopr systemd[1]: volumio.service: Failed with result 'signal'. Sep 02 13:40:29 volumiopr systemd[1]: Started dynamicswap service. Sep 02 13:40:29 volumiopr systemd[1]: dynamicswap.service: Succeeded. Sep 02 13:40:29 volumiopr systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 02 13:40:29 volumiopr systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 02 13:40:29 volumiopr systemd[1]: Stopped Volumio Backend Module. Sep 02 13:40:29 volumiopr systemd[1]: Started Volumio Backend Module. Sep 02 13:40:29 volumiopr systemd[1]: Started dynamicswap service. Sep 02 13:40:29 volumiopr systemd[1]: dynamicswap.service: Succeeded. Sep 02 13:40:31 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:31 volumiopr volumio[1561]: info: ----- Volumio3 ---- Sep 02 13:40:31 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:31 volumiopr volumio[1561]: info: ----- System startup ---- Sep 02 13:40:31 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:31 volumiopr wpa_supplicant[968]: wlan0: Failed to initiate sched scan Sep 02 13:40:32 volumiopr volumio[1561]: info: MYVOLUMIO Environment detected Sep 02 13:40:32 volumiopr volumio[1561]: info: Plugin folders cleanup Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning into folder /volumio/app/plugins/ Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning category audio_interface Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning category miscellanea Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning category music_service Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning category plugins.json Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning category system_controller Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning category user_interface Sep 02 13:40:32 volumiopr volumio[1561]: info: Scanning into folder /data/plugins/ Sep 02 13:40:32 volumiopr volumio[1561]: info: Plugin folders cleanup completed Sep 02 13:40:32 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:32 volumiopr volumio[1561]: info: ----- Core plugins startup ---- Sep 02 13:40:32 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:32 volumiopr volumio[1561]: info: Loading plugins from folder /volumio/app/plugins/ Sep 02 13:40:32 volumiopr volumio[1561]: info: Adding plugin upnp to MyMusic Plugins Sep 02 13:40:32 volumiopr volumio[1561]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 02 13:40:32 volumiopr volumio[1561]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 02 13:40:32 volumiopr volumio[1561]: info: Loading plugins from folder /data/plugins/ Sep 02 13:40:32 volumiopr volumio[1561]: info: Loading plugin "system"... Sep 02 13:40:32 volumiopr volumio[1561]: info: Loading plugin "appearance"... Sep 02 13:40:33 volumiopr volumio[1561]: info: Loading plugin "network"... Sep 02 13:40:33 volumiopr volumio[1561]: info: Refreshing Cached IP Addresses Sep 02 13:40:33 volumiopr sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 13:40:33 volumiopr sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:33 volumiopr sudo[1588]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:33 volumiopr volumio[1561]: info: Loading plugin "services"... Sep 02 13:40:33 volumiopr sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:40:33 volumiopr sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:33 volumiopr volumio[1561]: info: Loading plugin "alsa_controller"... Sep 02 13:40:33 volumiopr sudo[1590]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:33 volumiopr sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 02 13:40:33 volumiopr sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:33 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:40:33 volumiopr volumio[1561]: info: Loading plugin "wizard"... Sep 02 13:40:33 volumiopr volumio[1561]: info: Loading plugin "networkfs"... Sep 02 13:40:33 volumiopr volumio[1561]: info: Starting Udev Watcher for removable devices Sep 02 13:40:33 volumiopr volumio[1561]: info: Ignoring mount for partition: boot Sep 02 13:40:33 volumiopr volumio[1561]: info: Ignoring mount for partition: volumio Sep 02 13:40:33 volumiopr volumio[1561]: info: Ignoring mount for partition: volumio_data Sep 02 13:40:33 volumiopr volumio[1561]: info: Mounting Device 4EA4C1587F9F06D4 Sep 02 13:40:33 volumiopr sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/4EA4C1587F9F06D4 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 02 13:40:33 volumiopr sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:34 volumiopr sudo[1593]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:34 volumiopr volumio-remote-updater[683]: [2024-09-02 13:40:34] [connect] Successful connection Sep 02 13:40:35 volumiopr ntfs-3g[1608]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 02 13:40:35 volumiopr ntfs-3g[1608]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1) Sep 02 13:40:35 volumiopr ntfs-3g[1608]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 02 13:40:35 volumiopr ntfs-3g[1608]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Sep 02 13:40:35 volumiopr ntfs-3g[1608]: Global ownership and permissions enforced, configuration type 7 Sep 02 13:40:35 volumiopr sudo[1604]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:35 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:40:35 volumiopr volumio[1561]: info: Loading plugin "volumio_command_line_client"... Sep 02 13:40:35 volumiopr volumio[1561]: info: Plugin upnp is not enabled Sep 02 13:40:35 volumiopr volumio[1561]: info: Loading plugin "my_music"... Sep 02 13:40:35 volumiopr volumio[1561]: info: Loading plugin "mpd"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Plugin upnp_browser is not enabled Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "alarm-clock"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Plugin airplay_emulation is not enabled Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "last_100"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "webradio"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "i2s_dacs"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "volumiodiscovery"... Sep 02 13:40:36 volumiopr volumio[1561]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 13:40:36 volumiopr node[1561]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 02 13:40:36 volumiopr volumio[1561]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:40:36 volumiopr node[1561]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:40:36 volumiopr volumio[1561]: *** WARNING *** For more information see Sep 02 13:40:36 volumiopr node[1561]: *** WARNING *** For more information see Sep 02 13:40:36 volumiopr volumio[1561]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 13:40:36 volumiopr node[1561]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 02 13:40:36 volumiopr volumio[1561]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:40:36 volumiopr node[1561]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 02 13:40:36 volumiopr volumio[1561]: *** WARNING *** For more information see Sep 02 13:40:36 volumiopr node[1561]: *** WARNING *** For more information see Sep 02 13:40:36 volumiopr volumio[1561]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 02 13:40:36 volumiopr volumio[1561]: info: Discovery: Started advertising with name: Volumio_pr Sep 02 13:40:36 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "outputs"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "albumart"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Plugin example_plugin is not enabled Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "inputs"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "updater_comm"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Plugin mpdemulation is not enabled Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "rest_api"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Loading plugin "websocket"... Sep 02 13:40:36 volumiopr volumio[1561]: info: Starting Socket.io Server version 2.3.0 Sep 02 13:40:37 volumiopr volumio[1561]: info: Loading i18n strings for locale de Sep 02 13:40:37 volumiopr volumio[1561]: Updating browse sources language Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:40:37 volumiopr volumio[1561]: Forking 3 albumart workers Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::initPlayerControls Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:40:37 volumiopr volumio[1561]: Express server listening on port 3000 Sep 02 13:40:37 volumiopr volumio[1561]: [Metrics] WebUI: 6s 229.67ms Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreStateMachine::resetVolumioState Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreStateMachine::getcurrentVolume Sep 02 13:40:37 volumiopr volumio[1561]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:40:37 volumiopr wpa_supplicant[968]: wlan0: Trying to associate with SSID 'Vodafone Homespot' Sep 02 13:40:37 volumiopr volumio[1561]: Unable to parse: Sep 02 13:40:37 volumiopr volumio[1561]: Simple mixer control 'Master',0 Sep 02 13:40:37 volumiopr volumio[1561]: Capabilities: volume volume-joined Sep 02 13:40:37 volumiopr volumio[1561]: Playback channels: Mono Sep 02 13:40:37 volumiopr volumio[1561]: Capture channels: Mono Sep 02 13:40:37 volumiopr volumio[1561]: Limits: 0 - 248 Sep 02 13:40:37 volumiopr volumio[1561]: Mono: 141 [57%] Sep 02 13:40:37 volumiopr volumio[1561]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 02 13:40:37 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 02 13:40:37 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Vodafone Homespot" auth_failures=1 duration=10 reason=CONN_FAILED Sep 02 13:40:38 volumiopr volumio-remote-updater[683]: [2024-09-02 13:40:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725277234 101 Sep 02 13:40:38 volumiopr volumio[1561]: 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 Sep 02 13:40:38 volumiopr volumio[1561]: Unable to parse: Sep 02 13:40:38 volumiopr volumio[1561]: Simple mixer control 'Master',0 Sep 02 13:40:38 volumiopr volumio[1561]: Capabilities: volume volume-joined Sep 02 13:40:38 volumiopr volumio[1561]: Playback channels: Mono Sep 02 13:40:38 volumiopr volumio[1561]: Capture channels: Mono Sep 02 13:40:38 volumiopr volumio[1561]: Limits: 0 - 248 Sep 02 13:40:38 volumiopr volumio[1561]: Mono: 141 [57%] Sep 02 13:40:38 volumiopr volumio[1561]: info: VolumeController:: Volume=undefined Mute =false Sep 02 13:40:38 volumiopr volumio[1561]: Starting albumart workers Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::pushState Sep 02 13:40:38 volumiopr volumio[1561]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:38 volumiopr volumio[1561]: Starting albumart workers Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioPushState Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::updateTrackBlock Sep 02 13:40:38 volumiopr volumio[1561]: info: CorePlayQueue::getTrackBlock Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 13:40:38 volumiopr volumio[1561]: Starting albumart workers Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:38 volumiopr volumio[1561]: info: Reloading queue from file Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::setRepeat null single undefined Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::pushState Sep 02 13:40:38 volumiopr volumio[1561]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioPushState Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::setRandom null Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::pushState Sep 02 13:40:38 volumiopr volumio[1561]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioPushState Sep 02 13:40:38 volumiopr volumio[1561]: info: Setting Device type: Raspberry PI Sep 02 13:40:38 volumiopr volumio[1561]: Unable to parse: Sep 02 13:40:38 volumiopr volumio[1561]: Simple mixer control 'Master',0 Sep 02 13:40:38 volumiopr volumio[1561]: Capabilities: volume volume-joined Sep 02 13:40:38 volumiopr volumio[1561]: Playback channels: Mono Sep 02 13:40:38 volumiopr volumio[1561]: Capture channels: Mono Sep 02 13:40:38 volumiopr volumio[1561]: Limits: 0 - 248 Sep 02 13:40:38 volumiopr volumio[1561]: Mono: 141 [57%] Sep 02 13:40:38 volumiopr volumio[1561]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 02 13:40:38 volumiopr volumio[1561]: info: Completed loading Core Plugins Sep 02 13:40:38 volumiopr volumio[1561]: info: Preparing to generate the ALSA configuration file Sep 02 13:40:38 volumiopr volumio[1561]: Unable to parse: Sep 02 13:40:38 volumiopr volumio[1561]: Simple mixer control 'Master',0 Sep 02 13:40:38 volumiopr volumio[1561]: Capabilities: volume volume-joined Sep 02 13:40:38 volumiopr volumio[1561]: Playback channels: Mono Sep 02 13:40:38 volumiopr volumio[1561]: Capture channels: Mono Sep 02 13:40:38 volumiopr volumio[1561]: Limits: 0 - 248 Sep 02 13:40:38 volumiopr volumio[1561]: Mono: 141 [57%] Sep 02 13:40:38 volumiopr volumio[1561]: info: VolumeController:: Volume=undefined Mute =false Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreStateMachine::pushState Sep 02 13:40:38 volumiopr volumio[1561]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioPushState Sep 02 13:40:38 volumiopr volumio[1561]: info: Discovery: adding d7b00fe6-9e13-4d83-8f2b-6c24b65075de Sep 02 13:40:38 volumiopr volumio[1561]: info: Discovery: Found device Volumio_pr Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioGetState Sep 02 13:40:38 volumiopr volumio[1561]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:38 volumiopr volumio[1561]: info: Asound.conf file unchanged, so no further update is needed Sep 02 13:40:38 volumiopr volumio[1561]: info: Output device has changed, restarting MPD Sep 02 13:40:38 volumiopr volumio[1561]: info: ___________ START PLUGINS ___________ Sep 02 13:40:38 volumiopr volumio[1561]: info: ControllerMpd::onStart: Initializing MPD Sep 02 13:40:38 volumiopr volumio[1561]: info: Creating MPD Configuration file Sep 02 13:40:38 volumiopr sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:40:38 volumiopr sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:40:38 volumiopr volumio[1561]: info: [1725277238543] CoreMusicLibrary::Adding element Last_100 Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:40:38 volumiopr sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 13:40:38 volumiopr sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 02 13:40:38 volumiopr sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:40:38 volumiopr sudo[1653]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:38 volumiopr volumio[1561]: info: [1725277238559] CoreMusicLibrary::Adding element Webradio Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:40:38 volumiopr sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:40:38 volumiopr volumio[1561]: info: Initializing BBC Radios Sep 02 13:40:38 volumiopr sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 13:40:38 volumiopr sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:38 volumiopr sudo[1657]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:38 volumiopr systemd[1]: Stopping Music Player Daemon... Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:40:38 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:38 volumiopr volumio[1561]: info: Volumio Calling Home Sep 02 13:40:38 volumiopr systemd[1]: mpd.service: Succeeded. Sep 02 13:40:38 volumiopr systemd[1]: Stopped Music Player Daemon. Sep 02 13:40:38 volumiopr systemd[1]: Starting Music Player Daemon... Sep 02 13:40:39 volumiopr sudo[1683]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 13:40:39 volumiopr sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:40:39 volumiopr sudo[1683]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:39 volumiopr volumio[1561]: info: MPD Permissions set Sep 02 13:40:39 volumiopr volumio[1561]: info: MPD Permissions set Sep 02 13:40:39 volumiopr volumio[1561]: info: Volumio called home Sep 02 13:40:39 volumiopr volumio[1561]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:40:39 volumiopr volumio[1561]: info: CoreCommandRouter::volumioGetState Sep 02 13:40:39 volumiopr volumio[1561]: info: CorePlayQueue::getTrack 0 Sep 02 13:40:39 volumiopr kernel: sdhost_busy_irq: intmask 440, data a327fb1b Sep 02 13:40:40 volumiopr mpd[1700]: Sep 02 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 13:40:45 volumiopr systemd[1]: Started Music Player Daemon. Sep 02 13:40:45 volumiopr sudo[1655]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:45 volumiopr sudo[1659]: pam_unix(sudo:session): session closed for user root Sep 02 13:40:45 volumiopr volumio[1561]: info: Completed starting Core Plugins Sep 02 13:40:45 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:45 volumiopr volumio[1561]: info: ----- MyVolumio plugins startup ---- Sep 02 13:40:45 volumiopr volumio[1561]: info: ------------------------------------------- Sep 02 13:40:45 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 02 13:40:46 volumiopr volumio[1561]: error: MPD error: The expression evaluated to a falsy value: Sep 02 13:40:46 volumiopr volumio[1561]: assert.ok(self.idling) Sep 02 13:40:46 volumiopr volumio[1561]: error: The expression evaluated to a falsy value: Sep 02 13:40:46 volumiopr volumio[1561]: assert.ok(self.idling) Sep 02 13:40:46 volumiopr volumio[1561]: info: MPD running with PID1700 Sep 02 13:40:46 volumiopr volumio[1561]: ,establishing connection Sep 02 13:40:46 volumiopr volumio[1561]: error: updateQueue error: null Sep 02 13:40:46 volumiopr volumio[1561]: error: updateQueue error: null Sep 02 13:40:49 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Vodafone Homespot" Sep 02 13:40:49 volumiopr wpa_supplicant[968]: wlan0: Trying to associate with SSID 'Vodafone Homespot' Sep 02 13:40:49 volumiopr kernel: mmc0: timeout waiting for hardware interrupt. Sep 02 13:40:49 volumiopr kernel: [0605853a] TSK< ae70e8b0 0 Sep 02 13:40:49 volumiopr kernel: [06058567] TSK> ae70e8b0 0 Sep 02 13:40:49 volumiopr kernel: [06058594] PRD< ae70eb84 0 Sep 02 13:40:49 volumiopr kernel: [06058595] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [060585a1] PRD2 1 0 Sep 02 13:40:49 volumiopr kernel: [060585a8] PRD3 8c0f5c04 0 Sep 02 13:40:49 volumiopr kernel: [060585a9] PDM> ae70eb84 0 Sep 02 13:40:49 volumiopr kernel: [060585aa] REQ< ae70eab0 10801 Sep 02 13:40:49 volumiopr kernel: [060585ab] CMD< 12 35a898 Sep 02 13:40:49 volumiopr kernel: [060585b1] CMDD 100 200 Sep 02 13:40:49 volumiopr kernel: [060585b2] SDMA ae70eb84 81c4d840 Sep 02 13:40:49 volumiopr kernel: [060585b4] FCM< ae70eab0 ae70eb24 Sep 02 13:40:49 volumiopr kernel: [060585b5] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [060585b6] FCM> ae70eab0 0 Sep 02 13:40:49 volumiopr kernel: [060585b6] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [060585b6] REQ> ae70eab0 0 Sep 02 13:40:49 volumiopr kernel: [06059bf5] DMA< ae70eb84 1 Sep 02 13:40:49 volumiopr kernel: [06059bf5] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [06059c06] FDA< ae70eab0 0 Sep 02 13:40:49 volumiopr kernel: [06059c06] TCM< ae70eb84 0 Sep 02 13:40:49 volumiopr kernel: [06059c08] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [06059c0c] FCM< ae70eab0 ae70eb54 Sep 02 13:40:49 volumiopr kernel: [06059c0e] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [06059c0f] FCM> ae70eab0 ae70eb54 Sep 02 13:40:49 volumiopr kernel: [06059c0f] TCM> ae70eb84 0 Sep 02 13:40:49 volumiopr kernel: [06059c10] FDA> ae70eab0 ae70eb54 Sep 02 13:40:49 volumiopr kernel: [06059c10] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [06059c16] TSK< ae70eab0 0 Sep 02 13:40:49 volumiopr kernel: [06059c2f] TSK> ae70eab0 0 Sep 02 13:40:49 volumiopr kernel: [0605ab66] PRD< ae70ed84 0 Sep 02 13:40:49 volumiopr kernel: [0605ab66] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [0605ab6c] PRD2 1 0 Sep 02 13:40:49 volumiopr kernel: [0605ab74] PRD3 8c0f5c04 0 Sep 02 13:40:49 volumiopr kernel: [0605ab75] PDM> ae70ed84 0 Sep 02 13:40:49 volumiopr kernel: [0605ab76] REQ< ae70ecb0 10801 Sep 02 13:40:49 volumiopr kernel: [0605ab77] CMD< 12 3329d0 Sep 02 13:40:49 volumiopr kernel: [0605ab7d] CMDD 10 200 Sep 02 13:40:49 volumiopr kernel: [0605ab7e] SDMA ae70ed84 81c4d840 Sep 02 13:40:49 volumiopr kernel: [0605ab82] FCM< ae70ecb0 ae70ed24 Sep 02 13:40:49 volumiopr kernel: [0605ab84] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [0605ab84] FCM> ae70ecb0 0 Sep 02 13:40:49 volumiopr kernel: [0605ab85] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [0605ab85] REQ> ae70ecb0 0 Sep 02 13:40:49 volumiopr kernel: [0605ae33] DMA< ae70ed84 1 Sep 02 13:40:49 volumiopr kernel: [0605ae33] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [0605ae38] FDA< ae70ecb0 0 Sep 02 13:40:49 volumiopr kernel: [0605ae39] TCM< ae70ed84 0 Sep 02 13:40:49 volumiopr kernel: [0605ae3a] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [0605ae3d] FCM< ae70ecb0 ae70ed54 Sep 02 13:40:49 volumiopr kernel: [0605ae40] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [0605ae40] FCM> ae70ecb0 ae70ed54 Sep 02 13:40:49 volumiopr kernel: [0605ae41] TCM> ae70ed84 0 Sep 02 13:40:49 volumiopr kernel: [0605ae41] FDA> ae70ecb0 ae70ed54 Sep 02 13:40:49 volumiopr kernel: [0605ae42] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0605ae46] TSK< ae70ecb0 0 Sep 02 13:40:49 volumiopr kernel: [0605ae62] TSK> ae70ecb0 0 Sep 02 13:40:49 volumiopr kernel: [0605bf6e] PRD< ae70ef84 0 Sep 02 13:40:49 volumiopr kernel: [0605bf6f] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [0605bf74] PRD2 1 0 Sep 02 13:40:49 volumiopr kernel: [0605bf7b] PRD3 8c0f5c04 0 Sep 02 13:40:49 volumiopr kernel: [0605bf7b] PDM> ae70ef84 0 Sep 02 13:40:49 volumiopr kernel: [0605bf7c] REQ< ae70eeb0 10801 Sep 02 13:40:49 volumiopr kernel: [0605bf7d] CMD< 12 345ad8 Sep 02 13:40:49 volumiopr kernel: [0605bf81] CMDD 8 200 Sep 02 13:40:49 volumiopr kernel: [0605bf82] SDMA ae70ef84 81c4d840 Sep 02 13:40:49 volumiopr kernel: [0605bf85] FCM< ae70eeb0 ae70ef24 Sep 02 13:40:49 volumiopr kernel: [0605bf86] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [0605bf86] FCM> ae70eeb0 0 Sep 02 13:40:49 volumiopr kernel: [0605bf87] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [0605bf87] REQ> ae70eeb0 0 Sep 02 13:40:49 volumiopr kernel: [0605c12f] DMA< ae70ef84 1 Sep 02 13:40:49 volumiopr kernel: [0605c130] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [0605c135] FDA< ae70eeb0 0 Sep 02 13:40:49 volumiopr kernel: [0605c136] TCM< ae70ef84 0 Sep 02 13:40:49 volumiopr kernel: [0605c137] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [0605c13a] FCM< ae70eeb0 ae70ef54 Sep 02 13:40:49 volumiopr kernel: [0605c13c] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [0605c13d] FCM> ae70eeb0 ae70ef54 Sep 02 13:40:49 volumiopr kernel: [0605c13e] TCM> ae70ef84 0 Sep 02 13:40:49 volumiopr kernel: [0605c13e] FDA> ae70eeb0 ae70ef54 Sep 02 13:40:49 volumiopr kernel: [0605c13f] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0605c144] TSK< ae70eeb0 0 Sep 02 13:40:49 volumiopr kernel: [0605c162] TSK> ae70eeb0 0 Sep 02 13:40:49 volumiopr kernel: [0605c21a] PRD< ae70f184 0 Sep 02 13:40:49 volumiopr kernel: [0605c21a] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [0605c21f] PRD2 3 0 Sep 02 13:40:49 volumiopr kernel: [0605c227] PRD3 8c0f5c04 0 Sep 02 13:40:49 volumiopr kernel: [0605c227] PDM> ae70f184 0 Sep 02 13:40:49 volumiopr kernel: [0605c228] REQ< ae70f0b0 10801 Sep 02 13:40:49 volumiopr kernel: [0605c229] CMD< 12 345ae0 Sep 02 13:40:49 volumiopr kernel: [0605c22d] CMDD 20 200 Sep 02 13:40:49 volumiopr kernel: [0605c22d] SDMA ae70f184 81c4d840 Sep 02 13:40:49 volumiopr kernel: [0605c22f] FCM< ae70f0b0 ae70f124 Sep 02 13:40:49 volumiopr kernel: [0605c230] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [0605c231] FCM> ae70f0b0 0 Sep 02 13:40:49 volumiopr kernel: [0605c231] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [0605c231] REQ> ae70f0b0 0 Sep 02 13:40:49 volumiopr kernel: [0605c5cf] DMA< ae70f184 1 Sep 02 13:40:49 volumiopr kernel: [0605c5cf] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [0605c5d6] FDA< ae70f0b0 0 Sep 02 13:40:49 volumiopr kernel: [0605c5d7] TCM< ae70f184 0 Sep 02 13:40:49 volumiopr kernel: [0605c5d8] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [0605c5db] FCM< ae70f0b0 ae70f154 Sep 02 13:40:49 volumiopr kernel: [0605c5dd] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [0605c5de] FCM> ae70f0b0 ae70f154 Sep 02 13:40:49 volumiopr kernel: [0605c5de] TCM> ae70f184 0 Sep 02 13:40:49 volumiopr kernel: [0605c5df] FDA> ae70f0b0 ae70f154 Sep 02 13:40:49 volumiopr kernel: [0605c5df] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0605c5e4] TSK< ae70f0b0 0 Sep 02 13:40:49 volumiopr kernel: [0605c608] TSK> ae70f0b0 0 Sep 02 13:40:49 volumiopr kernel: [0605c6e5] PRD< ae70f384 0 Sep 02 13:40:49 volumiopr kernel: [0605c6e5] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [0605c6ee] PRD2 8 0 Sep 02 13:40:49 volumiopr kernel: [0605c6fa] PRD3 89de6784 0 Sep 02 13:40:49 volumiopr kernel: [0605c6fb] PDM> ae70f384 0 Sep 02 13:40:49 volumiopr kernel: [0605c6fb] REQ< ae70f2b0 10801 Sep 02 13:40:49 volumiopr kernel: [0605c6fc] CMD< 12 345b00 Sep 02 13:40:49 volumiopr kernel: [0605c700] CMDD 40 200 Sep 02 13:40:49 volumiopr kernel: [0605c701] SDMA ae70f384 81c4d840 Sep 02 13:40:49 volumiopr kernel: [0605c703] FCM< ae70f2b0 ae70f324 Sep 02 13:40:49 volumiopr kernel: [0605c704] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [0605c704] FCM> ae70f2b0 0 Sep 02 13:40:49 volumiopr kernel: [0605c705] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [0605c705] REQ> ae70f2b0 0 Sep 02 13:40:49 volumiopr kernel: [0605cd6d] DMA< ae70f384 1 Sep 02 13:40:49 volumiopr kernel: [0605cd6e] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [0605cd79] FDA< ae70f2b0 0 Sep 02 13:40:49 volumiopr kernel: [0605cd7a] TCM< ae70f384 0 Sep 02 13:40:49 volumiopr kernel: [0605cd7c] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [0605cd7f] FCM< ae70f2b0 ae70f354 Sep 02 13:40:49 volumiopr kernel: [0605cd81] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [0605cd82] FCM> ae70f2b0 ae70f354 Sep 02 13:40:49 volumiopr kernel: [0605cd83] TCM> ae70f384 0 Sep 02 13:40:49 volumiopr kernel: [0605cd83] FDA> ae70f2b0 ae70f354 Sep 02 13:40:49 volumiopr kernel: [0605cd83] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0605cd8a] TSK< ae70f2b0 0 Sep 02 13:40:49 volumiopr kernel: [0605cdb6] TSK> ae70f2b0 0 Sep 02 13:40:49 volumiopr kernel: [0605cded] PRD< ae70f584 0 Sep 02 13:40:49 volumiopr kernel: [0605cded] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [0605cdf2] PRD2 2 0 Sep 02 13:40:49 volumiopr kernel: [0605cdfb] PRD3 8c0f5c04 0 Sep 02 13:40:49 volumiopr kernel: [0605cdfb] PDM> ae70f584 0 Sep 02 13:40:49 volumiopr kernel: [0605cdfc] REQ< ae70f4b0 10801 Sep 02 13:40:49 volumiopr kernel: [0605cdfd] CMD< 12 345b40 Sep 02 13:40:49 volumiopr kernel: [0605ce02] CMDD 10 200 Sep 02 13:40:49 volumiopr kernel: [0605ce03] SDMA ae70f584 81c4d840 Sep 02 13:40:49 volumiopr kernel: [0605ce05] FCM< ae70f4b0 ae70f524 Sep 02 13:40:49 volumiopr kernel: [0605ce06] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [0605ce06] FCM> ae70f4b0 0 Sep 02 13:40:49 volumiopr kernel: [0605ce07] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [0605ce07] REQ> ae70f4b0 0 Sep 02 13:40:49 volumiopr kernel: [0605d066] DMA< ae70f584 1 Sep 02 13:40:49 volumiopr kernel: [0605d067] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [0605d06d] FDA< ae70f4b0 0 Sep 02 13:40:49 volumiopr kernel: [0605d06e] TCM< ae70f584 0 Sep 02 13:40:49 volumiopr kernel: [0605d06f] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [0605d072] FCM< ae70f4b0 ae70f554 Sep 02 13:40:49 volumiopr kernel: [0605d075] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [0605d076] FCM> ae70f4b0 ae70f554 Sep 02 13:40:49 volumiopr kernel: [0605d076] TCM> ae70f584 0 Sep 02 13:40:49 volumiopr kernel: [0605d076] FDA> ae70f4b0 ae70f554 Sep 02 13:40:49 volumiopr kernel: [0605d077] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0605d07c] TSK< ae70f4b0 0 Sep 02 13:40:49 volumiopr kernel: [0605d096] TSK> ae70f4b0 0 Sep 02 13:40:49 volumiopr kernel: [0605d0b9] PRD< ae70f784 0 Sep 02 13:40:49 volumiopr kernel: [0605d0ba] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [0605d0c5] PRD2 c 0 Sep 02 13:40:49 volumiopr kernel: [0605d0d3] PRD3 89de6784 0 Sep 02 13:40:49 volumiopr kernel: [0605d0d4] PDM> ae70f784 0 Sep 02 13:40:49 volumiopr kernel: [0605d0d4] REQ< ae70f6b0 10801 Sep 02 13:40:49 volumiopr kernel: [0605d0d5] CMD< 12 345c38 Sep 02 13:40:49 volumiopr kernel: [0605d0d9] CMDD 88 200 Sep 02 13:40:49 volumiopr kernel: [0605d0da] SDMA ae70f784 81c4d840 Sep 02 13:40:49 volumiopr kernel: [0605d0db] FCM< ae70f6b0 ae70f724 Sep 02 13:40:49 volumiopr kernel: [0605d0dc] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [0605d0dc] FCM> ae70f6b0 0 Sep 02 13:40:49 volumiopr kernel: [0605d0dd] CMD 12 200 Sep 02 13:40:49 volumiopr kernel: [0605d0dd] REQ> ae70f6b0 0 Sep 02 13:40:49 volumiopr kernel: [0605dd45] DMA< ae70f784 1 Sep 02 13:40:49 volumiopr kernel: [0605dd45] DMA 52 10902 Sep 02 13:40:49 volumiopr kernel: [0605dd57] FDA< ae70f6b0 0 Sep 02 13:40:49 volumiopr kernel: [0605dd58] TCM< ae70f784 0 Sep 02 13:40:49 volumiopr kernel: [0605dd59] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [0605dd5d] FCM< ae70f6b0 ae70f754 Sep 02 13:40:49 volumiopr kernel: [0605dd60] RSP b00 0 Sep 02 13:40:49 volumiopr kernel: [0605dd60] FCM> ae70f6b0 ae70f754 Sep 02 13:40:49 volumiopr kernel: [0605dd61] TCM> ae70f784 0 Sep 02 13:40:49 volumiopr kernel: [0605dd62] FDA> ae70f6b0 ae70f754 Sep 02 13:40:49 volumiopr kernel: [0605dd62] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0605dd6b] TSK< ae70f6b0 0 Sep 02 13:40:49 volumiopr kernel: [0605dd9a] TSK> ae70f6b0 0 Sep 02 13:40:49 volumiopr kernel: [460d60cd] PRD< ae700584 0 Sep 02 13:40:49 volumiopr kernel: [460d60ce] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [460d60d3] PRD2 1 0 Sep 02 13:40:49 volumiopr kernel: [460d60d9] PRD3 8c1ce884 0 Sep 02 13:40:49 volumiopr kernel: [460d60d9] PDM> ae700584 0 Sep 02 13:40:49 volumiopr kernel: [460d60da] REQ< ae7004b0 10801 Sep 02 13:40:49 volumiopr kernel: [460d60da] CMD< 19 555408 Sep 02 13:40:49 volumiopr kernel: [460d60e0] CMDD 2 200 Sep 02 13:40:49 volumiopr kernel: [460d60e1] SDMA ae700584 81c4d840 Sep 02 13:40:49 volumiopr kernel: [460d60e4] FCM< ae7004b0 ae700524 Sep 02 13:40:49 volumiopr kernel: [460d60e5] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [460d60e5] FCM> ae7004b0 0 Sep 02 13:40:49 volumiopr kernel: [460d60e6] CMD 19 200 Sep 02 13:40:49 volumiopr kernel: [460d60e6] REQ> ae7004b0 0 Sep 02 13:40:49 volumiopr kernel: [060e824f] DMA< ae700584 1 Sep 02 13:40:49 volumiopr kernel: [060e8250] DMA 99 10807 Sep 02 13:40:49 volumiopr kernel: [060e8252] FDA< ae7004b0 0 Sep 02 13:40:49 volumiopr kernel: [060e8253] TCM< ae700584 0 Sep 02 13:40:49 volumiopr kernel: [060e8254] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [060e8259] TCM> ae700584 0 Sep 02 13:40:49 volumiopr kernel: [060e8259] FDA> ae7004b0 ae700554 Sep 02 13:40:49 volumiopr kernel: [060e825a] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [060fad16] IRQ< 400 0 Sep 02 13:40:49 volumiopr kernel: [060fad16] IRQB ae700554 400 Sep 02 13:40:49 volumiopr kernel: [060fad17] FCM< ae7004b0 ae700554 Sep 02 13:40:49 volumiopr kernel: [060fad18] RSP c00 0 Sep 02 13:40:49 volumiopr kernel: [060fad1a] FCM> ae7004b0 ae700554 Sep 02 13:40:49 volumiopr kernel: [060fad1a] IRQ> 0 0 Sep 02 13:40:49 volumiopr kernel: [060fad21] TSK< ae7004b0 0 Sep 02 13:40:49 volumiopr kernel: [060fad42] TSK> ae7004b0 0 Sep 02 13:40:49 volumiopr kernel: [860fad64] REQ< b8905c40 10801 Sep 02 13:40:49 volumiopr kernel: [860fad65] CMD< d aaaa0000 Sep 02 13:40:49 volumiopr kernel: [860fad6a] FCM< b8905c40 b8905ca4 Sep 02 13:40:49 volumiopr kernel: [860fad6c] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [860fad71] FCM> b8905c40 0 Sep 02 13:40:49 volumiopr kernel: [860fad71] CMD d 0 Sep 02 13:40:49 volumiopr kernel: [860fad71] REQ> b8905c40 0 Sep 02 13:40:49 volumiopr kernel: [860fad82] TSK< b8905c40 0 Sep 02 13:40:49 volumiopr kernel: [860fad89] TSK> b8905c40 0 Sep 02 13:40:49 volumiopr kernel: [860fad91] PRD< ae709584 0 Sep 02 13:40:49 volumiopr kernel: [860fad91] PRD1 81c4d840 0 Sep 02 13:40:49 volumiopr kernel: [860fad96] PRD2 1 0 Sep 02 13:40:49 volumiopr kernel: [860fad9e] PRD3 8c1c7504 0 Sep 02 13:40:49 volumiopr kernel: [860fad9e] PDM> ae709584 0 Sep 02 13:40:49 volumiopr kernel: [860fad9f] REQ< ae7094b0 10801 Sep 02 13:40:49 volumiopr kernel: [860fad9f] CMD< 19 554024 Sep 02 13:40:49 volumiopr kernel: [860fada2] CMDD 6 200 Sep 02 13:40:49 volumiopr kernel: [860fada2] SDMA ae709584 81c4d840 Sep 02 13:40:49 volumiopr kernel: [860fada5] FCM< ae7094b0 ae709524 Sep 02 13:40:49 volumiopr kernel: [860fada5] RSP 900 0 Sep 02 13:40:49 volumiopr kernel: [860fada5] FCM> ae7094b0 0 Sep 02 13:40:49 volumiopr kernel: [860fada6] CMD 19 200 Sep 02 13:40:49 volumiopr kernel: [860fada6] REQ> ae7094b0 0 Sep 02 13:40:49 volumiopr kernel: [0612888f] DMA< ae709584 20 Sep 02 13:40:49 volumiopr kernel: [0612888f] DMA 4099 10801 Sep 02 13:40:49 volumiopr kernel: [06128892] FDA< ae7094b0 0 Sep 02 13:40:49 volumiopr kernel: [06128892] TCM< ae709584 0 Sep 02 13:40:49 volumiopr kernel: [06128893] CMD< c 0 Sep 02 13:40:49 volumiopr kernel: [06128896] TCM> ae709584 0 Sep 02 13:40:49 volumiopr kernel: [06128897] FDA> ae7094b0 ae709554 Sep 02 13:40:49 volumiopr kernel: [06128897] DMA> 0 0 Sep 02 13:40:49 volumiopr kernel: [0612889f] IRQ< 440 0 Sep 02 13:40:49 volumiopr kernel: [0612889f] IRQB ae709554 440 Sep 02 13:40:49 volumiopr kernel: [061288ba] IRQ> 40 0 Sep 02 13:40:49 volumiopr kernel: [86ac33f0] TIM< 0 0 Sep 02 13:40:49 volumiopr kernel: mmc0: cmd op 25 arg 0x554024 flags 0xb5 - resp 00000900 00000000 00000000 00000000, err 0 Sep 02 13:40:49 volumiopr kernel: mmc0: data blocks 6 blksz 200 - err 0 Sep 02 13:40:49 volumiopr kernel: mmc0:>stop op 12 arg 0x0 flags 0x49d - resp 00000000 00000000 00000000 00000000, err -110 Sep 02 13:40:49 volumiopr kernel: mmc0: =========== REGISTER DUMP =========== Sep 02 13:40:49 volumiopr kernel: mmc0: SDCMD 0x0000480c Sep 02 13:40:49 volumiopr kernel: mmc0: SDARG 0x00000000 Sep 02 13:40:49 volumiopr kernel: mmc0: SDTOUT 0x017d7840 Sep 02 13:40:49 volumiopr kernel: mmc0: SDCDIV 0x00000006 Sep 02 13:40:49 volumiopr kernel: mmc0: SDRSP0 0xffffffff Sep 02 13:40:49 volumiopr kernel: mmc0: SDRSP1 0x00007f7f Sep 02 13:40:49 volumiopr kernel: mmc0: SDRSP2 0xc6400002 Sep 02 13:40:49 volumiopr kernel: mmc0: SDRSP3 0xffffffff Sep 02 13:40:49 volumiopr kernel: mmc0: SDHSTS 0x00000040 Sep 02 13:40:49 volumiopr kernel: mmc0: SDVDD 0x00000001 Sep 02 13:40:49 volumiopr kernel: mmc0: SDEDM 0x00010801 Sep 02 13:40:49 volumiopr kernel: mmc0: SDHCFG 0x0000040e Sep 02 13:40:49 volumiopr kernel: mmc0: SDHBCT 0x00000006 Sep 02 13:40:49 volumiopr kernel: mmc0: SDHBLC 0x00000001 Sep 02 13:40:49 volumiopr kernel: mmc0: =========================================== Sep 02 13:40:49 volumiopr kernel: I/O error, dev mmcblk0, sector 5592074 op 0x1:(WRITE) flags 0x0 phys_seg 2 prio class 2 Sep 02 13:40:49 volumiopr kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1147525 starting block 2796037) Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61957 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61958 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61959 Sep 02 13:40:49 volumiopr kernel: mmc0: card aaaa removed Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61960 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61961 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61962 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 61963 Sep 02 13:40:49 volumiopr kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1147560 starting block 2793490) Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 59410 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 59411 Sep 02 13:40:49 volumiopr kernel: Buffer I/O error on device mmcblk0p3, logical block 59412 Sep 02 13:40:49 volumiopr kernel: JBD2: Detected IO errors while flushing file data on mmcblk0p3-8 Sep 02 13:40:49 volumiopr kernel: Aborting journal on device mmcblk0p3-8. Sep 02 13:40:49 volumiopr kernel: JBD2: I/O error when updating journal superblock for mmcblk0p3-8. Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): ext4_journal_check_start:83: comm node: Detected aborted journal Sep 02 13:40:49 volumiopr kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock Sep 02 13:40:49 volumiopr kernel: EXT4-fs (mmcblk0p3): Remounting filesystem read-only Sep 02 13:40:49 volumiopr volumio[1561]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/plugins.json' Sep 02 13:40:49 volumiopr volumio[1561]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/f02042ea-01 /dev/disk/by-id/mmc-SU08G_0x26839612-part1 /dev/disk/by-uuid/2E0A-AE55 /dev/disk/by-path/platform-3f202000.mmc-part1 /dev/disk/by-label/boot","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"2E0A-AE55","ID_FS_UUID_ENC":"2E0A-AE55","ID_FS_VERSION":"FAT16","ID_NAME":"SU08G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"f02042ea-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"f02042ea","ID_PATH":"platform-3f202000.mmc","ID_PATH_TAG":"platform-3f202000_mmc","ID_SERIAL":"0x26839612","MAJOR":"179","MINOR":"1","PARTN":"1","SEQNUM":"1938","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4124689"} Sep 02 13:40:49 volumiopr systemd[1]: Stopped target Local File Systems. Sep 02 13:40:49 volumiopr volumio[1561]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/f02042ea-02 /dev/disk/by-label/volumio /dev/disk/by-path/platform-3f202000.mmc-part2 /dev/disk/by-id/mmc-SU08G_0x26839612-part2 /dev/disk/by-uuid/af3faec2-9654-4fec-8791-d5638a5837ad","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"af3faec2-9654-4fec-8791-d5638a5837ad","ID_FS_UUID_ENC":"af3faec2-9654-4fec-8791-d5638a5837ad","ID_FS_VERSION":"1.0","ID_NAME":"SU08G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"f02042ea-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"f02042ea","ID_PATH":"platform-3f202000.mmc","ID_PATH_TAG":"platform-3f202000_mmc","ID_SERIAL":"0x26839612","MAJOR":"179","MINOR":"2","PARTN":"2","SEQNUM":"1939","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4102127"} Sep 02 13:40:49 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr volumio[1561]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/mmc-SU08G_0x26839612-part3 /dev/disk/by-partuuid/f02042ea-03 /dev/disk/by-label/volumio_data /dev/disk/by-uuid/660717eb-e471-4727-aac1-2c263938c365 /dev/disk/by-path/platform-3f202000.mmc-part3","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/soc/3f202000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"660717eb-e471-4727-aac1-2c263938c365","ID_FS_UUID_ENC":"660717eb-e471-4727-aac1-2c263938c365","ID_FS_VERSION":"1.0","ID_NAME":"SU08G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"10055278","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"f02042ea-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"f02042ea","ID_PATH":"platform-3f202000.mmc","ID_PATH_TAG":"platform-3f202000_mmc","ID_SERIAL":"0x26839612","MAJOR":"179","MINOR":"3","PARTN":"3","SEQNUM":"1940","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4115021"} Sep 02 13:40:49 volumiopr wpa_supplicant[968]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Vodafone Homespot" auth_failures=2 duration=20 reason=CONN_FAILED Sep 02 13:40:49 volumiopr systemd[1720]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1720]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1722]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1722]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1723]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1723]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1724]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1724]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1725]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1725]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1726]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1726]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1727]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1727]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1728]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1728]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1729]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1729]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1147437: comm (umount): reading directory lblock 0 Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1730]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1730]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 920920 op 0x0:(READ) flags 0x800 phys_seg 7 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1c1ab16b: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c1ab16b] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c1ab16b] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read page, block 1c1ab16b, size 6d2d Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1731]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1731]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr volumio[1561]: An internal error occurred while serving an albumart. Details: Error: EIO: i/o error, read Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 918650 op 0x0:(READ) flags 0x800 phys_seg 6 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1c08f72a: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c08f72a] Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 949026 op 0x0:(READ) flags 0x800 phys_seg 6 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c08f72a] Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 946394 op 0x0:(READ) flags 0x800 phys_seg 12 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read page, block 1c08f72a, size 50c4 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1ce1b427: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1cf645e2: -5 Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 949072 op 0x0:(READ) flags 0x800 phys_seg 4 prio class 2 Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 946486 op 0x0:(READ) flags 0x800 phys_seg 12 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1cf6a242: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1ce26c78: -5 Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 949026 op 0x0:(READ) flags 0x800 phys_seg 6 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1cf645e2: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1cf645e2] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read page, block 1cf645e2, size 5c60 Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 946394 op 0x0:(READ) flags 0x800 phys_seg 12 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1ce1b427: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1ce1b427] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read page, block 1ce1b427, size b851 Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1732]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1732]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr volumio[1561]: An internal error occurred while serving an albumart. Details: Error: EIO: i/o error, read Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr volumio[1561]: An internal error occurred while serving an albumart. Details: Error: EIO: i/o error, read Sep 02 13:40:49 volumiopr volumio[1561]: An internal error occurred while serving an albumart. Details: Error: EIO: i/o error, read Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1733]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1733]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr kernel: I/O error, dev loop0, sector 888026 op 0x0:(READ) flags 0x800 phys_seg 32 prio class 2 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1b19b75b: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1c83f9ba: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1c4004fb: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1c846c1e: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c4004fb] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1c83f9ba: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1c83f9ba] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c4004fb] Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read page, block 1c4004fb, size 6261 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Unable to read page, block 1c83f9ba, size 7264 Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1b1bb75b: -5 Sep 02 13:40:49 volumiopr kernel: SQUASHFS error: Failed to read block 0x1b1db75b: -5 Sep 02 13:40:49 volumiopr volumio[1561]: An internal error occurred while serving an albumart. Details: Error: EIO: i/o error, read Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1734]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1734]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1735]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1735]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:49 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:49 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:49 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:49 volumiopr systemd[1736]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:49 volumiopr systemd[1736]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:50 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:50 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:50 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Sep 02 13:40:50 volumiopr systemd[1737]: boot.mount: Failed to execute command: Input/output error Sep 02 13:40:50 volumiopr systemd[1737]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Sep 02 13:40:50 volumiopr systemd[1]: Unmounting /boot... Sep 02 13:40:50 volumiopr systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Sep 02 13:40:50 volumiopr systemd[1]: Failed unmounting /boot. Sep 02 13:40:50 volumiopr systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device, but not stopping since we tried this too often recently. Sep 02 13:40:50 volumiopr systemd[1]: systemd-fsck@dev-disk-by\x2duuid-2E0A\x2dAE55.service: Succeeded. Sep 02 13:40:50 volumiopr systemd[1]: Stopped File System Check on /dev/disk/by-uuid/2E0A-AE55. Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Failed to read block 0x1b19b75b: -5 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr kernel: mmc0: host does not support reading read-only switch, assuming write-enable Sep 02 13:40:50 volumiopr kernel: mmc0: new high speed SDHC card at address aaaa Sep 02 13:40:50 volumiopr kernel: mmcblk0: mmc0:aaaa SU08G 7.40 GiB Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: mmcblk0: p1 p2 p3 Sep 02 13:40:50 volumiopr kernel: mmcblk0: mmc0:aaaa SU08G 7.40 GiB (quirks 0x00004000) Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read fragment cache entry [1c4004fb] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1c4004fb, size 6261 Sep 02 13:40:50 volumiopr volumio[1561]: An internal error occurred while serving an albumart. Details: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr volumio[1561]: Error: EIO: i/o error, read Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read data cache entry [1b19b75b] Sep 02 13:40:50 volumiopr kernel: SQUASHFS error: Unable to read page, block 1b19b75b, size 1020000 Sep 02 13:40:50 volumiopr volumio[1561]: info: Ignoring mount for partition: volumio Sep 02 13:40:50 volumiopr volumio[1561]: info: Ignoring mount for partition: volumio_data Sep 02 13:40:50 volumiopr volumio[1561]: info: Ignoring mount for partition: boot Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 02 13:40:59 volumiopr volumio[1561]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 02 13:40:59 volumiopr volumio[1561]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:40:59 volumiopr kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #1147643: lblock 0: comm node: error -5 reading directory block Sep 02 13:40:59 volumiopr volumio[1561]: Error: EIO: i/o error, scandir '/myvolumio/plugins' Sep 02 13:40:59 volumiopr volumio[1561]: at Object.readdirSync (fs.js:1021:3) Sep 02 13:40:59 volumiopr volumio[1561]: at MyVolumioPluginManager.loadMyVolumioPluginsMyMusic (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:16515) Sep 02 13:40:59 volumiopr volumio[1561]: at MyVolumioPluginManager.initMyVolumio (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:1501) Sep 02 13:40:59 volumiopr volumio[1561]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:3480 Sep 02 13:40:59 volumiopr volumio[1561]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:552:70) Sep 02 13:40:59 volumiopr volumio[1561]: at Request.self.callback (/volumio/node_modules/request/request.js:185:22) Sep 02 13:40:59 volumiopr volumio[1561]: at Request.emit (events.js:315:20) Sep 02 13:40:59 volumiopr volumio[1561]: at Request. (/volumio/node_modules/request/request.js:1154:10) Sep 02 13:40:59 volumiopr volumio[1561]: at Request.emit (events.js:315:20) Sep 02 13:40:59 volumiopr volumio[1561]: at IncomingMessage. (/volumio/node_modules/request/request.js:1076:12) { Sep 02 13:40:59 volumiopr volumio[1561]: errno: -5, Sep 02 13:40:59 volumiopr volumio[1561]: syscall: 'scandir', Sep 02 13:40:59 volumiopr volumio[1561]: code: 'EIO', Sep 02 13:40:59 volumiopr volumio[1561]: path: '/myvolumio/plugins' Sep 02 13:40:59 volumiopr volumio[1561]: } Sep 02 13:40:59 volumiopr volumio[1561]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:41:00 volumiopr sudo[1749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 13:39 Sep 02 13:41:00 volumiopr sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"