-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Thu 2025-09-11 22:30:21 MSK. -- Sep 11 22:29:08 volumio-pi volumio[884]: info: CALLMETHOD: miscellanea kodi optimiseKodi [object Object] Sep 11 22:29:08 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: kodi , optimiseKodi Sep 11 22:29:08 volumio-pi volumio[884]: info: Successfully saved Kodi settings Sep 11 22:29:08 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:08 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:08 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:08 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:14 volumio-pi volumio[884]: info: CALLMETHOD: miscellanea kodi optimiseKodi [object Object] Sep 11 22:29:14 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: kodi , optimiseKodi Sep 11 22:29:14 volumio-pi volumio[884]: info: Successfully saved Kodi settings Sep 11 22:29:14 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:14 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:14 volumio-pi volumio[884]: /bin/sed: can't read /home/kodi/.kodi/userdata/guisettings.xml: No such file or directory Sep 11 22:29:20 volumio-pi volumio-time-update[615]: Failed to set time: Connection timed out Sep 11 22:29:20 volumio-pi dbus-daemon[611]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Sep 11 22:29:20 volumio-pi sudo[1908]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:20 volumio-pi volumio-time-update[615]: volumio-time-update-util: Error setting time. Sep 11 22:29:20 volumio-pi volumio-time-update[615]: volumio-time-update-util: Retrying in 5 seconds... Sep 11 22:29:25 volumio-pi volumio-time-update[615]: volumio-time-update-util: Fetching time from Volumio... Sep 11 22:29:25 volumio-pi volumio-time-update[615]: volumio-time-update-util: Setting system time to: 2025-09-11 22:44:09 Sep 11 22:29:25 volumio-pi sudo[2024]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-11 22:44:09 Sep 11 22:29:25 volumio-pi sudo[2024]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:25 volumio-pi dbus-daemon[611]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.23' (uid=0 pid=2025 comm="timedatectl set-time 2025-09-11 22:44:09 ") Sep 11 22:29:25 volumio-pi systemd[1]: systemd-timedated.service: Failed to run 'start' task: Read-only file system Sep 11 22:29:25 volumio-pi systemd[1]: systemd-timedated.service: Failed with result 'resources'. Sep 11 22:29:25 volumio-pi systemd[1]: Failed to start Time & Date Service. Sep 11 22:29:26 volumio-pi volumio[884]: info: CALLMETHOD: miscellanea kodi updateBootConfig [object Object] Sep 11 22:29:26 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: kodi , updateBootConfig Sep 11 22:29:26 volumio-pi volumio[884]: info: Successfully updated boot configuration Sep 11 22:29:34 volumio-pi volumio[884]: info: CoreCommandRouter::volumioGetState Sep 11 22:29:36 volumio-pi volumio[884]: info: CoreCommandRouter::volumioPlay Sep 11 22:29:36 volumio-pi volumio[884]: info: CoreStateMachine::play index undefined Sep 11 22:29:36 volumio-pi volumio[884]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 11 22:29:36 volumio-pi volumio[884]: info: CorePlayQueue::getTrack 0 Sep 11 22:29:36 volumio-pi volumio[884]: info: CoreStateMachine::startPlaybackTimer Sep 11 22:29:36 volumio-pi volumio[884]: info: CorePlayQueue::getTrack 0 Sep 11 22:29:36 volumio-pi volumio[884]: info: [1757618976584] ControllerWebradio::clearAddPlayTrack Sep 11 22:29:36 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand stop Sep 11 22:29:36 volumio-pi volumio[884]: info: sendMpdCommand stop took 0 milliseconds Sep 11 22:29:36 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand clear Sep 11 22:29:36 volumio-pi volumio[884]: info: Sep 11 22:29:36 volumio-pi volumio[884]: ---------------------------- MPD announces system playlist update Sep 11 22:29:36 volumio-pi volumio[884]: info: Ignoring MPD Status Update Sep 11 22:29:36 volumio-pi volumio[884]: info: sendMpdCommand clear took 1 milliseconds Sep 11 22:29:36 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1729607" Sep 11 22:29:36 volumio-pi volumio[884]: error: updateQueue error: null Sep 11 22:29:36 volumio-pi volumio[884]: info: ------------------------------ 2ms Sep 11 22:29:37 volumio-pi volumio[884]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1729607" took 725 milliseconds Sep 11 22:29:37 volumio-pi volumio[884]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 11 22:29:37 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand play Sep 11 22:29:37 volumio-pi volumio[884]: info: Sep 11 22:29:37 volumio-pi volumio[884]: ---------------------------- MPD announces system playlist update Sep 11 22:29:37 volumio-pi volumio[884]: info: Ignoring MPD Status Update Sep 11 22:29:37 volumio-pi volumio[884]: info: sendMpdCommand play took 2 milliseconds Sep 11 22:29:37 volumio-pi volumio[884]: info: ------------------------------ 1ms Sep 11 22:29:38 volumio-pi volumio[884]: info: Sep 11 22:29:38 volumio-pi volumio[884]: ---------------------------- MPD announces state update: player Sep 11 22:29:38 volumio-pi volumio[884]: info: ControllerMpd::getState Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand status Sep 11 22:29:38 volumio-pi volumio[884]: info: Sep 11 22:29:38 volumio-pi volumio[884]: ---------------------------- MPD announces state update: player Sep 11 22:29:38 volumio-pi volumio[884]: info: ControllerMpd::getState Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand status Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 11 22:29:38 volumio-pi volumio[884]: info: sendMpdCommand status took 8 milliseconds Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::parseState Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 11 22:29:38 volumio-pi volumio[884]: info: sendMpdCommand status took 10 milliseconds Sep 11 22:29:38 volumio-pi volumio[884]: info: sendMpdCommand clearerror took 5 milliseconds Sep 11 22:29:38 volumio-pi volumio[884]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::parseState Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::parseTrackInfo Sep 11 22:29:38 volumio-pi volumio[884]: info: ControllerMpd::pushState Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreCommandRouter::servicePushState Sep 11 22:29:38 volumio-pi volumio[884]: info: CorePlayQueue::getTrack 0 Sep 11 22:29:38 volumio-pi volumio[884]: verbose: STATE SERVICE {"status":"pause","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":"70s_128","artist":"1.FM - Absolute 70's Pop (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/70s_128","trackType":""} Sep 11 22:29:38 volumio-pi volumio[884]: verbose: CURRENT POSITION 0 Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreStateMachine::syncState stateService pause Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreStateMachine::syncState currentStatus stop Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreStateMachine::pushState Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreCommandRouter::volumioPushState Sep 11 22:29:38 volumio-pi volumio[884]: info: ------------------------------ 21ms Sep 11 22:29:38 volumio-pi volumio[884]: info: sendMpdCommand clearerror took 11 milliseconds Sep 11 22:29:38 volumio-pi volumio[884]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 11 22:29:38 volumio-pi volumio[884]: verbose: ControllerMpd::parseTrackInfo Sep 11 22:29:38 volumio-pi volumio[884]: info: ControllerMpd::pushState Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreCommandRouter::servicePushState Sep 11 22:29:38 volumio-pi volumio[884]: info: CorePlayQueue::getTrack 0 Sep 11 22:29:38 volumio-pi volumio[884]: verbose: STATE SERVICE {"status":"pause","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":"70s_128","artist":"1.FM - Absolute 70's Pop (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/70s_128","trackType":""} Sep 11 22:29:38 volumio-pi volumio[884]: verbose: CURRENT POSITION 0 Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreStateMachine::syncState stateService pause Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreStateMachine::syncState currentStatus stop Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreStateMachine::pushState Sep 11 22:29:38 volumio-pi volumio[884]: info: CoreCommandRouter::volumioPushState Sep 11 22:29:38 volumio-pi volumio[884]: info: ------------------------------ 28ms Sep 11 22:29:49 volumio-pi volumio[884]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 11 22:29:49 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 11 22:29:49 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Sep 11 22:29:49 volumio-pi sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Sep 11 22:29:49 volumio-pi sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:49 volumio-pi sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 11 22:29:49 volumio-pi sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:49 volumio-pi sudo[2080]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:49 volumio-pi sudo[2075]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:49 volumio-pi sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 11 22:29:49 volumio-pi sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:49 volumio-pi sudo[2088]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:49 volumio-pi sudo[2095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 11 22:29:49 volumio-pi sudo[2095]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:49 volumio-pi sudo[2095]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:49 volumio-pi sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 11 22:29:49 volumio-pi sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:49 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Sep 11 22:29:49 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Sep 11 22:29:49 volumio-pi sudo[2100]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:49 volumio-pi sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 11 22:29:49 volumio-pi sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:49 volumio-pi sudo[2103]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:49 volumio-pi sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 11 22:29:49 volumio-pi sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:50 volumio-pi volumio-time-update[615]: Failed to set time: Connection timed out Sep 11 22:29:50 volumio-pi dbus-daemon[611]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Sep 11 22:29:50 volumio-pi sudo[2024]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:50 volumio-pi volumio-time-update[615]: volumio-time-update-util: Error setting time. Sep 11 22:29:50 volumio-pi volumio-time-update[615]: volumio-time-update-util: Retrying in 5 seconds... Sep 11 22:29:53 volumio-pi sudo[2107]: pam_unix(sudo:session): session closed for user root Sep 11 22:29:55 volumio-pi volumio-time-update[615]: volumio-time-update-util: Fetching time from Volumio... Sep 11 22:29:56 volumio-pi volumio-time-update[615]: volumio-time-update-util: Setting system time to: 2025-09-11 22:44:39 Sep 11 22:29:56 volumio-pi sudo[2132]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-09-11 22:44:39 Sep 11 22:29:56 volumio-pi sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:29:56 volumio-pi dbus-daemon[611]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.24' (uid=0 pid=2133 comm="timedatectl set-time 2025-09-11 22:44:39 ") Sep 11 22:29:56 volumio-pi systemd[1]: systemd-timedated.service: Failed to run 'start' task: Read-only file system Sep 11 22:29:56 volumio-pi systemd[1]: systemd-timedated.service: Failed with result 'resources'. Sep 11 22:29:56 volumio-pi systemd[1]: Failed to start Time & Date Service. Sep 11 22:30:06 volumio-pi volumio[884]: info: CALLMETHOD: system_controller network saveWirelessNet [object Object] Sep 11 22:30:06 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNet Sep 11 22:30:06 volumio-pi sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Sep 11 22:30:06 volumio-pi sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:30:06 volumio-pi sudo[2152]: pam_unix(sudo:session): session closed for user root Sep 11 22:30:06 volumio-pi volumio[884]: error: Cannot set permissions for /etc/network/interfaces: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/network/interfaces && /usr/bin/sudo /bin/chmod 777 /etc/dhcpcd.conf Sep 11 22:30:06 volumio-pi volumio[884]: /bin/chmod: changing permissions of '/etc/network/interfaces': Read-only file system Sep 11 22:30:07 volumio-pi volumio[884]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/system_controller/network/config.json' Sep 11 22:30:16 volumio-pi volumio[884]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Sep 11 22:30:16 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Sep 11 22:30:17 volumio-pi volumio[884]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/system_controller/network/config.json' Sep 11 22:30:17 volumio-pi sudo[2201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Sep 11 22:30:17 volumio-pi sudo[2201]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 11 22:30:17 volumio-pi sudo[2201]: pam_unix(sudo:session): session closed for user root Sep 11 22:30:17 volumio-pi volumio[884]: error: Cannot set permissions for /etc/hostapd/hostapd.conf: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/hostapd/hostapd.conf Sep 11 22:30:17 volumio-pi volumio[884]: /bin/chmod: changing permissions of '/etc/hostapd/hostapd.conf': Read-only file system Sep 11 22:30:21 volumio-pi volumio-time-update[615]: Failed to set time: Connection timed out Sep 11 22:30:21 volumio-pi dbus-daemon[611]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) Sep 11 22:30:21 volumio-pi sudo[2132]: pam_unix(sudo:session): session closed for user root Sep 11 22:30:21 volumio-pi volumio-time-update[615]: volumio-time-update-util: Error setting time. Sep 11 22:30:21 volumio-pi volumio-time-update[615]: volumio-time-update-util: Retrying in 5 seconds... Sep 11 22:30:21 volumio-pi volumio[884]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Sep 11 22:30:21 volumio-pi volumio[884]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Sep 11 22:30:21 volumio-pi volumio[884]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 22:30:21 volumio-pi volumio[884]: ReferenceError: error is not defined Sep 11 22:30:21 volumio-pi volumio[884]: at /volumio/app/plugins/system_controller/network/index.js:866:58 Sep 11 22:30:21 volumio-pi volumio[884]: at /volumio/node_modules/graceful-fs/graceful-fs.js:144:16 Sep 11 22:30:21 volumio-pi volumio[884]: at fs.js:1448:7 Sep 11 22:30:21 volumio-pi volumio[884]: at FSReqCallback.oncomplete (fs.js:171:23) Sep 11 22:30:21 volumio-pi volumio[884]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 11 22:30:21 volumio-pi sudo[2229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-11 22:29 Sep 11 22:30:21 volumio-pi sudo[2229]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"