-- Logs begin at Tue 2024-09-17 17:06:55 CEST, end at Fri 2024-10-04 17:31:43 CEST. -- Oct 04 17:30:00 volumio volumio[728]: info: Mounting Device MUSIC Oct 04 17:30:01 volumio sudo[2518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/MUSIC -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 04 17:30:01 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 17:30:01 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 04 17:30:01 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 04 17:30:01 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root Oct 04 17:30:03 volumio volumio[728]: info: Scanning removed location : "USB/MUSIC" Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand status Oct 04 17:30:04 volumio volumio[728]: info: Oct 04 17:30:04 volumio volumio[728]: ---------------------------- MPD announces state update: update Oct 04 17:30:04 volumio volumio[728]: info: ControllerMpd::getState Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand status Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand status Oct 04 17:30:04 volumio volumio[728]: info: Oct 04 17:30:04 volumio volumio[728]: ---------------------------- MPD announces state update: update Oct 04 17:30:04 volumio volumio[728]: info: ControllerMpd::getState Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand status Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand status Oct 04 17:30:04 volumio volumio[728]: info: Oct 04 17:30:04 volumio volumio[728]: ---------------------------- MPD announces state update: update Oct 04 17:30:04 volumio volumio[728]: info: ControllerMpd::getState Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand status Oct 04 17:30:04 volumio volumio[728]: info: sendMpdCommand status took 425 milliseconds Oct 04 17:30:04 volumio volumio[728]: info: sendMpdCommand status took 389 milliseconds Oct 04 17:30:04 volumio volumio[728]: info: sendMpdCommand status took 346 milliseconds Oct 04 17:30:04 volumio volumio[728]: info: sendMpdCommand status took 293 milliseconds Oct 04 17:30:04 volumio volumio[728]: info: sendMpdCommand status took 285 milliseconds Oct 04 17:30:04 volumio volumio[728]: info: sendMpdCommand status took 219 milliseconds Oct 04 17:30:04 volumio volumio[728]: verbose: ControllerMpd::parseState Oct 04 17:30:05 volumio volumio[728]: info: Command Router : Notfying DB Updatetrue Oct 04 17:30:06 volumio volumio[728]: verbose: ControllerMpd::parseState Oct 04 17:30:06 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 17:30:06 volumio volumio[728]: verbose: ControllerMpd::parseState Oct 04 17:30:07 volumio volumio[728]: info: Command Router : Notfying DB Updatetrue Oct 04 17:30:07 volumio volumio[728]: info: CoreCommandRouter::Close All Modals sent Oct 04 17:30:07 volumio volumio[728]: verbose: ControllerMpd::parseState Oct 04 17:30:07 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 17:30:07 volumio volumio[728]: verbose: ControllerMpd::parseState Oct 04 17:30:07 volumio volumio[728]: info: Command Router : Notfying DB Updatetrue Oct 04 17:30:08 volumio volumio[728]: info: CoreCommandRouter::Close All Modals sent Oct 04 17:30:08 volumio volumio[728]: verbose: ControllerMpd::parseState Oct 04 17:30:08 volumio volumio[728]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 04 17:30:09 volumio volumio[728]: info: sendMpdCommand playlistinfo took 2770 milliseconds Oct 04 17:30:09 volumio volumio[728]: verbose: ControllerMpd::parseTrackInfo Oct 04 17:30:09 volumio volumio[728]: info: ControllerMpd::pushState Oct 04 17:30:09 volumio volumio[728]: info: CoreCommandRouter::servicePushState Oct 04 17:30:09 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:09 volumio volumio[728]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Tracy Chapman","uri":"USB/MUSIC/Tracy Chapman - Tracy Chapman/02 - Fast Car.flac","trackType":"flac"} Oct 04 17:30:09 volumio volumio[728]: verbose: CURRENT POSITION 0 Oct 04 17:30:09 volumio volumio[728]: info: CoreStateMachine::syncState stateService pause Oct 04 17:30:09 volumio volumio[728]: info: CoreStateMachine::syncState currentStatus stop Oct 04 17:30:09 volumio volumio[728]: info: CoreStateMachine::pushState Oct 04 17:30:09 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:09 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 17:30:09 volumio volumio[728]: info: CoreCommandRouter::volumioPushState Oct 04 17:30:09 volumio volumio[728]: info: ------------------------------ 5901ms Oct 04 17:30:11 volumio volumio[728]: info: sendMpdCommand playlistinfo took 4204 milliseconds Oct 04 17:30:11 volumio volumio[728]: info: sendMpdCommand playlistinfo took 3350 milliseconds Oct 04 17:30:11 volumio volumio[728]: verbose: ControllerMpd::parseTrackInfo Oct 04 17:30:11 volumio volumio[728]: verbose: ControllerMpd::parseTrackInfo Oct 04 17:30:11 volumio volumio[728]: info: ControllerMpd::pushState Oct 04 17:30:11 volumio volumio[728]: info: CoreCommandRouter::servicePushState Oct 04 17:30:11 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:11 volumio volumio[728]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Tracy Chapman","uri":"USB/MUSIC/Tracy Chapman - Tracy Chapman/02 - Fast Car.flac","trackType":"flac"} Oct 04 17:30:11 volumio volumio[728]: verbose: CURRENT POSITION 0 Oct 04 17:30:11 volumio volumio[728]: info: CoreStateMachine::syncState stateService pause Oct 04 17:30:12 volumio volumio[728]: info: CoreStateMachine::syncState currentStatus stop Oct 04 17:30:12 volumio volumio[728]: info: CoreStateMachine::pushState Oct 04 17:30:12 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:12 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 04 17:30:12 volumio volumio[728]: info: CoreCommandRouter::volumioPushState Oct 04 17:30:12 volumio volumio[728]: info: ControllerMpd::pushState Oct 04 17:30:12 volumio volumio[728]: info: CoreCommandRouter::servicePushState Oct 04 17:30:12 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:12 volumio volumio[728]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Tracy Chapman","uri":"USB/MUSIC/Tracy Chapman - Tracy Chapman/02 - Fast Car.flac","trackType":"flac"} Oct 04 17:30:12 volumio volumio[728]: verbose: CURRENT POSITION 0 Oct 04 17:30:12 volumio volumio[728]: info: CoreStateMachine::syncState stateService pause Oct 04 17:30:12 volumio volumio[728]: info: CoreStateMachine::syncState currentStatus stop Oct 04 17:30:12 volumio volumio[728]: info: CoreStateMachine::pushState Oct 04 17:30:12 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:12 volumio volumio[728]: info: CoreCommandRouter::volumioPushState Oct 04 17:30:14 volumio volumio[728]: info: ------------------------------ 9841ms Oct 04 17:30:14 volumio volumio[728]: info: ------------------------------ 9851ms Oct 04 17:30:33 volumio volumio[728]: verbose: New Socket.io Connection to 192.168.2.2 from 192.168.2.8 UA: Mozilla/5.0 (Android 12; Mobile; rv:130.0) Gecko/130.0 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 04 17:30:33 volumio volumio[728]: verbose: New Socket.io Connection to 192.168.2.2 from 192.168.2.8 UA: Mozilla/5.0 (Android 12; Mobile; rv:130.0) Gecko/130.0 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 04 17:30:36 volumio volumio[728]: verbose: New Socket.io Connection to 192.168.2.2 from 192.168.2.8 UA: Mozilla/5.0 (Android 12; Mobile; rv:130.0) Gecko/130.0 Firefox/130.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 04 17:30:36 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:30:37 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 17:30:37 volumio volumio[728]: info: Received Get System Info Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 17:30:37 volumio volumio[728]: info: Discovery: Getting this device information Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:30:37 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:30:37 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:37 volumio volumio[728]: info: Listing playlists Oct 04 17:30:37 volumio volumio[728]: info: CoreCommandRouter::volumioGetQueue Oct 04 17:30:37 volumio volumio[728]: info: CoreStateMachine::getQueue Oct 04 17:30:37 volumio volumio[728]: info: CorePlayQueue::getQueue Oct 04 17:30:38 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:30:38 volumio volumio[728]: info: CorePlayQueue::getTrack 1 Oct 04 17:30:38 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 04 17:30:51 volumio volumio[728]: info: Enabling plugin now_playing Oct 04 17:30:51 volumio volumio[728]: info: Loading plugin "now_playing"... Oct 04 17:30:58 volumio volumio[728]: error: !!!! WARNING !!!! Oct 04 17:30:58 volumio volumio[728]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Oct 04 17:30:58 volumio volumio[728]: Require stack: Oct 04 17:30:58 volumio volumio[728]: - /data/plugins/user_interface/now_playing/dist/index.js Oct 04 17:30:58 volumio volumio[728]: - /volumio/app/pluginmanager.js Oct 04 17:30:58 volumio volumio[728]: - /volumio/app/index.js Oct 04 17:30:58 volumio volumio[728]: - /volumio/index.js Oct 04 17:30:58 volumio volumio[728]: error: Stack trace: Error: Cannot find module 'geo-tz' Oct 04 17:30:58 volumio volumio[728]: Require stack: Oct 04 17:30:58 volumio volumio[728]: - /data/plugins/user_interface/now_playing/dist/index.js Oct 04 17:30:58 volumio volumio[728]: - /volumio/app/pluginmanager.js Oct 04 17:30:58 volumio volumio[728]: - /volumio/app/index.js Oct 04 17:30:58 volumio volumio[728]: - /volumio/index.js Oct 04 17:30:58 volumio volumio[728]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Oct 04 17:30:58 volumio volumio[728]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Oct 04 17:30:58 volumio volumio[728]: at Module.require (internal/modules/cjs/loader.js:952:19) Oct 04 17:30:58 volumio volumio[728]: at require (internal/modules/cjs/helpers.js:88:18) Oct 04 17:30:58 volumio volumio[728]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Oct 04 17:30:58 volumio volumio[728]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Oct 04 17:30:58 volumio volumio[728]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Oct 04 17:30:58 volumio volumio[728]: at Module.load (internal/modules/cjs/loader.js:928:32) Oct 04 17:30:58 volumio volumio[728]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Oct 04 17:30:58 volumio volumio[728]: at Module.require (internal/modules/cjs/loader.js:952:19) Oct 04 17:30:58 volumio volumio[728]: at require (internal/modules/cjs/helpers.js:88:18) Oct 04 17:30:58 volumio volumio[728]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Oct 04 17:30:58 volumio volumio[728]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 04 17:30:58 volumio volumio[728]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 04 17:30:58 volumio volumio[728]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 04 17:30:58 volumio volumio[728]: error: !!!! WARNING !!!! Oct 04 17:30:59 volumio volumio[728]: info: Done. Oct 04 17:31:01 volumio volumio[728]: verbose: New Socket.io Connection to 192.168.2.2 from 192.168.2.10 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.13; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 04 17:31:03 volumio volumio[728]: verbose: New Socket.io Connection to 192.168.2.2 from 192.168.2.10 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.13; rv:109.0) Gecko/20100101 Firefox/115.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:31:04 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:31:04 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 17:31:04 volumio volumio[728]: info: Received Get System Info Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 17:31:04 volumio volumio[728]: info: Discovery: Getting this device information Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:31:04 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 17:31:04 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:31:04 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:31:04 volumio volumio[728]: info: Listing playlists Oct 04 17:31:04 volumio volumio[728]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 04 17:31:05 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 04 17:31:05 volumio volumio[728]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 04 17:31:07 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 17:31:07 volumio volumio[728]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 04 17:31:07 volumio volumio[728]: info: CoreCommandRouter::volumioGetState Oct 04 17:31:07 volumio volumio[728]: info: CorePlayQueue::getTrack 0 Oct 04 17:31:22 volumio volumio[728]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/touch_display/volumio/buster/armhf Oct 04 17:31:23 volumio volumio[728]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 17:31:23 volumio volumio[728]: TypeError: Cannot read property 'broadcastMessage' of null Oct 04 17:31:23 volumio volumio[728]: at /volumio/app/index.js:1515:32 Oct 04 17:31:23 volumio volumio[728]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 04 17:31:23 volumio volumio[728]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 04 17:31:23 volumio volumio[728]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1513:13) Oct 04 17:31:23 volumio volumio[728]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Oct 04 17:31:23 volumio volumio[728]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Oct 04 17:31:23 volumio volumio[728]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1434:22) Oct 04 17:31:23 volumio volumio[728]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1195:47) Oct 04 17:31:23 volumio volumio[728]: at Socket.emit (events.js:315:20) Oct 04 17:31:23 volumio volumio[728]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 04 17:31:23 volumio volumio[728]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 04 17:31:23 volumio volumio[728]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 17:31:43 volumio sudo[2626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 17:30 Oct 04 17:31:43 volumio sudo[2626]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"