-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-05-29 11:30:00 CEST. -- May 29 11:29:01 volumio-2 smbd[3106]: [2025/05/29 11:29:01.010103, 0] ../source3/param/loadparm.c:3362(process_usershare_file) May 29 11:29:01 volumio-2 smbd[3106]: process_usershare_file: stat of /var/lib/samba/usershares/movie failed. No such file or directory May 29 11:29:01 volumio-2 smbd[3107]: [2025/05/29 11:29:01.067322, 0] ../source3/param/loadparm.c:3362(process_usershare_file) May 29 11:29:01 volumio-2 smbd[3107]: process_usershare_file: stat of /var/lib/samba/usershares/movie failed. No such file or directory May 29 11:29:01 volumio-2 volumio[1050]: info: May 29 11:29:01 volumio-2 volumio[1050]: ---------------------------- MPD announces system playlist update May 29 11:29:01 volumio-2 volumio[1050]: info: Ignoring MPD Status Update May 29 11:29:01 volumio-2 volumio[1050]: info: May 29 11:29:01 volumio-2 volumio[1050]: ---------------------------- MPD announces state update: player May 29 11:29:01 volumio-2 volumio[1050]: info: ControllerMpd::getState May 29 11:29:01 volumio-2 volumio[1050]: verbose: ControllerMpd::sendMpdCommand status May 29 11:29:01 volumio-2 volumio[1050]: info: ------------------------------ 2ms May 29 11:29:01 volumio-2 volumio[1050]: info: sendMpdCommand status took 2 milliseconds May 29 11:29:01 volumio-2 volumio[1050]: verbose: ControllerMpd::parseState May 29 11:29:01 volumio-2 volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 11:29:01 volumio-2 volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 11:29:01 volumio-2 volumio[1050]: verbose: ControllerMpd::parseTrackInfo May 29 11:29:01 volumio-2 volumio[1050]: info: ControllerMpd::pushState May 29 11:29:01 volumio-2 volumio[1050]: info: CoreCommandRouter::servicePushState May 29 11:29:01 volumio-2 volumio[1050]: info: CorePlayQueue::getTrack 0 May 29 11:29:01 volumio-2 volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":167228,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Radio VOX FM - W rytmie hitów","artist":null,"album":null,"uri":"https://waw.ic.smcdn.pl/3990-1.mp3","trackType":"mp3"} May 29 11:29:01 volumio-2 volumio[1050]: verbose: CURRENT POSITION 0 May 29 11:29:01 volumio-2 volumio[1050]: info: CoreStateMachine::syncState stateService play May 29 11:29:01 volumio-2 volumio[1050]: info: CoreStateMachine::syncState currentStatus play May 29 11:29:01 volumio-2 volumio[1050]: info: Received an update from plugin. extracting info from payload May 29 11:29:01 volumio-2 volumio[1050]: info: CoreStateMachine::pushState May 29 11:29:01 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 11:29:01 volumio-2 volumio[1050]: info: CoreCommandRouter::volumioPushState May 29 11:29:01 volumio-2 volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'pushState' of null May 29 11:29:01 volumio-2 volumio[1050]: info: ------------------------------ 21ms May 29 11:29:01 volumio-2 volumio[1050]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 29 11:29:02 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 11:29:02 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 11:29:02 volumio-2 volumio[1050]: info: Discovery: Getting this device information May 29 11:29:02 volumio-2 volumio[1050]: info: CoreCommandRouter::volumioGetState May 29 11:29:02 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 11:29:03 volumio-2 volumio[1050]: info: Enabling plugin now_playing May 29 11:29:03 volumio-2 volumio[1050]: info: Loading plugin "now_playing"... May 29 11:29:03 volumio-2 volumio[1050]: error: !!!! WARNING !!!! May 29 11:29:03 volumio-2 volumio[1050]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' May 29 11:29:03 volumio-2 volumio[1050]: Require stack: May 29 11:29:03 volumio-2 volumio[1050]: - /data/plugins/user_interface/now_playing/dist/index.js May 29 11:29:03 volumio-2 volumio[1050]: - /volumio/app/pluginmanager.js May 29 11:29:03 volumio-2 volumio[1050]: - /volumio/app/index.js May 29 11:29:03 volumio-2 volumio[1050]: - /volumio/index.js May 29 11:29:03 volumio-2 volumio[1050]: error: Stack trace: Error: Cannot find module 'geo-tz' May 29 11:29:03 volumio-2 volumio[1050]: Require stack: May 29 11:29:03 volumio-2 volumio[1050]: - /data/plugins/user_interface/now_playing/dist/index.js May 29 11:29:03 volumio-2 volumio[1050]: - /volumio/app/pluginmanager.js May 29 11:29:03 volumio-2 volumio[1050]: - /volumio/app/index.js May 29 11:29:03 volumio-2 volumio[1050]: - /volumio/index.js May 29 11:29:03 volumio-2 volumio[1050]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) May 29 11:29:03 volumio-2 volumio[1050]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) May 29 11:29:03 volumio-2 volumio[1050]: at Module.require (internal/modules/cjs/loader.js:952:19) May 29 11:29:03 volumio-2 volumio[1050]: at require (internal/modules/cjs/helpers.js:88:18) May 29 11:29:03 volumio-2 volumio[1050]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) May 29 11:29:03 volumio-2 volumio[1050]: at Module._compile (internal/modules/cjs/loader.js:1063:30) May 29 11:29:03 volumio-2 volumio[1050]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) May 29 11:29:03 volumio-2 volumio[1050]: at Module.load (internal/modules/cjs/loader.js:928:32) May 29 11:29:03 volumio-2 volumio[1050]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) May 29 11:29:03 volumio-2 volumio[1050]: at Module.require (internal/modules/cjs/loader.js:952:19) May 29 11:29:03 volumio-2 volumio[1050]: at require (internal/modules/cjs/helpers.js:88:18) May 29 11:29:03 volumio-2 volumio[1050]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) May 29 11:29:03 volumio-2 volumio[1050]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 29 11:29:03 volumio-2 volumio[1050]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 29 11:29:03 volumio-2 volumio[1050]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 29 11:29:03 volumio-2 volumio[1050]: error: !!!! WARNING !!!! May 29 11:29:03 volumio-2 volumio[1050]: info: Error: TypeError: Cannot read property 'printToastMessage' of null May 29 11:29:04 volumio-2 volumio[1050]: info: May 29 11:29:04 volumio-2 volumio[1050]: ---------------------------- MPD announces system playlist update May 29 11:29:04 volumio-2 volumio[1050]: info: Ignoring MPD Status Update May 29 11:29:04 volumio-2 volumio[1050]: info: May 29 11:29:04 volumio-2 volumio[1050]: ---------------------------- MPD announces state update: player May 29 11:29:04 volumio-2 volumio[1050]: info: ControllerMpd::getState May 29 11:29:04 volumio-2 volumio[1050]: verbose: ControllerMpd::sendMpdCommand status May 29 11:29:04 volumio-2 volumio[1050]: info: ------------------------------ 2ms May 29 11:29:04 volumio-2 volumio[1050]: info: sendMpdCommand status took 1 milliseconds May 29 11:29:04 volumio-2 volumio[1050]: verbose: ControllerMpd::parseState May 29 11:29:04 volumio-2 volumio[1050]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 29 11:29:04 volumio-2 volumio[1050]: info: sendMpdCommand playlistinfo took 1 milliseconds May 29 11:29:05 volumio-2 volumio[1050]: verbose: ControllerMpd::parseTrackInfo May 29 11:29:05 volumio-2 volumio[1050]: info: ControllerMpd::pushState May 29 11:29:05 volumio-2 volumio[1050]: info: CoreCommandRouter::servicePushState May 29 11:29:05 volumio-2 volumio[1050]: info: CorePlayQueue::getTrack 0 May 29 11:29:05 volumio-2 volumio[1050]: verbose: STATE SERVICE {"status":"play","position":0,"seek":170228,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"FLO RIDA & SIA - Wild Ones","artist":null,"album":null,"uri":"https://waw.ic.smcdn.pl/3990-1.mp3","trackType":"mp3"} May 29 11:29:05 volumio-2 volumio[1050]: verbose: CURRENT POSITION 0 May 29 11:29:05 volumio-2 volumio[1050]: info: CoreStateMachine::syncState stateService play May 29 11:29:05 volumio-2 volumio[1050]: info: CoreStateMachine::syncState currentStatus play May 29 11:29:05 volumio-2 volumio[1050]: info: Received an update from plugin. extracting info from payload May 29 11:29:05 volumio-2 volumio[1050]: info: CoreStateMachine::pushState May 29 11:29:05 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 11:29:05 volumio-2 volumio[1050]: info: CoreCommandRouter::volumioPushState May 29 11:29:05 volumio-2 volumio[1050]: error: ControllerMpd::pushError: TypeError: Cannot read property 'pushState' of null May 29 11:29:05 volumio-2 volumio[1050]: info: ------------------------------ 26ms May 29 11:29:05 volumio-2 volumio[1050]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 May 29 11:29:05 volumio-2 go-librespot[1317]: time="2025-05-29T11:29:05+02:00" level=trace msg="received accesspoint ping" May 29 11:29:05 volumio-2 go-librespot[1317]: time="2025-05-29T11:29:05+02:00" level=trace msg="received accesspoint pong ack" May 29 11:29:09 volumio-2 go-librespot[1317]: time="2025-05-29T11:29:09+02:00" level=trace msg="sent dealer ping" May 29 11:29:09 volumio-2 go-librespot[1317]: time="2025-05-29T11:29:09+02:00" level=trace msg="received dealer pong" May 29 11:29:21 volumio-2 volumiologrotate[718]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 29 11:29:21 volumio-2 volumiologrotate[718]: ls: cannot access '2': No such file or directory May 29 11:29:27 volumio-2 volumio[1050]: info: Starting Uninstall of plugin user_interface - now_playing May 29 11:29:27 volumio-2 volumio[1050]: info: Uninstalling plugin now_playing May 29 11:29:31 volumio-2 smbd[3199]: [2025/05/29 11:29:31.172355, 0] ../source3/param/loadparm.c:3362(process_usershare_file) May 29 11:29:31 volumio-2 smbd[3199]: process_usershare_file: stat of /var/lib/samba/usershares/movie failed. No such file or directory May 29 11:29:31 volumio-2 smbd[3200]: [2025/05/29 11:29:31.234324, 0] ../source3/param/loadparm.c:3362(process_usershare_file) May 29 11:29:31 volumio-2 smbd[3200]: process_usershare_file: stat of /var/lib/samba/usershares/movie failed. No such file or directory May 29 11:29:32 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 11:29:32 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 11:29:32 volumio-2 volumio[1050]: info: Discovery: Getting this device information May 29 11:29:32 volumio-2 volumio[1050]: info: CoreCommandRouter::volumioGetState May 29 11:29:32 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 11:29:36 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:29:36 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 11:29:39 volumio-2 go-librespot[1317]: time="2025-05-29T11:29:39+02:00" level=trace msg="sent dealer ping" May 29 11:29:39 volumio-2 go-librespot[1317]: time="2025-05-29T11:29:39+02:00" level=trace msg="received dealer pong" May 29 11:29:42 volumio-2 volumio[1050]: info: CoreCommandRouter::getUIConfigOnPlugin May 29 11:29:43 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 11:29:43 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 11:29:56 volumio-2 volumio[1050]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 29 11:29:59 volumio-2 volumio[1050]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/0.7.5/volumio/buster/armhf May 29 11:29:59 volumio-2 volumio[1050]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 11:29:59 volumio-2 volumio[1050]: TypeError: Cannot read property 'broadcastMessage' of null May 29 11:29:59 volumio-2 volumio[1050]: at /volumio/app/index.js:1534:32 May 29 11:29:59 volumio-2 volumio[1050]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) May 29 11:29:59 volumio-2 volumio[1050]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) May 29 11:29:59 volumio-2 volumio[1050]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) May 29 11:29:59 volumio-2 volumio[1050]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) May 29 11:29:59 volumio-2 volumio[1050]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) May 29 11:29:59 volumio-2 volumio[1050]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1453:22) May 29 11:29:59 volumio-2 volumio[1050]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1209:47) May 29 11:29:59 volumio-2 volumio[1050]: at Socket.emit (events.js:315:20) May 29 11:29:59 volumio-2 volumio[1050]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 29 11:29:59 volumio-2 volumio[1050]: at processTicksAndRejections (internal/process/task_queues.js:75:11) May 29 11:29:59 volumio-2 volumio[1050]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 11:30:00 volumio-2 sudo[3261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-29 11:29 May 29 11:30:00 volumio-2 sudo[3261]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"