Dec 11 21:29:27 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 21:29:35 volumiomirka volumio[1485]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpd_oled/volumio/bookworm/armhf Dec 11 21:29:47 volumiomirka volumio[1485]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/mpd_oled/volumio/bookworm/armhf Dec 11 21:29:47 volumiomirka volumio[1485]: info: Folder /tmp/plugins removed Dec 11 21:29:47 volumiomirka volumio[1485]: info: Check plugin dependencies Dec 11 21:29:47 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 21:29:47 volumiomirka volumio[1485]: info: Checking if plugin already exists Dec 11 21:29:47 volumiomirka volumio[1485]: info: Rename folder Dec 11 21:29:47 volumiomirka volumio[1485]: info: Folder /tmp/downloaded_plugin.zip removed Dec 11 21:29:47 volumiomirka volumio[1485]: info: Move to category Dec 11 21:29:48 volumiomirka volumio[1485]: info: Checking if install.sh is present Dec 11 21:29:48 volumiomirka volumio[1485]: info: Executing install.sh Dec 11 21:29:48 volumiomirka sudo[2776]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/mpd_oled/install.sh Dec 11 21:29:48 volumiomirka sudo[2776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:29:48 volumiomirka sudo[2780]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/systemctl disable mpd_oled Dec 11 21:29:48 volumiomirka sudo[2780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:29:48 volumiomirka sudo[2780]: pam_unix(sudo:session): session closed for user root Dec 11 21:29:48 volumiomirka systemd[1]: Reloading. Dec 11 21:29:48 volumiomirka systemd[1]: Reloading. Dec 11 21:29:49 volumiomirka volumio[1485]: info: Installing i2c-tools for screen detection Dec 11 21:29:49 volumiomirka sudo[2836]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/apt update Dec 11 21:29:49 volumiomirka sudo[2836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:29:49 volumiomirka volumio[1485]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Dec 11 21:29:49 volumiomirka volumio[1485]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Dec 11 21:29:49 volumiomirka volumio[1485]: info: Hit:3 https://deb.nodesource.com/node_20.x nodistro InRelease Dec 11 21:29:51 volumiomirka volumio[1485]: info: Reading package lists... Dec 11 21:29:52 volumiomirka volumio[1485]: info: Building dependency tree... Dec 11 21:29:52 volumiomirka volumio[1485]: info: Reading state information... Dec 11 21:29:52 volumiomirka sudo[2836]: pam_unix(sudo:session): session closed for user root Dec 11 21:29:52 volumiomirka volumio[1485]: info: 2 packages can be upgraded. Run 'apt list --upgradable' to see them. Dec 11 21:29:52 volumiomirka sudo[3016]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/apt-get --assume-yes install --no-install-recommends i2c-tools cava Dec 11 21:29:52 volumiomirka sudo[3016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:29:52 volumiomirka volumio[1485]: info: Reading package lists... Dec 11 21:29:52 volumiomirka volumio[1485]: info: Building dependency tree... Dec 11 21:29:52 volumiomirka volumio[1485]: info: Reading state information... Dec 11 21:29:53 volumiomirka volumio[1485]: info: i2c-tools is already the newest version (4.3-2+b2). Dec 11 21:29:53 volumiomirka volumio[1485]: info: The following additional packages will be installed: Dec 11 21:29:53 volumiomirka volumio[1485]: info: libfftw3-double3 libiniparser1 Dec 11 21:29:53 volumiomirka volumio[1485]: info: Suggested packages: Dec 11 21:29:53 volumiomirka volumio[1485]: info: pulseaudio libfftw3-bin libfftw3-dev Dec 11 21:29:53 volumiomirka volumio[1485]: info: The following NEW packages will be installed: Dec 11 21:29:53 volumiomirka volumio[1485]: info: cava libfftw3-double3 libiniparser1 Dec 11 21:29:53 volumiomirka volumio[1485]: info: 0 upgraded, 3 newly installed, 0 to remove and 2 not upgraded. Dec 11 21:29:53 volumiomirka volumio[1485]: info: Need to get 411 kB of archives. Dec 11 21:29:53 volumiomirka volumio[1485]: info: After this operation, 1150 kB of additional disk space will be used. Dec 11 21:29:53 volumiomirka volumio[1485]: info: Get:1 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libfftw3-double3 armhf 3.3.10-1 [325 kB] Dec 11 21:29:55 volumiomirka volumio[1485]: info: Get:2 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libiniparser1 armhf 4.1-6 [8900 B] Dec 11 21:29:55 volumiomirka volumio[1485]: info: Get:3 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf cava armhf 0.7.4-1 [77.5 kB] Dec 11 21:29:56 volumiomirka volumio[1485]: info: Fetched 411 kB in 3s (160 kB/s) Dec 11 21:29:56 volumiomirka volumio[1485]: info: Selecting previously unselected package libfftw3-double3:armhf. Dec 11 21:29:57 volumiomirka volumio[1485]: [620B blob data] Dec 11 21:29:57 volumiomirka volumio[1485]: info: Preparing to unpack .../libfftw3-double3_3.3.10-1_armhf.deb ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Selecting previously unselected package libiniparser1:armhf. Dec 11 21:29:57 volumiomirka volumio[1485]: info: Preparing to unpack .../libiniparser1_4.1-6_armhf.deb ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Unpacking libiniparser1:armhf (4.1-6) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Selecting previously unselected package cava. Dec 11 21:29:57 volumiomirka volumio[1485]: info: Preparing to unpack .../cava_0.7.4-1_armhf.deb ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Unpacking cava (0.7.4-1) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Setting up libiniparser1:armhf (4.1-6) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Setting up cava (0.7.4-1) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Processing triggers for man-db (2.11.2-2) ... Dec 11 21:29:57 volumiomirka volumio[1485]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 11 21:29:58 volumiomirka sudo[3016]: pam_unix(sudo:session): session closed for user root Dec 11 21:29:58 volumiomirka volumio[1485]: info: Installing mpd_oled Dec 11 21:29:58 volumiomirka sudo[3092]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cp -p /data/plugins/system_hardware/mpd_oled/mpd_oled /usr/local/bin/mpd_oled Dec 11 21:29:58 volumiomirka sudo[3092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:29:58 volumiomirka sudo[3092]: pam_unix(sudo:session): session closed for user root Dec 11 21:29:58 volumiomirka sudo[3094]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/chmod +x /usr/local/bin/mpd_oled Dec 11 21:29:58 volumiomirka sudo[3094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:29:58 volumiomirka sudo[3094]: pam_unix(sudo:session): session closed for user root Dec 11 21:29:58 volumiomirka volumio[1485]: info: Checking I2C busses Dec 11 21:29:58 volumiomirka volumio[1485]: info: * I2C-1 bus enabled Dec 11 21:29:58 volumiomirka sudo[2776]: pam_unix(sudo:session): session closed for user root Dec 11 21:29:58 volumiomirka volumio[1485]: info: * I2C-0 bus enabled Dec 11 21:29:58 volumiomirka volumio[1485]: info: Install script completed Dec 11 21:29:58 volumiomirka volumio[1485]: info: Adding reference to registry Dec 11 21:29:58 volumiomirka volumio[1485]: info: Done installing plugin. Dec 11 21:29:58 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 21:29:58 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 21:29:58 volumiomirka volumio[1485]: info: Setting I2C baudrate Dec 11 21:29:58 volumiomirka volumio[1485]: info: Enabling SPI Dec 11 21:29:58 volumiomirka volumio[1485]: Plugin install end detected on script Dec 11 21:29:58 volumiomirka volumio[1485]: info: Folder /tmp/plugins removed Dec 11 21:29:58 volumiomirka volumio[1485]: info: Folder /tmp/downloaded_plugin.zip removed Dec 11 21:29:58 volumiomirka volumio[1485]: info: Folder /data/temp removed Dec 11 21:30:00 volumiomirka volumio[1485]: info: Enabling plugin mpd_oled Dec 11 21:30:00 volumiomirka volumio[1485]: info: Loading plugin "mpd_oled"... Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of OLED_TYPE to Select... Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of NUMBER_OF_BARS to 16 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of GAP_BETWEEN_BARS to 1 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of FRAME_RATE to 50 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of SCROLL_RATE to 8 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of SCROLL_DELAY to 5 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of CLOCK_FORMAT to 24h leading 0 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of PAUSE_SCREEN_TYPE to Play (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of INVERT_DISPLAY_PERIOD to Disabled (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of I2C_ADDRESS to Default for display Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of I2C_BUS to /dev/i2c-1 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of SPI_RESET_GPIO_NUMBER to 25 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of SPI_DC_GPIO_NUMBER to 24 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of SPI_CS to 0 (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Translated default label of DATE_FORMAT to DD-MM-YYYY (default) Dec 11 21:30:00 volumiomirka volumio[1485]: info: Preparing to generate the ALSA configuration file Dec 11 21:30:00 volumiomirka volumio[1485]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 11 21:30:00 volumiomirka volumio[1485]: info: Reading ALSA contributions from plugins. Dec 11 21:30:00 volumiomirka volumio[1485]: info: Asound.conf file written Dec 11 21:30:00 volumiomirka sudo[3124]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 21:30:00 volumiomirka sudo[3124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:00 volumiomirka sudo[3124]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:00 volumiomirka volumio[1485]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 21:30:00 volumiomirka volumio[1485]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 21:30:00 volumiomirka volumio[1485]: info: Output device has changed, restarting MPD Dec 11 21:30:00 volumiomirka volumio[1485]: info: Output device has changed, restarting Shairport Sync Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 21:30:00 volumiomirka sudo[3130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 21:30:00 volumiomirka sudo[3130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:00 volumiomirka sudo[3130]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:00 volumiomirka sudo[3132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 21:30:00 volumiomirka sudo[3132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:00 volumiomirka volumio[1485]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 21:30:00 volumiomirka volumio[1485]: info: PLUGIN START: mpd_oled Dec 11 21:30:00 volumiomirka systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 11 21:30:00 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Dec 11 21:30:00 volumiomirka volumio[1485]: info: Preparing to generate the ALSA configuration file Dec 11 21:30:00 volumiomirka volumio[1485]: info: Done. Dec 11 21:30:00 volumiomirka volumio[1485]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 11 21:30:00 volumiomirka volumio[1485]: info: Reading ALSA contributions from plugins. Dec 11 21:30:00 volumiomirka volumio[1485]: info: MPD Permissions set Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: Starting Shairport Sync Dec 11 21:30:00 volumiomirka volumio[1485]: info: Asound.conf file unchanged, so no further update is needed Dec 11 21:30:00 volumiomirka volumio[1485]: info: Output device has changed, restarting MPD Dec 11 21:30:00 volumiomirka sudo[3148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 21:30:00 volumiomirka sudo[3148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:00 volumiomirka volumio[1485]: info: Output device has changed, restarting Shairport Sync Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:00 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 21:30:00 volumiomirka sudo[3155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 21:30:00 volumiomirka sudo[3155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:00 volumiomirka sudo[3150]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 21:30:00 volumiomirka sudo[3150]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:00 volumiomirka sudo[3150]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:00 volumiomirka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 21:30:00 volumiomirka volumio[1485]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 21:30:00 volumiomirka systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 21:30:01 volumiomirka volumio[1485]: info: MPD Permissions set Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 21:30:01 volumiomirka systemd[1]: shairport-sync.service: Consumed 1.754s CPU time. Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:01 volumiomirka volumio[1485]: info: Starting Shairport Sync Dec 11 21:30:01 volumiomirka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 21:30:01 volumiomirka sudo[3148]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:01 volumiomirka sudo[3167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 21:30:01 volumiomirka sudo[3167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:01 volumiomirka volumio[1485]: info: Shairport-Sync Started Dec 11 21:30:01 volumiomirka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 21:30:01 volumiomirka systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 21:30:01 volumiomirka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 21:30:01 volumiomirka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 21:30:01 volumiomirka sudo[3167]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:01 volumiomirka volumio[1485]: info: Shairport-Sync Started Dec 11 21:30:01 volumiomirka systemd[1]: mpd.service: Deactivated successfully. Dec 11 21:30:01 volumiomirka systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 21:30:01 volumiomirka systemd[1]: mpd.service: Consumed 3.857s CPU time. Dec 11 21:30:01 volumiomirka systemd[1]: mpd.socket: Deactivated successfully. Dec 11 21:30:01 volumiomirka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 21:30:01 volumiomirka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 21:30:01 volumiomirka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 21:30:01 volumiomirka systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 21:30:01 volumiomirka sudo[3185]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 21:30:01 volumiomirka sudo[3185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:30:01 volumiomirka sudo[3185]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:01 volumiomirka mpd[3187]: 2025-12-11T21:30:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 21:30:01 volumiomirka systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 21:30:01 volumiomirka sudo[3132]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:01 volumiomirka sudo[3155]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:01 volumiomirka volumio[1485]: error: MPD error: The expression evaluated to a falsy value: Dec 11 21:30:01 volumiomirka volumio[1485]: assert.ok(self.idling) Dec 11 21:30:01 volumiomirka volumio[1485]: error: The expression evaluated to a falsy value: Dec 11 21:30:01 volumiomirka volumio[1485]: assert.ok(self.idling) Dec 11 21:30:01 volumiomirka volumio[1485]: error: updateQueue error: null Dec 11 21:30:04 volumiomirka sudo[3199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 21:30:04 volumiomirka sudo[3199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:04 volumiomirka sudo[3201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 21:30:04 volumiomirka sudo[3201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:04 volumiomirka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 21:30:04 volumiomirka systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 21:30:04 volumiomirka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 21:30:04 volumiomirka systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 21:30:04 volumiomirka sudo[3201]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:04 volumiomirka sudo[3199]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:04 volumiomirka mpd_monitor.sh[3204]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 21:30:04 volumiomirka volumio[1485]: info: Successfully started MPD Monitor Dec 11 21:30:04 volumiomirka volumio[1485]: info: Successfully started MPD Monitor Dec 11 21:30:11 volumiomirka volumio[1485]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: oledType: 0 - Select... Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: numberOfBars: 16 - 16 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: gapBetweenBars: 1 - 1 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: frameRate: 50 - 50 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: scrollRate: 8 - 8 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: scrollDelay: 5 - 5 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: clockFormat: 0 - 24h leading 0 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: pauseScreenType: p - Play (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: invertDisplayPeriod: 0 - Disabled (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: i2cAddress: - Default for display Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: i2cBus: 1 - /dev/i2c-1 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: spiResetGPIONumber: 25 - 25 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: spiDCGPIONumber: 24 - 24 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: spiCS: 0 - 0 (default) Dec 11 21:30:11 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Populating: dateFormat: 0 - DD-MM-YYYY (default) Dec 11 21:30:29 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 21:30:29 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 21:30:34 volumiomirka volumio[1485]: info: Starting Uninstall of plugin system_hardware - mpd_oled Dec 11 21:30:34 volumiomirka volumio[1485]: info: Uninstalling plugin mpd_oled Dec 11 21:30:34 volumiomirka sudo[3316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Dec 11 21:30:34 volumiomirka sudo[3316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:34 volumiomirka volumio[1485]: info: Disabling plugin mpd_oled Dec 11 21:30:34 volumiomirka volumio[1485]: info: Preparing to generate the ALSA configuration file Dec 11 21:30:34 volumiomirka volumio[1485]: info: Asound.conf file written Dec 11 21:30:34 volumiomirka sudo[3323]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 11 21:30:34 volumiomirka sudo[3323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:34 volumiomirka sudo[3316]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:34 volumiomirka sudo[3323]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:34 volumiomirka volumio[1485]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 11 21:30:34 volumiomirka volumio[1485]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 11 21:30:34 volumiomirka volumio[1485]: info: Output device has changed, restarting MPD Dec 11 21:30:34 volumiomirka volumio[1485]: info: Output device has changed, restarting Shairport Sync Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 21:30:34 volumiomirka sudo[3331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 21:30:34 volumiomirka volumio[1485]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 21:30:34 volumiomirka sudo[3331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:34 volumiomirka sudo[3330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 21:30:34 volumiomirka sudo[3330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:34 volumiomirka volumio[1485]: info: Checking if uninstall.sh is present Dec 11 21:30:34 volumiomirka volumio[1485]: info: Executing uninstall.sh Dec 11 21:30:34 volumiomirka sudo[3330]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:34 volumiomirka volumio[1485]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Dec 11 21:30:34 volumiomirka volumio[1485]: info: MPD Permissions set Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka sudo[3340]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/system_hardware/mpd_oled/uninstall.sh Dec 11 21:30:34 volumiomirka sudo[3340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 21:30:34 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 21:30:34 volumiomirka systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 11 21:30:34 volumiomirka volumio[1485]: info: Starting Shairport Sync Dec 11 21:30:35 volumiomirka sudo[3350]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 21:30:35 volumiomirka sudo[3350]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:35 volumiomirka systemd[1]: mpd.service: Deactivated successfully. Dec 11 21:30:35 volumiomirka systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 11 21:30:35 volumiomirka systemd[1]: mpd.socket: Deactivated successfully. Dec 11 21:30:35 volumiomirka systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 11 21:30:35 volumiomirka systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 11 21:30:35 volumiomirka systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 11 21:30:35 volumiomirka systemd[1]: Starting mpd.service - Music Player Daemon... Dec 11 21:30:35 volumiomirka systemd[1]: Reloading. Dec 11 21:30:35 volumiomirka sudo[3353]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 21:30:35 volumiomirka sudo[3353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:30:35 volumiomirka sudo[3353]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:35 volumiomirka sudo[3372]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm /etc/systemd/system/mpd_oled_plugin.service Dec 11 21:30:35 volumiomirka sudo[3372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:30:35 volumiomirka sudo[3372]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:35 volumiomirka systemd[1]: Reloading. Dec 11 21:30:36 volumiomirka systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 11 21:30:36 volumiomirka systemd[1]: shairport-sync.service: Deactivated successfully. Dec 11 21:30:36 volumiomirka systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 21:30:36 volumiomirka systemd[1]: shairport-sync.service: Consumed 1.560s CPU time. Dec 11 21:30:36 volumiomirka sudo[3398]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -f /usr/local/bin/mpd_oled /usr/local/bin/mpd_oled_cava Dec 11 21:30:36 volumiomirka sudo[3398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 11 21:30:36 volumiomirka sudo[3398]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:36 volumiomirka sudo[3340]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:36 volumiomirka volumio[1485]: info: Uninstall script completed Dec 11 21:30:36 volumiomirka volumio[1485]: info: Removing plugin mpd_oled from configuration Dec 11 21:30:36 volumiomirka systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 11 21:30:36 volumiomirka sudo[3350]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:36 volumiomirka mpd[3373]: 2025-12-11T21:30:36 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 21:30:36 volumiomirka systemd[1]: Started mpd.service - Music Player Daemon. Dec 11 21:30:36 volumiomirka sudo[3331]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:36 volumiomirka volumio[1485]: info: Successfully removed mpd_oled configuration files Dec 11 21:30:36 volumiomirka volumio[1485]: info: Plugin folders cleanup Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning into folder /volumio/app/plugins/ Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category audio_interface Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category miscellanea Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category music_service Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category plugins.json Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category system_controller Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category user_interface Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning into folder /data/plugins/ Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category system_hardware Dec 11 21:30:36 volumiomirka volumio[1485]: info: Cleaning folder for mpd_oled Dec 11 21:30:36 volumiomirka volumio[1485]: info: Scanning category user_interface Dec 11 21:30:36 volumiomirka volumio[1485]: info: Plugin folders cleanup completed Dec 11 21:30:36 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 21:30:36 volumiomirka volumio[1485]: info: [{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.6.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":false}] Dec 11 21:30:36 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 21:30:36 volumiomirka volumio[1485]: info: Shairport-Sync Started Dec 11 21:30:36 volumiomirka volumio[1485]: error: updateQueue error: null Dec 11 21:30:39 volumiomirka sudo[3441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 21:30:39 volumiomirka sudo[3441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:39 volumiomirka systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 11 21:30:39 volumiomirka systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 11 21:30:39 volumiomirka systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 11 21:30:39 volumiomirka systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 11 21:30:39 volumiomirka mpd_monitor.sh[3443]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 21:30:39 volumiomirka sudo[3441]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:39 volumiomirka volumio[1485]: info: Successfully started MPD Monitor Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioStop Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreStateMachine::stop Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreStateMachine::stPlaybackTimer Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreStateMachine::updateTrackBlock Dec 11 21:30:47 volumiomirka volumio[1485]: info: CorePlayQueue::getTrackBlock Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreStateMachine::pushState Dec 11 21:30:47 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPushState Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreStateMachine::serviceStop Dec 11 21:30:47 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:47 volumiomirka volumio[1485]: info: CoreCommandRouter::serviceStop Dec 11 21:30:47 volumiomirka volumio[1485]: info: [1765485047053] ControllerWebradio::stop Dec 11 21:30:47 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 21:30:47 volumiomirka volumio[1485]: info: sendMpdCommand stop took 3 milliseconds Dec 11 21:30:48 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPlay Dec 11 21:30:48 volumiomirka volumio[1485]: info: CoreStateMachine::play index undefined Dec 11 21:30:48 volumiomirka volumio[1485]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 21:30:48 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:48 volumiomirka volumio[1485]: info: CoreStateMachine::startPlaybackTimer Dec 11 21:30:48 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:48 volumiomirka volumio[1485]: info: [1765485048251] ControllerWebradio::clearAddPlayTrack Dec 11 21:30:48 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 21:30:48 volumiomirka volumio[1485]: info: sendMpdCommand stop took 1 milliseconds Dec 11 21:30:48 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 21:30:48 volumiomirka volumio[1485]: info: Dec 11 21:30:48 volumiomirka volumio[1485]: ---------------------------- MPD announces system playlist update Dec 11 21:30:48 volumiomirka volumio[1485]: info: Ignoring MPD Status Update Dec 11 21:30:48 volumiomirka volumio[1485]: info: sendMpdCommand clear took 0 milliseconds Dec 11 21:30:48 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand load "http://www.rmfon.pl/tunein/rmfclassic.pls" Dec 11 21:30:48 volumiomirka volumio[1485]: error: updateQueue error: null Dec 11 21:30:48 volumiomirka volumio[1485]: info: ------------------------------ 1ms Dec 11 21:30:48 volumiomirka volumio[1485]: info: Dec 11 21:30:48 volumiomirka volumio[1485]: ---------------------------- MPD announces system playlist update Dec 11 21:30:48 volumiomirka volumio[1485]: info: Ignoring MPD Status Update Dec 11 21:30:48 volumiomirka volumio[1485]: info: sendMpdCommand load "http://www.rmfon.pl/tunein/rmfclassic.pls" took 647 milliseconds Dec 11 21:30:48 volumiomirka volumio[1485]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 21:30:48 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand play Dec 11 21:30:48 volumiomirka volumio[1485]: info: ------------------------------ 2ms Dec 11 21:30:48 volumiomirka volumio[1485]: info: sendMpdCommand play took 1 milliseconds Dec 11 21:30:50 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPlay Dec 11 21:30:50 volumiomirka volumio[1485]: info: CoreStateMachine::play index undefined Dec 11 21:30:50 volumiomirka volumio[1485]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 21:30:50 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:50 volumiomirka volumio[1485]: info: CoreStateMachine::startPlaybackTimer Dec 11 21:30:50 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:50 volumiomirka volumio[1485]: info: [1765485050620] ControllerWebradio::clearAddPlayTrack Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 21:30:50 volumiomirka volumio[1485]: info: Dec 11 21:30:50 volumiomirka volumio[1485]: ---------------------------- MPD announces state update: player Dec 11 21:30:50 volumiomirka volumio[1485]: info: sendMpdCommand stop took 1 milliseconds Dec 11 21:30:50 volumiomirka volumio[1485]: info: ControllerMpd::getState Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand status Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand clear Dec 11 21:30:50 volumiomirka volumio[1485]: info: Dec 11 21:30:50 volumiomirka volumio[1485]: ---------------------------- MPD announces system playlist update Dec 11 21:30:50 volumiomirka volumio[1485]: info: Ignoring MPD Status Update Dec 11 21:30:50 volumiomirka volumio[1485]: info: sendMpdCommand status took 1 milliseconds Dec 11 21:30:50 volumiomirka volumio[1485]: info: sendMpdCommand clear took 1 milliseconds Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::parseState Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand load "http://www.rmfon.pl/tunein/rmfclassic.pls" Dec 11 21:30:50 volumiomirka volumio[1485]: error: updateQueue error: null Dec 11 21:30:50 volumiomirka volumio[1485]: info: ------------------------------ 1ms Dec 11 21:30:50 volumiomirka volumio[1485]: info: Dec 11 21:30:50 volumiomirka volumio[1485]: ---------------------------- MPD announces system playlist update Dec 11 21:30:50 volumiomirka volumio[1485]: info: Ignoring MPD Status Update Dec 11 21:30:50 volumiomirka volumio[1485]: info: sendMpdCommand playlistinfo took 112 milliseconds Dec 11 21:30:50 volumiomirka volumio[1485]: info: sendMpdCommand load "http://www.rmfon.pl/tunein/rmfclassic.pls" took 112 milliseconds Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::parseTrackInfo Dec 11 21:30:50 volumiomirka volumio[1485]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 11 21:30:50 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand play Dec 11 21:30:50 volumiomirka volumio[1485]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 21:30:50 volumiomirka volumio[1485]: info: ------------------------------ 114ms Dec 11 21:30:50 volumiomirka volumio[1485]: info: ------------------------------ 2ms Dec 11 21:30:50 volumiomirka volumio[1485]: info: sendMpdCommand play took 1 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: info: Dec 11 21:30:53 volumiomirka volumio[1485]: ---------------------------- MPD announces state update: player Dec 11 21:30:53 volumiomirka volumio[1485]: info: ControllerMpd::getState Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand status Dec 11 21:30:53 volumiomirka volumio[1485]: info: Dec 11 21:30:53 volumiomirka volumio[1485]: ---------------------------- MPD announces state update: player Dec 11 21:30:53 volumiomirka volumio[1485]: info: sendMpdCommand status took 1 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: info: ControllerMpd::getState Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand status Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::parseState Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: sendMpdCommand status took 0 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::parseState Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::parseTrackInfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: ControllerMpd::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::servicePushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RMF Classic","artist":null,"album":null,"uri":"http://www.rmfon.pl/tunein/tunein.php?host=rs201-krk.rmfstream.pl&mount=rmf_classic_nt&secure=0","trackType":""} Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: CURRENT POSITION 0 Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::syncState stateService play Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::syncState currentStatus stop Dec 11 21:30:53 volumiomirka volumio[1485]: info: ------------------------------ 3ms Dec 11 21:30:53 volumiomirka volumio[1485]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::parseTrackInfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: ControllerMpd::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::servicePushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RMF Classic","artist":null,"album":null,"uri":"http://www.rmfon.pl/tunein/tunein.php?host=rs201-krk.rmfstream.pl&mount=rmf_classic_nt&secure=0","trackType":""} Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: CURRENT POSITION 0 Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::syncState stateService play Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::syncState currentStatus play Dec 11 21:30:53 volumiomirka volumio[1485]: info: Received an update from plugin. extracting info from payload Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: ------------------------------ 9ms Dec 11 21:30:53 volumiomirka volumio[1485]: info: Dec 11 21:30:53 volumiomirka volumio[1485]: ---------------------------- MPD announces system playlist update Dec 11 21:30:53 volumiomirka volumio[1485]: info: Ignoring MPD Status Update Dec 11 21:30:53 volumiomirka volumio[1485]: info: Dec 11 21:30:53 volumiomirka volumio[1485]: ---------------------------- MPD announces state update: player Dec 11 21:30:53 volumiomirka volumio[1485]: info: ControllerMpd::getState Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand status Dec 11 21:30:53 volumiomirka volumio[1485]: info: ------------------------------ 3ms Dec 11 21:30:53 volumiomirka volumio[1485]: info: sendMpdCommand status took 3 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::parseState Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: ControllerMpd::parseTrackInfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: ControllerMpd::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::servicePushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CorePlayQueue::getTrack 0 Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Now Playing info goes here","artist":"RMF Classic","album":null,"uri":"http://www.rmfon.pl/tunein/tunein.php?host=rs201-krk.rmfstream.pl&mount=rmf_classic_nt&secure=0","trackType":""} Dec 11 21:30:53 volumiomirka volumio[1485]: verbose: CURRENT POSITION 0 Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::syncState stateService play Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::syncState currentStatus play Dec 11 21:30:53 volumiomirka volumio[1485]: info: Received an update from plugin. extracting info from payload Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreStateMachine::pushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: CoreCommandRouter::volumioPushState Dec 11 21:30:53 volumiomirka volumio[1485]: info: ------------------------------ 10ms Dec 11 21:30:58 volumiomirka volumio[1485]: info: Starting Uninstall of plugin user_interface - touch_display Dec 11 21:30:58 volumiomirka volumio[1485]: info: Uninstalling plugin touch_display Dec 11 21:30:58 volumiomirka sudo[3528]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 11 21:30:58 volumiomirka sudo[3528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:58 volumiomirka sudo[3528]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:58 volumiomirka sudo[3530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 11 21:30:58 volumiomirka sudo[3530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:58 volumiomirka volumio[1485]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 11 21:30:58 volumiomirka volumio[1485]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 21:30:58 volumiomirka sudo[3536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 11 21:30:58 volumiomirka volumio[1485]: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 21:30:58 volumiomirka volumio[1485]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 11 21:30:58 volumiomirka volumio[1485]: at ChildProcess.exithandler (node:child_process:413:7) Dec 11 21:30:58 volumiomirka volumio[1485]: at ChildProcess.emit (node:events:514:28) Dec 11 21:30:58 volumiomirka volumio[1485]: at maybeClose (node:internal/child_process:1105:16) Dec 11 21:30:58 volumiomirka volumio[1485]: at Socket. (node:internal/child_process:457:11) Dec 11 21:30:58 volumiomirka volumio[1485]: at Socket.emit (node:events:514:28) Dec 11 21:30:58 volumiomirka volumio[1485]: at Pipe. (node:net:337:12) Dec 11 21:30:58 volumiomirka volumio[1485]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 21:30:58 volumiomirka sudo[3536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:58 volumiomirka sudo[3532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 11 21:30:58 volumiomirka sudo[3532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 21:30:58 volumiomirka sudo[3536]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:58 volumiomirka systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 11 21:30:58 volumiomirka systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 11 21:30:58 volumiomirka sudo[3530]: pam_unix(sudo:session): session closed for user root Dec 11 21:30:58 volumiomirka systemd[1]: Reloading. Dec 11 21:30:58 volumiomirka sudo[3571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 21:29' Dec 11 21:30:58 volumiomirka sudo[3571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"