-- Logs begin at Thu 2019-02-14 11:12:07 CET, end at Tue 2025-05-20 00:25:10 CEST. -- May 20 00:24:37 volumio ntpd[717]: receive: Unexpected origin timestamp 0xeb99fa74.1c4e6676 does not match aorg 0000000000.00000000 from server@95.110.135.141 xmt 0xebd62e25.d38ca96c May 20 00:24:37 volumio ntpd[717]: receive: Unexpected origin timestamp 0xeb99fa74.1c8e3a74 does not match aorg 0000000000.00000000 from server@151.22.209.90 xmt 0xebd62e25.d39797d7 May 20 00:24:38 volumio systemd[1]: Starting Daily apt download activities... May 20 00:24:38 volumio volumio[973]: info: Alarm: Scheduling Playlist Sveglia at 6:30 May 20 00:24:38 volumio volumio[973]: info: Loading plugin "airplay_emulation"... May 20 00:24:38 volumio dbus-daemon[412]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.8' (uid=0 pid=1096 comm="timedatectl set-time 2025-05-20 00:24:37 ") May 20 00:24:38 volumio systemd[1]: Starting Time & Date Service... May 20 00:24:38 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm (imedated): corrupted xattr block 22417444: invalid header May 20 00:24:38 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm (imedated): corrupted xattr block 22417444: invalid header May 20 00:24:38 volumio kernel: overlayfs: failed to get redirect (-117) May 20 00:24:38 volumio systemd[1108]: systemd-timedated.service: Failed to set up mount namespacing: Structure needs cleaning May 20 00:24:38 volumio systemd[1108]: systemd-timedated.service: Failed at step NAMESPACE spawning /lib/systemd/systemd-timedated: Structure needs cleaning May 20 00:24:38 volumio systemd[1]: systemd-timedated.service: Main process exited, code=exited, status=226/NAMESPACE May 20 00:24:38 volumio systemd[1]: systemd-timedated.service: Failed with result 'exit-code'. May 20 00:24:39 volumio systemd[1]: Failed to start Time & Date Service. May 20 00:24:39 volumio volumio[973]: info: Starting Shairport Sync May 20 00:24:39 volumio volumio[973]: info: Loading plugin "last_100"... May 20 00:24:39 volumio volumio[973]: info: Loading plugin "webradio"... May 20 00:24:41 volumio volumio[973]: info: Loading plugin "i2s_dacs"... May 20 00:24:41 volumio volumio[973]: info: I2S DAC not set, start Auto-detection May 20 00:24:41 volumio volumio[973]: info: Loading plugin "volumiodiscovery"... May 20 00:24:41 volumio volumio[973]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 00:24:41 volumio volumio[973]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 00:24:41 volumio volumio[973]: *** WARNING *** For more information see May 20 00:24:41 volumio volumio[973]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 00:24:41 volumio volumio[973]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 00:24:41 volumio volumio[973]: *** WARNING *** For more information see May 20 00:24:41 volumio node[973]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 00:24:41 volumio node[973]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 00:24:41 volumio node[973]: *** WARNING *** For more information see May 20 00:24:41 volumio node[973]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 00:24:41 volumio node[973]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 00:24:41 volumio node[973]: *** WARNING *** For more information see May 20 00:24:41 volumio volumio[973]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 00:24:41 volumio volumio[973]: info: Discovery: Started advertising with name: Volumio May 20 00:24:42 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 00:24:42 volumio volumio[973]: info: Loading plugin "outputs"... May 20 00:24:42 volumio volumio[973]: info: Loading plugin "albumart"... May 20 00:24:42 volumio volumio[973]: info: Plugin example_plugin is not enabled May 20 00:24:42 volumio volumio[973]: info: Loading plugin "inputs"... May 20 00:24:42 volumio volumio[973]: info: Loading plugin "updater_comm"... May 20 00:24:45 volumio systemd[1]: apt-daily.service: Succeeded. May 20 00:24:46 volumio systemd[1]: Started Daily apt download activities. May 20 00:24:46 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 20 00:24:46 volumio volumio-remote-updater[389]: [2025-05-20 00:24:46] [connect] Successful connection May 20 00:24:48 volumio volumio[973]: info: Plugin mpdemulation is not enabled May 20 00:24:48 volumio volumio[973]: info: Loading plugin "rest_api"... May 20 00:24:49 volumio volumio[973]: info: Loading plugin "websocket"... May 20 00:24:49 volumio volumio[973]: info: Starting Socket.io Server version 2.3.0 May 20 00:24:50 volumio volumio[973]: info: Loading i18n strings for locale it May 20 00:24:50 volumio volumio[973]: Updating browse sources language May 20 00:24:50 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 00:24:52 volumio volumio[973]: Forking 1 albumart workers May 20 00:24:52 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 20 00:24:52 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 20 00:24:54 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 00:24:54 volumio volumio[973]: info: CoreCommandRouter::initPlayerControls May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 00:24:55 volumio volumio[973]: Express server listening on port 3000 May 20 00:24:55 volumio volumio[973]: [Metrics] WebUI: 48s 107.60ms May 20 00:24:55 volumio volumio[973]: info: CoreStateMachine::resetVolumioState May 20 00:24:55 volumio volumio[973]: info: CoreStateMachine::getcurrentVolume May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::volumioRetrievevolume May 20 00:24:55 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 00:24:56 volumio volumio[973]: info: Starting Scheduled Playlist Sveglia May 20 00:24:56 volumio volumio[973]: info: Play playlist Sveglia May 20 00:24:56 volumio sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 00:24:56 volumio sudo[1253]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 00:24:56 volumio sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 00:24:56 volumio sudo[1253]: pam_unix(sudo:session): session closed for user root May 20 00:24:57 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 00:24:57 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root May 20 00:24:57 volumio volumio[973]: info: Volumio Network Manager: Network status updated: 2 May 20 00:24:58 volumio volumio[973]: info: Starting Scheduled Playlist Sveglia May 20 00:24:58 volumio volumio[973]: info: Play playlist Sveglia May 20 00:24:59 volumio volumio[973]: info: VolumeController:: Volume=59 Mute =false May 20 00:24:59 volumio volumio[973]: info: CoreStateMachine::pushState May 20 00:24:59 volumio volumio[973]: info: CorePlayQueue::getTrack 0 May 20 00:24:59 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 00:24:59 volumio volumio[973]: info: CoreCommandRouter::volumioPushState May 20 00:24:59 volumio volumio[973]: info: CoreStateMachine::updateTrackBlock May 20 00:24:59 volumio volumio[973]: info: CorePlayQueue::getTrackBlock May 20 00:24:59 volumio volumio[973]: info: CoreCommandRouter::volumioRetrievevolume May 20 00:25:00 volumio volumio[973]: info: Starting Scheduled Playlist Sveglia May 20 00:25:00 volumio volumio[973]: info: Play playlist Sveglia May 20 00:25:01 volumio volumio-remote-updater[389]: [2025-05-20 00:25:01] [connect] Successful connection May 20 00:25:01 volumio volumio[973]: info: Starting Scheduled Playlist Sveglia May 20 00:25:01 volumio volumio[973]: info: Play playlist Sveglia May 20 00:25:01 volumio volumio[973]: info: Reloading queue from file May 20 00:25:02 volumio volumio[973]: info: Starting Scheduled Playlist Sveglia May 20 00:25:02 volumio volumio[973]: info: Play playlist Sveglia May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::setRepeat null single undefined May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::pushState May 20 00:25:02 volumio volumio[973]: info: CorePlayQueue::getTrack 0 May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::volumioPushState May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::setRandom null May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::pushState May 20 00:25:02 volumio volumio[973]: info: CorePlayQueue::getTrack 0 May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::volumioPushState May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::volumioClearQueue May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::ClearQueue May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::stop May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined May 20 00:25:02 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue May 20 00:25:02 volumio volumio[973]: info: CorePlayQueue::saveQueue May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::volumioPushState May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue May 20 00:25:02 volumio volumio[973]: info: CoreCommandRouter::volumioAddQueueItems May 20 00:25:02 volumio volumio[973]: info: CoreStateMachine::addQueueItems May 20 00:25:02 volumio volumio[973]: info: CorePlayQueue::addQueueItems May 20 00:25:02 volumio volumio[973]: info: Preload queue cleared May 20 00:25:02 volumio volumio[973]: info: Adding Item to queue: mnt/USB/PENNA_USB/12 Take It Easy.mp3 May 20 00:25:02 volumio volumio[973]: info: Exploding uri mnt/USB/PENNA_USB/12 Take It Easy.mp3 in service mpd May 20 00:25:02 volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 00:25:02 volumio volumio[973]: TypeError: Cannot read property 'then' of undefined May 20 00:25:02 volumio volumio[973]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19) May 20 00:25:02 volumio volumio[973]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21) May 20 00:25:02 volumio volumio[973]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) May 20 00:25:02 volumio volumio[973]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 20 00:25:02 volumio volumio[973]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 20 00:25:02 volumio volumio[973]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) May 20 00:25:02 volumio volumio[973]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25) May 20 00:25:02 volumio volumio[973]: at CoreCommandRouter.addQueueItems (/volumio/app/index.js:448:28) May 20 00:25:02 volumio volumio[973]: at /volumio/app/playlistManager.js:726:30 May 20 00:25:02 volumio volumio[973]: at /volumio/node_modules/jsonfile/index.js:46:5 May 20 00:25:02 volumio volumio[973]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 May 20 00:25:02 volumio volumio[973]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) May 20 00:25:02 volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 00:25:03 volumio dbus-daemon[412]: [system] Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) May 20 00:25:03 volumio volumio-time-update[387]: Failed to set time: Failed to activate service 'org.freedesktop.timedate1': timed out (service_start_timeout=25000ms) May 20 00:25:03 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root May 20 00:25:03 volumio volumio-time-update[387]: volumio-time-update-util: Error setting time. May 20 00:25:03 volumio volumio-time-update[387]: volumio-time-update-util: Retrying in 5 seconds... May 20 00:25:04 volumio volumio[973]: Starting albumart workers May 20 00:25:08 volumio volumio-time-update[387]: volumio-time-update-util: Fetching time from Volumio... May 20 00:25:08 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm wget: corrupted xattr block 22417444: invalid header May 20 00:25:08 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm wget: corrupted xattr block 22417444: invalid header May 20 00:25:08 volumio kernel: overlayfs: failed to get redirect (-117) May 20 00:25:09 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm wget: corrupted xattr block 22417444: invalid header May 20 00:25:09 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm wget: corrupted xattr block 22417444: invalid header May 20 00:25:09 volumio kernel: overlayfs: failed to get redirect (-117) May 20 00:25:09 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm wget: corrupted xattr block 22417444: invalid header May 20 00:25:09 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm wget: corrupted xattr block 22417444: invalid header May 20 00:25:09 volumio kernel: overlayfs: failed to get redirect (-117) May 20 00:25:09 volumio volumio-time-update[387]: volumio-time-update-util: Setting system time to: 2025-05-20 00:25:09 May 20 00:25:09 volumio sudo[1332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-05-20 00:25:09 May 20 00:25:09 volumio sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 00:25:09 volumio dbus-daemon[412]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.12' (uid=0 pid=1333 comm="timedatectl set-time 2025-05-20 00:25:09 ") May 20 00:25:09 volumio systemd[1]: Starting Time & Date Service... May 20 00:25:10 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm (imedated): corrupted xattr block 22417444: invalid header May 20 00:25:10 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_xattr_block_get:596: inode #5450261: comm (imedated): corrupted xattr block 22417444: invalid header May 20 00:25:10 volumio kernel: overlayfs: failed to get redirect (-117) May 20 00:25:10 volumio systemd[1334]: systemd-timedated.service: Failed to set up mount namespacing: Structure needs cleaning May 20 00:25:10 volumio systemd[1334]: systemd-timedated.service: Failed at step NAMESPACE spawning /lib/systemd/systemd-timedated: Structure needs cleaning May 20 00:25:10 volumio systemd[1]: systemd-timedated.service: Main process exited, code=exited, status=226/NAMESPACE May 20 00:25:10 volumio systemd[1]: systemd-timedated.service: Failed with result 'exit-code'. May 20 00:25:10 volumio systemd[1]: Failed to start Time & Date Service. May 20 00:25:10 volumio sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 00:24 May 20 00:25:10 volumio sudo[1338]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"