-- Logs begin at Sun 2024-04-28 11:45:44 CEST, end at Sun 2024-04-28 12:35:55 CEST. -- Apr 28 12:34:24 mimivolumio volumio[955]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpd_oled/volumio/buster/armhf Apr 28 12:34:25 mimivolumio volumio[955]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpd_oled/volumio/buster/armhf Apr 28 12:34:25 mimivolumio volumio[955]: info: Folder /tmp/plugins removed Apr 28 12:34:25 mimivolumio volumio[955]: info: Check plugin dependencies Apr 28 12:34:25 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 12:34:25 mimivolumio volumio[955]: info: Checking if plugin already exists Apr 28 12:34:25 mimivolumio volumio[955]: info: Rename folder Apr 28 12:34:25 mimivolumio volumio[955]: info: Folder /tmp/downloaded_plugin.zip removed Apr 28 12:34:25 mimivolumio volumio[955]: info: Move to category Apr 28 12:34:26 mimivolumio volumio[955]: info: Checking if install.sh is present Apr 28 12:34:26 mimivolumio volumio[955]: info: Executing install.sh Apr 28 12:34:26 mimivolumio sudo[1830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/mpd_oled/install.sh Apr 28 12:34:26 mimivolumio sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:26 mimivolumio sudo[1833]: root : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/bash mpd_oled_volumio_install_latest.sh Apr 28 12:34:26 mimivolumio sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:26 mimivolumio volumio[955]: info: Hit:1 http://archive.raspberrypi.org/debian buster InRelease Apr 28 12:34:26 mimivolumio volumio[955]: info: Hit:2 http://raspbian.raspberrypi.org/raspbian buster InRelease Apr 28 12:34:26 mimivolumio volumio[955]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Apr 28 12:34:30 mimivolumio volumio[955]: info: Reading package lists... Apr 28 12:34:31 mimivolumio volumio[955]: info: Building dependency tree... Apr 28 12:34:31 mimivolumio volumio[955]: info: Reading state information... Apr 28 12:34:31 mimivolumio volumio[955]: info: 20 packages can be upgraded. Run 'apt list --upgradable' to see them. Apr 28 12:34:31 mimivolumio volumio[955]: info: Reading package lists... Apr 28 12:34:31 mimivolumio volumio[955]: info: Building dependency tree... Apr 28 12:34:31 mimivolumio volumio[955]: info: Reading state information... Apr 28 12:34:32 mimivolumio volumio[955]: info: The following additional packages will be installed: Apr 28 12:34:32 mimivolumio volumio[955]: info: file iso-codes libapt-inst2.0 python-apt-common python3-apt python3-debian Apr 28 12:34:32 mimivolumio volumio[955]: info: Suggested packages: Apr 28 12:34:32 mimivolumio volumio[955]: info: isoquery python3-apt-dbg python-apt-doc Apr 28 12:34:32 mimivolumio volumio[955]: info: The following NEW packages will be installed: Apr 28 12:34:32 mimivolumio volumio[955]: info: file gdebi-core iso-codes libapt-inst2.0 python-apt-common python3-apt Apr 28 12:34:32 mimivolumio volumio[955]: info: python3-debian Apr 28 12:34:32 mimivolumio volumio[955]: info: 0 upgraded, 7 newly installed, 0 to remove and 22 not upgraded. Apr 28 12:34:32 mimivolumio volumio[955]: info: Need to get 3457 kB of archives. Apr 28 12:34:32 mimivolumio volumio[955]: info: After this operation, 22.9 MB of additional disk space will be used. Apr 28 12:34:32 mimivolumio volumio[955]: info: Get:1 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf file armhf 1:5.35-4+deb10u2 [65.5 kB] Apr 28 12:34:33 mimivolumio volumio[955]: info: Get:2 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf libapt-inst2.0 armhf 1.8.2.3 [200 kB] Apr 28 12:34:33 mimivolumio volumio[955]: info: Get:3 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf python-apt-common all 1.8.4.3 [96.3 kB] Apr 28 12:34:33 mimivolumio volumio[955]: info: Get:4 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf python3-apt armhf 1.8.4.3 [168 kB] Apr 28 12:34:33 mimivolumio volumio[955]: info: Get:5 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf python3-debian all 0.1.35 [78.8 kB] Apr 28 12:34:33 mimivolumio volumio[955]: info: Get:6 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf gdebi-core all 0.9.5.7+nmu3 [135 kB] Apr 28 12:34:33 mimivolumio volumio[955]: info: Get:7 http://mirrors.ircam.fr/pub/raspbian/raspbian buster/main armhf iso-codes all 4.2-1 [2713 kB] Apr 28 12:34:34 mimivolumio volumio[955]: info: Fetched 3457 kB in 1s (2641 kB/s) Apr 28 12:34:35 mimivolumio volumio[955]: info: Selecting previously unselected package file. Apr 28 12:34:38 mimivolumio volumio[955]: [620B blob data] Apr 28 12:34:38 mimivolumio volumio[955]: info: Preparing to unpack .../0-file_1%3a5.35-4+deb10u2_armhf.deb ... Apr 28 12:34:38 mimivolumio volumio[955]: info: Unpacking file (1:5.35-4+deb10u2) ... Apr 28 12:34:38 mimivolumio volumio[955]: info: Selecting previously unselected package libapt-inst2.0:armhf. Apr 28 12:34:38 mimivolumio volumio[955]: info: Preparing to unpack .../1-libapt-inst2.0_1.8.2.3_armhf.deb ... Apr 28 12:34:38 mimivolumio volumio[955]: info: Unpacking libapt-inst2.0:armhf (1.8.2.3) ... Apr 28 12:34:38 mimivolumio volumio[955]: info: Selecting previously unselected package python-apt-common. Apr 28 12:34:38 mimivolumio volumio[955]: info: Preparing to unpack .../2-python-apt-common_1.8.4.3_all.deb ... Apr 28 12:34:38 mimivolumio volumio[955]: info: Unpacking python-apt-common (1.8.4.3) ... Apr 28 12:34:39 mimivolumio volumio[955]: info: Selecting previously unselected package python3-apt. Apr 28 12:34:39 mimivolumio volumio[955]: info: Preparing to unpack .../3-python3-apt_1.8.4.3_armhf.deb ... Apr 28 12:34:39 mimivolumio volumio[955]: info: Unpacking python3-apt (1.8.4.3) ... Apr 28 12:34:39 mimivolumio volumio[955]: info: Selecting previously unselected package python3-debian. Apr 28 12:34:39 mimivolumio volumio[955]: info: Preparing to unpack .../4-python3-debian_0.1.35_all.deb ... Apr 28 12:34:39 mimivolumio volumio[955]: info: Unpacking python3-debian (0.1.35) ... Apr 28 12:34:39 mimivolumio volumio[955]: info: Selecting previously unselected package gdebi-core. Apr 28 12:34:39 mimivolumio volumio[955]: info: Preparing to unpack .../5-gdebi-core_0.9.5.7+nmu3_all.deb ... Apr 28 12:34:39 mimivolumio volumio[955]: info: Unpacking gdebi-core (0.9.5.7+nmu3) ... Apr 28 12:34:40 mimivolumio volumio[955]: info: Selecting previously unselected package iso-codes. Apr 28 12:34:40 mimivolumio volumio[955]: info: Preparing to unpack .../6-iso-codes_4.2-1_all.deb ... Apr 28 12:34:40 mimivolumio volumio[955]: info: Unpacking iso-codes (4.2-1) ... Apr 28 12:34:41 mimivolumio volumio[955]: info: Setting up file (1:5.35-4+deb10u2) ... Apr 28 12:34:41 mimivolumio volumio[955]: info: Setting up libapt-inst2.0:armhf (1.8.2.3) ... Apr 28 12:34:41 mimivolumio volumio[955]: info: Setting up python3-debian (0.1.35) ... Apr 28 12:34:41 mimivolumio volumio[955]: info: Setting up python-apt-common (1.8.4.3) ... Apr 28 12:34:41 mimivolumio volumio[955]: info: Setting up iso-codes (4.2-1) ... Apr 28 12:34:41 mimivolumio volumio[955]: info: Setting up python3-apt (1.8.4.3) ... Apr 28 12:34:42 mimivolumio volumio[955]: info: Setting up gdebi-core (0.9.5.7+nmu3) ... Apr 28 12:34:42 mimivolumio volumio[955]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Apr 28 12:34:44 mimivolumio volumio[955]: [97B blob data] Apr 28 12:34:44 mimivolumio volumio[955]: [161B blob data] Apr 28 12:34:45 mimivolumio volumio[955]: [107B blob data] Apr 28 12:34:49 mimivolumio volumio[955]: [1.8K blob data] Apr 28 12:34:49 mimivolumio volumio[955]: [620B blob data] Apr 28 12:34:49 mimivolumio volumio[955]: info: Preparing to unpack .../libfftw3-3_3.3.8-2_armhf.deb ... Apr 28 12:34:49 mimivolumio volumio[955]: info: Unpacking libfftw3-3:armhf (3.3.8-2) ... Apr 28 12:34:49 mimivolumio volumio[955]: info: Selecting previously unselected package libiniparser1:armhf. Apr 28 12:34:49 mimivolumio volumio[955]: info: Preparing to unpack .../libiniparser1_4.1-4_armhf.deb ... Apr 28 12:34:49 mimivolumio volumio[955]: info: Unpacking libiniparser1:armhf (4.1-4) ... Apr 28 12:34:49 mimivolumio volumio[955]: info: Setting up libiniparser1:armhf (4.1-4) ... Apr 28 12:34:49 mimivolumio volumio[955]: info: Setting up libfftw3-3:armhf (3.3.8-2) ... Apr 28 12:34:49 mimivolumio volumio[955]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... Apr 28 12:34:50 mimivolumio volumio[955]: info: Selecting previously unselected package mpd-oled. Apr 28 12:34:50 mimivolumio volumio[955]: info: (Reading database ... 39623 files and directories currently installed.) Apr 28 12:34:50 mimivolumio volumio[955]: info: Preparing to unpack .../mpd-oled_0.2+202306240927+aaaca86_armhf.deb ... Apr 28 12:34:50 mimivolumio volumio[955]: info: Unpacking mpd-oled (0.2+202306240927+aaaca86) ... Apr 28 12:34:51 mimivolumio volumio[955]: info: Setting up mpd-oled (0.2+202306240927+aaaca86) ... Apr 28 12:34:51 mimivolumio systemd[1]: Reloading. Apr 28 12:34:51 mimivolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 28 12:34:51 mimivolumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Apr 28 12:34:51 mimivolumio systemd[1]: Reloading. Apr 28 12:34:51 mimivolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 28 12:34:52 mimivolumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Apr 28 12:34:52 mimivolumio volumio[955]: [41B blob data] Apr 28 12:34:52 mimivolumio volumio[955]: [111B blob data] Apr 28 12:34:52 mimivolumio volumio[955]: [111B blob data] Apr 28 12:34:52 mimivolumio volumio[955]: [36B blob data] Apr 28 12:34:52 mimivolumio sudo[1833]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:52 mimivolumio sudo[2301]: root : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl disable mpd_oled Apr 28 12:34:52 mimivolumio sudo[2301]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:52 mimivolumio systemd[1]: Reloading. Apr 28 12:34:52 mimivolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 28 12:34:52 mimivolumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Apr 28 12:34:52 mimivolumio sudo[2301]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:53 mimivolumio systemd[1]: Reloading. Apr 28 12:34:53 mimivolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 28 12:34:53 mimivolumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Apr 28 12:34:53 mimivolumio systemd[1]: Reloading. Apr 28 12:34:53 mimivolumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 28 12:34:53 mimivolumio systemd[1]: /data/plugins/music_service/lms/unit/logitechmediaserver.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/logitechmediaserver.pid → /run/logitechmediaserver.pid; please update the unit file accordingly. Apr 28 12:34:54 mimivolumio volumio[955]: info: Installing i2c-tools for screen detection Apr 28 12:34:54 mimivolumio sudo[2349]: root : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/apt-get --assume-yes install i2c-tools Apr 28 12:34:54 mimivolumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:55 mimivolumio volumio[955]: info: Reading package lists... Apr 28 12:34:56 mimivolumio volumio[955]: info: Building dependency tree... Apr 28 12:34:56 mimivolumio volumio[955]: info: Reading state information... Apr 28 12:34:57 mimivolumio volumio[955]: info: i2c-tools is already the newest version (4.1-1). Apr 28 12:34:57 mimivolumio sudo[2349]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:57 mimivolumio volumio[955]: info: 0 upgraded, 0 newly installed, 0 to remove and 22 not upgraded. Apr 28 12:34:57 mimivolumio sudo[2356]: root : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/cp ./cava /usr/local/bin/mpd_oled_cava Apr 28 12:34:57 mimivolumio volumio[955]: info: Installing CAVA Apr 28 12:34:57 mimivolumio sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:57 mimivolumio sudo[2356]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:57 mimivolumio sudo[2358]: root : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/mkdir /usr/local/share/consolefonts Apr 28 12:34:57 mimivolumio sudo[2358]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:57 mimivolumio sudo[2358]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:57 mimivolumio sudo[2360]: root : TTY=unknown ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/cp ./cava.psf /usr/local/share/consolefonts Apr 28 12:34:57 mimivolumio sudo[2360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:34:57 mimivolumio sudo[2360]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:57 mimivolumio volumio[955]: info: Checking I2C busses Apr 28 12:34:57 mimivolumio volumio[955]: info: * I2C-1 bus enabled Apr 28 12:34:57 mimivolumio volumio[955]: info: * I2C-0 bus enabled Apr 28 12:34:57 mimivolumio sudo[1830]: pam_unix(sudo:session): session closed for user root Apr 28 12:34:57 mimivolumio volumio[955]: info: Install script completed Apr 28 12:34:57 mimivolumio volumio[955]: info: Adding reference to registry Apr 28 12:34:57 mimivolumio volumio[955]: info: Done installing plugin. Apr 28 12:34:57 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 28 12:34:57 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 28 12:34:57 mimivolumio volumio[955]: info: Setting I2C baudrate Apr 28 12:34:57 mimivolumio volumio[955]: info: Folder /tmp/plugins removed Apr 28 12:34:57 mimivolumio volumio[955]: info: Folder /tmp/downloaded_plugin.zip removed Apr 28 12:34:57 mimivolumio volumio[955]: info: Folder /data/temp removed Apr 28 12:34:57 mimivolumio volumio[955]: info: Enabling SPI Apr 28 12:34:57 mimivolumio volumio[955]: Plugin install end detected on script Apr 28 12:35:02 mimivolumio volumio[955]: info: Enabling plugin mpd_oled Apr 28 12:35:02 mimivolumio volumio[955]: info: Loading plugin "mpd_oled"... Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of OLED_TYPE to Select... Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of NUMBER_OF_BARS to 16 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of GAP_BETWEEN_BARS to 1 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of FRAME_RATE to 50 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of SCROLL_RATE to 8 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of SCROLL_DELAY to 5 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of CLOCK_FORMAT to 24h leading 0 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of PAUSE_SCREEN_TYPE to Play (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of INVERT_DISPLAY_PERIOD to Disabled (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of I2C_ADDRESS to Default for display Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of I2C_BUS to /dev/i2c-1 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of SPI_RESET_GPIO_NUMBER to 25 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of SPI_DC_GPIO_NUMBER to 24 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of SPI_CS to 0 (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Translated default label of DATE_FORMAT to DD-MM-YYYY (default) Apr 28 12:35:02 mimivolumio volumio[955]: info: Preparing to generate the ALSA configuration file Apr 28 12:35:02 mimivolumio volumio[955]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 28 12:35:02 mimivolumio volumio[955]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 28 12:35:02 mimivolumio volumio[955]: info: Reading ALSA contributions from plugins. Apr 28 12:35:02 mimivolumio volumio[955]: info: Asound.conf file written Apr 28 12:35:02 mimivolumio sudo[2369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 28 12:35:02 mimivolumio sudo[2369]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:02 mimivolumio sudo[2369]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:02 mimivolumio volumio[955]: info: Output device has changed, restarting MPD Apr 28 12:35:02 mimivolumio sudo[2374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 12:35:02 mimivolumio sudo[2374]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:02 mimivolumio sudo[2374]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:02 mimivolumio volumio[955]: info: Output device has changed, restarting Shairport Sync Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:02 mimivolumio sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 12:35:02 mimivolumio sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:02 mimivolumio systemd[1]: Stopping Music Player Daemon... Apr 28 12:35:02 mimivolumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 12:35:02 mimivolumio volumio[955]: info: PLUGIN START: mpd_oled Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 28 12:35:02 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Apr 28 12:35:02 mimivolumio volumio[955]: info: Preparing to generate the ALSA configuration file Apr 28 12:35:02 mimivolumio volumio[955]: info: Done. Apr 28 12:35:02 mimivolumio volumio[955]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 28 12:35:02 mimivolumio volumio[955]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 28 12:35:02 mimivolumio volumio[955]: info: Reading ALSA contributions from plugins. Apr 28 12:35:02 mimivolumio volumio[955]: info: MPD Permissions set Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:02 mimivolumio systemd[1]: mpd.service: Succeeded. Apr 28 12:35:02 mimivolumio systemd[1]: Stopped Music Player Daemon. Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:02 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: Starting Shairport Sync Apr 28 12:35:03 mimivolumio volumio[955]: info: Asound.conf file unchanged, so no further update is needed Apr 28 12:35:03 mimivolumio volumio[955]: info: Output device has changed, restarting MPD Apr 28 12:35:03 mimivolumio sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 12:35:03 mimivolumio sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:03 mimivolumio systemd[1]: Starting Music Player Daemon... Apr 28 12:35:03 mimivolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 12:35:03 mimivolumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 12:35:03 mimivolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:03 mimivolumio volumio[955]: info: Output device has changed, restarting Shairport Sync Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:03 mimivolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:03 mimivolumio sudo[2393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 12:35:03 mimivolumio sudo[2393]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:03 mimivolumio sudo[2390]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:03 mimivolumio sudo[2393]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:03 mimivolumio sudo[2396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 12:35:03 mimivolumio sudo[2396]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:03 mimivolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 28 12:35:03 mimivolumio systemd[1]: mpd.service: Succeeded. Apr 28 12:35:03 mimivolumio systemd[1]: Stopped Music Player Daemon. Apr 28 12:35:03 mimivolumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 12:35:03 mimivolumio volumio[955]: info: MPD Permissions set Apr 28 12:35:03 mimivolumio volumio[955]: info: Shairport-Sync Started Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:03 mimivolumio volumio[955]: info: Starting Shairport Sync Apr 28 12:35:03 mimivolumio systemd[1]: Starting Music Player Daemon... Apr 28 12:35:03 mimivolumio sudo[2412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 12:35:03 mimivolumio sudo[2412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:03 mimivolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 12:35:03 mimivolumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 12:35:03 mimivolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:03 mimivolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:03 mimivolumio sudo[2412]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:03 mimivolumio volumio[955]: info: Shairport-Sync Started Apr 28 12:35:03 mimivolumio mpd[2405]: Apr 28 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 12:35:03 mimivolumio systemd[1]: Started Music Player Daemon. Apr 28 12:35:03 mimivolumio sudo[2377]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:03 mimivolumio sudo[2396]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:03 mimivolumio volumio[955]: error: MPD error: The expression evaluated to a falsy value: Apr 28 12:35:03 mimivolumio volumio[955]: assert.ok(self.idling) Apr 28 12:35:03 mimivolumio volumio[955]: error: The expression evaluated to a falsy value: Apr 28 12:35:03 mimivolumio volumio[955]: assert.ok(self.idling) Apr 28 12:35:03 mimivolumio volumio[955]: error: updateQueue error: null Apr 28 12:35:13 mimivolumio volumio[955]: info: Enabling plugin mpd_oled Apr 28 12:35:13 mimivolumio volumio[955]: info: Loading plugin "mpd_oled"... Apr 28 12:35:13 mimivolumio volumio[955]: info: Applying required configuration parameters for plugin mpd_oled Apr 28 12:35:13 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Apr 28 12:35:13 mimivolumio volumio[955]: info: Preparing to generate the ALSA configuration file Apr 28 12:35:13 mimivolumio volumio[955]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 28 12:35:13 mimivolumio volumio[955]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 28 12:35:13 mimivolumio volumio[955]: info: Reading ALSA contributions from plugins. Apr 28 12:35:13 mimivolumio volumio[955]: info: Asound.conf file unchanged, so no further update is needed Apr 28 12:35:13 mimivolumio volumio[955]: info: Output device has changed, restarting MPD Apr 28 12:35:13 mimivolumio sudo[2422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 12:35:13 mimivolumio sudo[2422]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:13 mimivolumio sudo[2422]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:13 mimivolumio volumio[955]: info: Output device has changed, restarting Shairport Sync Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:13 mimivolumio sudo[2425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 12:35:13 mimivolumio sudo[2425]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:13 mimivolumio systemd[1]: Stopping Music Player Daemon... Apr 28 12:35:13 mimivolumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 12:35:13 mimivolumio systemd[1]: mpd.service: Succeeded. Apr 28 12:35:13 mimivolumio systemd[1]: Stopped Music Player Daemon. Apr 28 12:35:13 mimivolumio volumio[955]: info: PLUGIN START: mpd_oled Apr 28 12:35:13 mimivolumio volumio[955]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Apr 28 12:35:13 mimivolumio volumio[955]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Apr 28 12:35:13 mimivolumio volumio[955]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 28 12:35:13 mimivolumio volumio[955]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Apr 28 12:35:13 mimivolumio volumio[955]: info: Preparing to generate the ALSA configuration file Apr 28 12:35:13 mimivolumio volumio[955]: info: Done. Apr 28 12:35:13 mimivolumio volumio[955]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 28 12:35:13 mimivolumio systemd[1]: Starting Music Player Daemon... Apr 28 12:35:13 mimivolumio volumio[955]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Apr 28 12:35:13 mimivolumio volumio[955]: info: Reading ALSA contributions from plugins. Apr 28 12:35:13 mimivolumio volumio[955]: info: MPD Permissions set Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: Starting Shairport Sync Apr 28 12:35:13 mimivolumio volumio[955]: info: Asound.conf file unchanged, so no further update is needed Apr 28 12:35:13 mimivolumio volumio[955]: info: Output device has changed, restarting MPD Apr 28 12:35:13 mimivolumio sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 12:35:13 mimivolumio sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:13 mimivolumio sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 12:35:13 mimivolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 12:35:13 mimivolumio sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:13 mimivolumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 12:35:13 mimivolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:13 mimivolumio sudo[2441]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:13 mimivolumio volumio[955]: info: Output device has changed, restarting Shairport Sync Apr 28 12:35:13 mimivolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:13 mimivolumio sudo[2438]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:13 mimivolumio sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 12:35:13 mimivolumio sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:13 mimivolumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 28 12:35:13 mimivolumio systemd[1]: mpd.service: Succeeded. Apr 28 12:35:13 mimivolumio systemd[1]: Stopped Music Player Daemon. Apr 28 12:35:13 mimivolumio systemd[1]: Starting Music Player Daemon... Apr 28 12:35:13 mimivolumio volumio[955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 28 12:35:13 mimivolumio volumio[955]: info: MPD Permissions set Apr 28 12:35:13 mimivolumio volumio[955]: info: Shairport-Sync Started Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:13 mimivolumio volumio[955]: info: Starting Shairport Sync Apr 28 12:35:13 mimivolumio sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 28 12:35:13 mimivolumio sudo[2458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:13 mimivolumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 28 12:35:13 mimivolumio systemd[1]: shairport-sync.service: Succeeded. Apr 28 12:35:13 mimivolumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:13 mimivolumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 28 12:35:13 mimivolumio sudo[2458]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:13 mimivolumio volumio[955]: info: Shairport-Sync Started Apr 28 12:35:14 mimivolumio mpd[2452]: Apr 28 12:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 12:35:14 mimivolumio systemd[1]: Started Music Player Daemon. Apr 28 12:35:14 mimivolumio sudo[2425]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:14 mimivolumio sudo[2446]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:14 mimivolumio volumio[955]: error: MPD error: The expression evaluated to a falsy value: Apr 28 12:35:14 mimivolumio volumio[955]: assert.ok(self.idling) Apr 28 12:35:14 mimivolumio volumio[955]: error: The expression evaluated to a falsy value: Apr 28 12:35:14 mimivolumio volumio[955]: assert.ok(self.idling) Apr 28 12:35:14 mimivolumio volumio[955]: error: updateQueue error: null Apr 28 12:35:20 mimivolumio volumio[955]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Apr 28 12:35:20 mimivolumio volumio[955]: info: PLUGIN onReboot : networkfs Apr 28 12:35:20 mimivolumio sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/MusicNAS Apr 28 12:35:20 mimivolumio sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:20 mimivolumio sudo[2471]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:20 mimivolumio volumio[955]: umount: /mnt/NAS/MusicNAS: not mounted. Apr 28 12:35:20 mimivolumio volumio[955]: error: Cannot umount share MusicNAS : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/MusicNAS Apr 28 12:35:20 mimivolumio volumio[955]: umount: /mnt/NAS/MusicNAS: not mounted. Apr 28 12:35:20 mimivolumio volumio[955]: info: PLUGIN onReboot : audiophonicsonoff Apr 28 12:35:20 mimivolumio volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 12:35:20 mimivolumio volumio[955]: TypeError: Cannot read property 'writeSync' of undefined Apr 28 12:35:20 mimivolumio volumio[955]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Apr 28 12:35:20 mimivolumio volumio[955]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Apr 28 12:35:20 mimivolumio volumio[955]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Apr 28 12:35:20 mimivolumio volumio[955]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 28 12:35:20 mimivolumio volumio[955]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 28 12:35:20 mimivolumio volumio[955]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Apr 28 12:35:20 mimivolumio volumio[955]: at CoreCommandRouter.reboot (/volumio/app/index.js:1325:22) Apr 28 12:35:20 mimivolumio volumio[955]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:836:33) Apr 28 12:35:20 mimivolumio volumio[955]: at Socket.emit (events.js:315:20) Apr 28 12:35:20 mimivolumio volumio[955]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 28 12:35:20 mimivolumio volumio[955]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 28 12:35:20 mimivolumio volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 12:35:20 mimivolumio sudo[2482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 12:34 Apr 28 12:35:20 mimivolumio sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:20 mimivolumio sudo[2482]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:21 mimivolumio volumio-remote-updater[554]: [2024-04-28 12:35:21] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 12:35:21 mimivolumio volumio-remote-updater[554]: [2024-04-28 12:35:21] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 12:35:21 mimivolumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 12:35:21 mimivolumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 12:35:21 mimivolumio systemd[1]: Started dynamicswap service. Apr 28 12:35:21 mimivolumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 12:35:21 mimivolumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 12:35:21 mimivolumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 28 12:35:21 mimivolumio systemd[1]: Stopped Volumio Backend Module. Apr 28 12:35:21 mimivolumio systemd[1]: Started Volumio Backend Module. Apr 28 12:35:21 mimivolumio systemd[1]: Started dynamicswap service. Apr 28 12:35:21 mimivolumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 12:35:22 mimivolumio volumio[2497]: info: ------------------------------------------- Apr 28 12:35:22 mimivolumio volumio[2497]: info: ----- Volumio3 ---- Apr 28 12:35:22 mimivolumio volumio[2497]: info: ------------------------------------------- Apr 28 12:35:22 mimivolumio volumio[2497]: info: ----- System startup ---- Apr 28 12:35:22 mimivolumio volumio[2497]: info: ------------------------------------------- Apr 28 12:35:22 mimivolumio volumio[2497]: info: MYVOLUMIO Environment detected Apr 28 12:35:22 mimivolumio volumio[2497]: info: Plugin folders cleanup Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning into folder /volumio/app/plugins/ Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category audio_interface Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category miscellanea Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category music_service Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category plugins.json Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category system_controller Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category user_interface Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning into folder /data/plugins/ Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category audio_interface Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category music_service Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category system_controller Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category system_hardware Apr 28 12:35:22 mimivolumio volumio[2497]: info: Scanning category user_interface Apr 28 12:35:22 mimivolumio volumio[2497]: info: Plugin folders cleanup completed Apr 28 12:35:22 mimivolumio volumio[2497]: info: ------------------------------------------- Apr 28 12:35:22 mimivolumio volumio[2497]: info: ----- Core plugins startup ---- Apr 28 12:35:22 mimivolumio volumio[2497]: info: ------------------------------------------- Apr 28 12:35:22 mimivolumio volumio[2497]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 12:35:22 mimivolumio volumio[2497]: info: Adding plugin upnp to MyMusic Plugins Apr 28 12:35:22 mimivolumio volumio[2497]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 12:35:22 mimivolumio volumio[2497]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 12:35:22 mimivolumio volumio[2497]: info: Loading plugins from folder /data/plugins/ Apr 28 12:35:22 mimivolumio volumio[2497]: info: Loading plugin "system"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "appearance"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "network"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Refreshing Cached IP Addresses Apr 28 12:35:23 mimivolumio sudo[2524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 12:35:23 mimivolumio sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:23 mimivolumio sudo[2524]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:23 mimivolumio sudo[2526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 12:35:23 mimivolumio sudo[2526]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:23 mimivolumio sudo[2526]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:23 mimivolumio sudo[2530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 28 12:35:23 mimivolumio sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "services"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "alsa_controller"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "wizard"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "networkfs"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Starting Udev Watcher for removable devices Apr 28 12:35:23 mimivolumio sudo[2537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.1.3:5000//volume1/music/Flac_music /mnt/NAS/MusicNAS Apr 28 12:35:23 mimivolumio sudo[2537]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:23 mimivolumio volumio[2497]: info: Ignoring mount for partition: boot Apr 28 12:35:23 mimivolumio volumio[2497]: info: Ignoring mount for partition: volumio Apr 28 12:35:23 mimivolumio volumio[2497]: info: Ignoring mount for partition: volumio_data Apr 28 12:35:23 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "volumio_command_line_client"... Apr 28 12:35:23 mimivolumio sudo[2537]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "upnp"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: [1714300523814] Starting Upmpd Daemon Apr 28 12:35:23 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "my_music"... Apr 28 12:35:23 mimivolumio volumio[2497]: info: Loading plugin "mpd"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "upnp_browser"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "alarm-clock"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "airplay_emulation"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Starting Shairport Sync Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "last_100"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "webradio"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "i2s_dacs"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "volumiodiscovery"... Apr 28 12:35:24 mimivolumio volumio[2497]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 12:35:24 mimivolumio node[2497]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 12:35:24 mimivolumio volumio[2497]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 12:35:24 mimivolumio node[2497]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 12:35:24 mimivolumio volumio[2497]: *** WARNING *** For more information see Apr 28 12:35:24 mimivolumio node[2497]: *** WARNING *** For more information see Apr 28 12:35:24 mimivolumio volumio[2497]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 12:35:24 mimivolumio node[2497]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 12:35:24 mimivolumio volumio[2497]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 12:35:24 mimivolumio node[2497]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 12:35:24 mimivolumio volumio[2497]: *** WARNING *** For more information see Apr 28 12:35:24 mimivolumio node[2497]: *** WARNING *** For more information see Apr 28 12:35:24 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 12:35:24 mimivolumio volumio[2497]: info: Discovery: Started advertising with name: mimiVolumio Apr 28 12:35:24 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "gpio-buttons"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: GPIO-Buttons initialized Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "outputs"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "albumart"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Plugin example_plugin is not enabled Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "inputs"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "updater_comm"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Plugin mpdemulation is not enabled Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "rest_api"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "websocket"... Apr 28 12:35:24 mimivolumio volumio[2497]: info: Loading plugin "fusiondsp"... Apr 28 12:35:24 mimivolumio volumio[2497]: Forking 3 albumart workers Apr 28 12:35:25 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin fusiondsp Apr 28 12:35:25 mimivolumio volumio[2497]: info: Loading plugin "lms"... Apr 28 12:35:25 mimivolumio volumio[2497]: info: Loading plugin "radio_paradise"... Apr 28 12:35:25 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin radio_paradise Apr 28 12:35:25 mimivolumio volumio[2497]: info: [1714300525395] [RadioParadise] API delay: 5 Apr 28 12:35:25 mimivolumio volumio[2497]: info: Loading plugin "audiophonicsonoff"... Apr 28 12:35:25 mimivolumio volumio[2497]: Starting albumart workers Apr 28 12:35:25 mimivolumio volumio[2497]: Starting albumart workers Apr 28 12:35:25 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin audiophonicsonoff Apr 28 12:35:25 mimivolumio volumio[2497]: info: Audiophonics on/off initiated Apr 28 12:35:25 mimivolumio volumio[2497]: info: Loading plugin "audiophonics_evo_sabre"... Apr 28 12:35:25 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Apr 28 12:35:25 mimivolumio volumio[2497]: Starting albumart workers Apr 28 12:35:25 mimivolumio volumio[2497]: info: Loading plugin "gpio_control"... Apr 28 12:35:26 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin gpio_control Apr 28 12:35:26 mimivolumio volumio[2497]: info: [GPIO_Control] Detected Raspberry Pi 4 Model B Rev 1.5 Apr 28 12:35:26 mimivolumio volumio[2497]: info: [GPIO_Control] 40 GPIOs: true Apr 28 12:35:26 mimivolumio volumio[2497]: info: [GPIO_Control] Initialized Apr 28 12:35:26 mimivolumio volumio[2497]: info: Loading plugin "mpd_oled"... Apr 28 12:35:26 mimivolumio volumio[2497]: info: Applying required configuration parameters for plugin mpd_oled Apr 28 12:35:26 mimivolumio volumio[2497]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Apr 28 12:35:26 mimivolumio volumio[2497]: info: Loading plugin "Systeminfo"... Apr 28 12:35:26 mimivolumio volumio-remote-updater[554]: [2024-04-28 12:35:26] [connect] Successful connection Apr 28 12:35:26 mimivolumio volumio[2497]: info: Loading i18n strings for locale fr Apr 28 12:35:26 mimivolumio volumio[2497]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Apr 28 12:35:26 mimivolumio volumio[2497]: Updating browse sources language Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::initPlayerControls Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: Express server listening on port 3000 Apr 28 12:35:26 mimivolumio volumio[2497]: [Metrics] WebUI: 4s 299.05ms Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::resetVolumioState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::getcurrentVolume Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 12:35:26 mimivolumio volumio[2497]: info: Cannot mount NAS MusicNAS at system boot, trial number 1 ,retrying in 5 seconds Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.30 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 1 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.30 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.30 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 4 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.30 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 5 Apr 28 12:35:26 mimivolumio volumio-remote-updater[554]: [2024-04-28 12:35:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714300526 101 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 6 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to 192.168.1.31 from 192.168.1.30 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 7 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 28 12:35:26 mimivolumio volumio[2497]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 12:35:26 mimivolumio volumio[2497]: info: Reloading queue from file Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::setRepeat null single undefined Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::pushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioPushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::setRandom null Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::pushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioPushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: Setting Device type: Raspberry PI Apr 28 12:35:26 mimivolumio volumio[2497]: info: VolumeController:: Volume=100 Mute =true Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::pushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioPushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::updateTrackBlock Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrackBlock Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioGetState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioGetVisibleSources Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioGetQueue Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::getQueue Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getQueue Apr 28 12:35:26 mimivolumio volumio[2497]: info: Listing playlists Apr 28 12:35:26 mimivolumio volumio[2497]: info: VolumeController:: Volume=100 Mute =true Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreStateMachine::pushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioPushState Apr 28 12:35:26 mimivolumio volumio[2497]: info: Discovery: adding b0b826ce-0803-4342-a4e8-0840ab20a6c3 Apr 28 12:35:26 mimivolumio volumio[2497]: info: Discovery: Found device mimiVolumio Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioGetState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:26 mimivolumio volumio[2497]: info: Discovery: this is already registered, b0b826ce-0803-4342-a4e8-0840ab20a6c3 Apr 28 12:35:26 mimivolumio volumio[2497]: info: Discovery: Found device mimiVolumio Apr 28 12:35:26 mimivolumio volumio[2497]: info: CoreCommandRouter::volumioGetState Apr 28 12:35:26 mimivolumio volumio[2497]: info: CorePlayQueue::getTrack 0 Apr 28 12:35:27 mimivolumio sudo[2530]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:31 mimivolumio sudo[2659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.1.3:5000//volume1/music/Flac_music /mnt/NAS/MusicNAS Apr 28 12:35:31 mimivolumio sudo[2659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:31 mimivolumio sudo[2659]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:31 mimivolumio volumio[2497]: info: Cannot mount NAS MusicNAS at system boot, trial number 2 ,retrying in 5 seconds Apr 28 12:35:33 mimivolumio sudo[2671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 12:35:33 mimivolumio sudo[2671]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:33 mimivolumio sudo[2671]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:33 mimivolumio sudo[2674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 12:35:33 mimivolumio sudo[2674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:33 mimivolumio sudo[2674]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:33 mimivolumio sudo[2677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 28 12:35:33 mimivolumio sudo[2677]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:33 mimivolumio sudo[2677]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:33 mimivolumio volumio[2497]: info: Upmpdcli Daemon Started Apr 28 12:35:36 mimivolumio sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.1.3:5000//volume1/music/Flac_music /mnt/NAS/MusicNAS Apr 28 12:35:36 mimivolumio sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:36 mimivolumio sudo[2680]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:36 mimivolumio volumio[2497]: info: Cannot mount NAS MusicNAS at system boot, trial number 3 ,retrying in 5 seconds Apr 28 12:35:41 mimivolumio sudo[2685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.1.3:5000//volume1/music/Flac_music /mnt/NAS/MusicNAS Apr 28 12:35:41 mimivolumio sudo[2685]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:41 mimivolumio sudo[2685]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:41 mimivolumio volumio[2497]: info: Cannot mount NAS MusicNAS at system boot, trial number 4 ,retrying in 5 seconds Apr 28 12:35:41 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 12:35:41 mimivolumio volumio[2497]: info: CURURI: music-library Apr 28 12:35:41 mimivolumio volumio[2497]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 28 12:35:46 mimivolumio sudo[2691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.1.3:5000//volume1/music/Flac_music /mnt/NAS/MusicNAS Apr 28 12:35:46 mimivolumio sudo[2691]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 12:35:46 mimivolumio sudo[2691]: pam_unix(sudo:session): session closed for user root Apr 28 12:35:46 mimivolumio volumio[2497]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Apr 28 12:35:51 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 12:35:51 mimivolumio volumio[2497]: info: CURURI: playlists Apr 28 12:35:51 mimivolumio volumio[2497]: info: Listing playlists Apr 28 12:35:51 mimivolumio volumio[2497]: info: Preload queue cleared Apr 28 12:35:51 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 12:35:51 mimivolumio volumio[2497]: info: CURURI: music-library Apr 28 12:35:51 mimivolumio volumio[2497]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 28 12:35:53 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 12:35:53 mimivolumio volumio[2497]: info: CURURI: artists:// Apr 28 12:35:53 mimivolumio volumio[2497]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'sendCommand' of undefined Apr 28 12:35:55 mimivolumio volumio[2497]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 28 12:35:55 mimivolumio volumio[2497]: info: CURURI: albums:// Apr 28 12:35:55 mimivolumio volumio[2497]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 12:35:55 mimivolumio volumio[2497]: TypeError: Cannot read property 'sendCommand' of undefined Apr 28 12:35:55 mimivolumio volumio[2497]: at /volumio/app/plugins/music_service/mpd/index.js:2986:22 Apr 28 12:35:55 mimivolumio volumio[2497]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 28 12:35:55 mimivolumio volumio[2497]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 12:35:55 mimivolumio sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-28 12:34 Apr 28 12:35:55 mimivolumio sudo[2704]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"