Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: verbose: New Socket.io Connection to volumio.local from 192.168.3.238 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 14:40:17 jeanremyvolumiocomble systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetQueue Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreStateMachine::getQueue Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CorePlayQueue::getQueue Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Aug 31 14:40:17 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Aug 31 14:40:17 jeanremyvolumiocomble dbus-daemon[593]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.134' (uid=0 pid=2795 comm="timedatectl show --property=NTPSynchronized --valu") Aug 31 14:40:17 jeanremyvolumiocomble systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 31 14:40:18 jeanremyvolumiocomble dbus-daemon[593]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 31 14:40:18 jeanremyvolumiocomble systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 31 14:40:18 jeanremyvolumiocomble setdatetime-helper.sh[2794]: Time is not synchronized. Attempting to sync... Aug 31 14:40:18 jeanremyvolumiocomble sudo[2819]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 31 Aug 2025 12:40:18 GMT#015' Aug 31 14:40:18 jeanremyvolumiocomble sudo[2819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 14:40:18 jeanremyvolumiocomble setdatetime-helper.sh[2820]: Sun Aug 31 14:40:18 CEST 2025 Aug 31 14:40:18 jeanremyvolumiocomble sudo[2819]: pam_unix(sudo:session): session closed for user root Aug 31 14:40:18 jeanremyvolumiocomble setdatetime-helper.sh[2794]: Time synchronized successfully. Aug 31 14:40:18 jeanremyvolumiocomble systemd-journald[334]: Time jumped backwards, rotating. Aug 31 14:40:18 jeanremyvolumiocomble systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 31 14:40:18 jeanremyvolumiocomble systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 31 14:40:26 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , setUpdaterChannel Aug 31 14:40:26 jeanremyvolumiocomble volumio[1119]: info: Updater channel set to test Aug 31 14:40:28 jeanremyvolumiocomble volumio[1119]: info: CALLMETHOD: system_controller system setTestPlugins true Aug 31 14:40:28 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , setTestPlugins Aug 31 14:40:28 jeanremyvolumiocomble volumio[1119]: info: Plugins store is now in test mode Aug 31 14:40:29 jeanremyvolumiocomble volumio[1119]: info: CALLMETHOD: system_controller system enableSSH true Aug 31 14:40:29 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Aug 31 14:40:29 jeanremyvolumiocomble sudo[2863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Aug 31 14:40:29 jeanremyvolumiocomble sudo[2863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 14:40:29 jeanremyvolumiocomble systemd[1]: Starting ssh.service - OpenBSD Secure Shell server... Aug 31 14:40:29 jeanremyvolumiocomble sshd[2866]: Server listening on 0.0.0.0 port 22. Aug 31 14:40:29 jeanremyvolumiocomble sshd[2866]: Server listening on :: port 22. Aug 31 14:40:29 jeanremyvolumiocomble systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Aug 31 14:40:29 jeanremyvolumiocomble sudo[2863]: pam_unix(sudo:session): session closed for user root Aug 31 14:40:29 jeanremyvolumiocomble sudo[2867]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Aug 31 14:40:29 jeanremyvolumiocomble sudo[2867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 14:40:30 jeanremyvolumiocomble systemd[1]: Reloading. Aug 31 14:40:31 jeanremyvolumiocomble systemd[1]: Reloading. Aug 31 14:40:33 jeanremyvolumiocomble systemd[1]: Reloading. Aug 31 14:40:34 jeanremyvolumiocomble sudo[2867]: pam_unix(sudo:session): session closed for user root Aug 31 14:40:34 jeanremyvolumiocomble volumio[1119]: info: enable SSH service success Aug 31 14:40:34 jeanremyvolumiocomble volumio[1119]: info: CALLMETHOD: system_controller system enableLiveLog true Aug 31 14:40:34 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Aug 31 14:40:34 jeanremyvolumiocomble volumio[1119]: info: Launching a new LiveLog session Aug 31 14:40:40 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:40:40 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 14:40:44 jeanremyvolumiocomble volumio[1119]: info: Starting Uninstall of plugin user_interface - now_playing Aug 31 14:40:47 jeanremyvolumiocomble systemd[1]: systemd-timedated.service: Deactivated successfully. Aug 31 14:40:56 jeanremyvolumiocomble sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 14:40:56 jeanremyvolumiocomble sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 14:40:56 jeanremyvolumiocomble sudo[3019]: pam_unix(sudo:session): session closed for user root Aug 31 14:40:56 jeanremyvolumiocomble sudo[3021]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 14:40:56 jeanremyvolumiocomble sudo[3021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 14:40:56 jeanremyvolumiocomble sudo[3021]: pam_unix(sudo:session): session closed for user root Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: verbose: New Socket.io Connection to 192.168.3.105 from 192.168.3.238 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: Received Get System Info Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: Discovery: Getting this device information Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: Listing playlists Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 14:40:56 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 14:40:57 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: Received Get System Info Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: Discovery: Getting this device information Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: Received Get System Info Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: Discovery: Getting this device information Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::volumioGetState Aug 31 14:40:58 jeanremyvolumiocomble volumio[1119]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: info: Enabling plugin now_playing Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: info: Loading plugin "now_playing"... Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: error: !!!! WARNING !!!! Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins//user_interface/now_playing/./dist/index.js' Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: Require stack: Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: - /volumio/app/pluginmanager.js Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: - /volumio/app/index.js Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: - /volumio/index.js Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: error: Stack trace: Error: Cannot find module '/data/plugins//user_interface/now_playing/./dist/index.js' Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: Require stack: Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: - /volumio/app/pluginmanager.js Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: - /volumio/app/index.js Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: - /volumio/index.js Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at Module._load (node:internal/modules/cjs/loader:901:27) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at Module.require (node:internal/modules/cjs/loader:1115:19) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at require (node:internal/modules/helpers:130:18) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: error: !!!! WARNING !!!! Aug 31 14:40:59 jeanremyvolumiocomble volumio[1119]: info: Done. Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at /volumio/app/index.js:1534:32 Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at Socket. (/volumio/app/plugins/system_controller/system/index.js:1555:28) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at Socket.emit (node:events:514:28) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at addChunk (node:internal/streams/readable:343:12) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at readableAddChunk (node:internal/streams/readable:316:9) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at Readable.push (node:internal/streams/readable:253:10) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 31 14:41:00 jeanremyvolumiocomble volumio[1119]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 14:41:00 jeanremyvolumiocomble sudo[3066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-31 14:40' Aug 31 14:41:00 jeanremyvolumiocomble sudo[3066]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="30d63a8c53d2826bd7f262e22b4e4511a4cc9d01" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 30 06:39:32 UTC 2025" VOLUMIO_VERSION="4.022" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e7b0bd0cb5661ea77402e5bd121ec64a"