-- Logs begin at Fri 2024-08-23 21:06:28 EDT, end at Sat 2024-08-24 04:15:27 EDT. -- Aug 24 04:14:00 volumio-vm volumio[1002]: info: AutoStart - Plugin is starting Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetQueue Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::getQueue Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getQueue Aug 24 04:14:00 volumio-vm volumio[1002]: info: AutoStart - start playing Aug 24 04:14:00 volumio-vm volumio[1002]: info: AutoStart - start playing with no specific position Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPlay Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::play index 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::stop Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::stPlaybackTimer Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::updateTrackBlock Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrackBlock Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::pushState Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPushState Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::serviceStop Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::serviceStop Aug 24 04:14:00 volumio-vm volumio[1002]: info: [1724487240076] ControllerWebradio::stop Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 04:14:00 volumio-vm volumio[1002]: info: Aug 24 04:14:00 volumio-vm volumio[1002]: ---------------------------- MPD announces state update: player Aug 24 04:14:00 volumio-vm volumio[1002]: info: sendMpdCommand stop took 9 milliseconds Aug 24 04:14:00 volumio-vm volumio[1002]: info: ControllerMpd::getState Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::play index undefined Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::startPlaybackTimer Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: [1724487240087] ControllerWebradio::clearAddPlayTrack Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 04:14:00 volumio-vm volumio[1002]: info: sendMpdCommand status took 2 milliseconds Aug 24 04:14:00 volumio-vm volumio[1002]: info: sendMpdCommand stop took 0 milliseconds Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::parseState Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear Aug 24 04:14:00 volumio-vm volumio[1002]: info: Aug 24 04:14:00 volumio-vm volumio[1002]: ---------------------------- MPD announces system playlist update Aug 24 04:14:00 volumio-vm volumio[1002]: info: Ignoring MPD Status Update Aug 24 04:14:00 volumio-vm volumio[1002]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 24 04:14:00 volumio-vm volumio[1002]: info: sendMpdCommand clear took 0 milliseconds Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::parseTrackInfo Aug 24 04:14:00 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand load "https://pianosolo.streamguys1.com/live" Aug 24 04:14:00 volumio-vm volumio[1002]: info: ControllerMpd::pushState Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::servicePushState Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreStateMachine::pushState Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPushState Aug 24 04:14:00 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:00 volumio-vm volumio[1002]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 24 04:14:00 volumio-vm volumio[1002]: info: ------------------------------ 5ms Aug 24 04:14:00 volumio-vm volumio[1002]: error: updateQueue error: null Aug 24 04:14:00 volumio-vm volumio[1002]: info: ------------------------------ 3ms Aug 24 04:14:02 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "https://pianosolo.streamguys1.com/live" Aug 24 04:14:02 volumio-vm volumio[1002]: info: Aug 24 04:14:02 volumio-vm volumio[1002]: ---------------------------- MPD announces system playlist update Aug 24 04:14:02 volumio-vm volumio[1002]: info: Ignoring MPD Status Update Aug 24 04:14:02 volumio-vm volumio[1002]: info: sendMpdCommand add "https://pianosolo.streamguys1.com/live" took 1 milliseconds Aug 24 04:14:02 volumio-vm volumio[1002]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 24 04:14:02 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand play Aug 24 04:14:02 volumio-vm volumio[1002]: info: ------------------------------ 0ms Aug 24 04:14:02 volumio-vm volumio[1002]: info: sendMpdCommand play took 0 milliseconds Aug 24 04:14:03 volumio-vm volumio[1002]: info: Aug 24 04:14:03 volumio-vm volumio[1002]: ---------------------------- MPD announces state update: player Aug 24 04:14:03 volumio-vm volumio[1002]: info: ControllerMpd::getState Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Aug 24 04:14:03 volumio-vm volumio[1002]: info: Aug 24 04:14:03 volumio-vm volumio[1002]: ---------------------------- MPD announces system playlist update Aug 24 04:14:03 volumio-vm volumio[1002]: info: Ignoring MPD Status Update Aug 24 04:14:03 volumio-vm volumio[1002]: info: Aug 24 04:14:03 volumio-vm volumio[1002]: ---------------------------- MPD announces state update: player Aug 24 04:14:03 volumio-vm volumio[1002]: info: sendMpdCommand status took 19 milliseconds Aug 24 04:14:03 volumio-vm volumio[1002]: info: ControllerMpd::getState Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::parseState Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 04:14:03 volumio-vm volumio[1002]: info: ------------------------------ 1ms Aug 24 04:14:03 volumio-vm volumio[1002]: info: sendMpdCommand status took 0 milliseconds Aug 24 04:14:03 volumio-vm volumio[1002]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::parseState Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::parseTrackInfo Aug 24 04:14:03 volumio-vm volumio[1002]: info: ControllerMpd::pushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreCommandRouter::servicePushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Zsolt Pataki - A Dream Come True","artist":"Whisperings: Solo Piano Radio","album":null,"uri":"https://pianosolo.streamguys1.com/live","trackType":"com/live"} Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: CURRENT POSITION 0 Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreStateMachine::syncState stateService play Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Aug 24 04:14:03 volumio-vm volumio[1002]: info: ------------------------------ 20ms Aug 24 04:14:03 volumio-vm volumio[1002]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: ControllerMpd::parseTrackInfo Aug 24 04:14:03 volumio-vm volumio[1002]: info: ControllerMpd::pushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreCommandRouter::servicePushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Zsolt Pataki - A Dream Come True","artist":"Whisperings: Solo Piano Radio","album":null,"uri":"https://pianosolo.streamguys1.com/live","trackType":"com/live"} Aug 24 04:14:03 volumio-vm volumio[1002]: verbose: CURRENT POSITION 0 Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreStateMachine::syncState stateService play Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreStateMachine::syncState currentStatus play Aug 24 04:14:03 volumio-vm volumio[1002]: info: Received an update from plugin. extracting info from payload Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreStateMachine::pushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreStateMachine::pushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPushState Aug 24 04:14:03 volumio-vm volumio[1002]: info: ------------------------------ 3ms Aug 24 04:14:08 volumio-vm volumio[1002]: info: Aug 24 04:14:08 volumio-vm volumio[1002]: ---------------------------- MPD announces system playlist update Aug 24 04:14:08 volumio-vm volumio[1002]: info: Ignoring MPD Status Update Aug 24 04:14:08 volumio-vm volumio[1002]: info: Aug 24 04:14:08 volumio-vm volumio[1002]: ---------------------------- MPD announces state update: player Aug 24 04:14:08 volumio-vm volumio[1002]: info: ControllerMpd::getState Aug 24 04:14:08 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Aug 24 04:14:08 volumio-vm volumio[1002]: info: ------------------------------ 0ms Aug 24 04:14:08 volumio-vm volumio[1002]: info: sendMpdCommand status took 0 milliseconds Aug 24 04:14:08 volumio-vm volumio[1002]: verbose: ControllerMpd::parseState Aug 24 04:14:08 volumio-vm volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 24 04:14:08 volumio-vm volumio[1002]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 24 04:14:08 volumio-vm volumio[1002]: verbose: ControllerMpd::parseTrackInfo Aug 24 04:14:08 volumio-vm volumio[1002]: info: ControllerMpd::pushState Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreCommandRouter::servicePushState Aug 24 04:14:08 volumio-vm volumio[1002]: info: CorePlayQueue::getTrack 0 Aug 24 04:14:08 volumio-vm volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6112,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Jo Jasper - Bleibender Moment","artist":"Whisperings: Solo Piano Radio","album":null,"uri":"https://pianosolo.streamguys1.com/live","trackType":"com/live"} Aug 24 04:14:08 volumio-vm volumio[1002]: verbose: CURRENT POSITION 0 Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreStateMachine::syncState stateService play Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreStateMachine::syncState currentStatus play Aug 24 04:14:08 volumio-vm volumio[1002]: info: Received an update from plugin. extracting info from payload Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreStateMachine::pushState Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPushState Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreStateMachine::pushState Aug 24 04:14:08 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioPushState Aug 24 04:14:08 volumio-vm volumio[1002]: info: ------------------------------ 3ms Aug 24 04:14:09 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:14:09 volumio-vm volumio[1002]: info: Listing playlists Aug 24 04:14:19 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:14:20 volumio-vm volumio[1002]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/amd64 Aug 24 04:14:21 volumio-vm volumio[1002]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/amd64 Aug 24 04:14:21 volumio-vm volumio[1002]: info: Folder /tmp/plugins removed Aug 24 04:14:21 volumio-vm volumio[1002]: info: Check plugin dependencies Aug 24 04:14:21 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 24 04:14:21 volumio-vm volumio[1002]: info: Checking if plugin already exists Aug 24 04:14:21 volumio-vm volumio[1002]: info: Rename folder Aug 24 04:14:21 volumio-vm volumio[1002]: info: Folder /tmp/downloaded_plugin.zip removed Aug 24 04:14:21 volumio-vm volumio[1002]: info: Move to category Aug 24 04:14:21 volumio-vm volumio[1002]: info: Checking if install.sh is present Aug 24 04:14:21 volumio-vm volumio[1002]: info: Executing install.sh Aug 24 04:14:21 volumio-vm sudo[15598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Aug 24 04:14:21 volumio-vm sudo[15598]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 04:14:21 volumio-vm volumio[1002]: info: Installing geo-tz node dependency Aug 24 04:14:27 volumio-vm volumio[1002]: info: Creating user directories (if not exist) Aug 24 04:14:27 volumio-vm sudo[15598]: pam_unix(sudo:session): session closed for user root Aug 24 04:14:27 volumio-vm volumio[1002]: info: Now Playing plugin installed Aug 24 04:14:27 volumio-vm volumio[1002]: Plugin install end detected on script Aug 24 04:14:27 volumio-vm volumio[1002]: info: Install script completed Aug 24 04:14:27 volumio-vm volumio[1002]: info: Adding reference to registry Aug 24 04:14:27 volumio-vm volumio[1002]: info: Done installing plugin. Aug 24 04:14:27 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 24 04:14:27 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 24 04:14:27 volumio-vm volumio[1002]: info: Folder /tmp/plugins removed Aug 24 04:14:27 volumio-vm volumio[1002]: info: Folder /tmp/downloaded_plugin.zip removed Aug 24 04:14:27 volumio-vm volumio[1002]: info: Folder /data/temp removed Aug 24 04:14:29 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:14:29 volumio-vm volumio[1002]: info: Listing playlists Aug 24 04:14:29 volumio-vm volumio[1002]: info: Enabling plugin now_playing Aug 24 04:14:29 volumio-vm volumio[1002]: info: Loading plugin "now_playing"... Aug 24 04:14:29 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:14:29 volumio-vm volumio[1002]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Aug 24 04:14:29 volumio-vm volumio[1002]: Require stack: Aug 24 04:14:29 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:14:29 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:14:29 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:14:29 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:14:29 volumio-vm volumio[1002]: error: Stack trace: Error: Cannot find module 'geo-tz' Aug 24 04:14:29 volumio-vm volumio[1002]: Require stack: Aug 24 04:14:29 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:14:29 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:14:29 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:14:29 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:14:29 volumio-vm volumio[1002]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Aug 24 04:14:29 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Aug 24 04:14:29 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:14:29 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:14:29 volumio-vm volumio[1002]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Aug 24 04:14:29 volumio-vm volumio[1002]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Aug 24 04:14:29 volumio-vm volumio[1002]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Aug 24 04:14:29 volumio-vm volumio[1002]: at Module.load (internal/modules/cjs/loader.js:979:32) Aug 24 04:14:29 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Aug 24 04:14:29 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:14:29 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:14:29 volumio-vm volumio[1002]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 24 04:14:29 volumio-vm volumio[1002]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 24 04:14:29 volumio-vm volumio[1002]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 24 04:14:29 volumio-vm volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 24 04:14:29 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:14:29 volumio-vm volumio[1002]: info: Done. Aug 24 04:14:39 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:14:40 volumio-vm volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 24 04:14:43 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 24 04:14:43 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 24 04:14:46 volumio-vm volumio[1002]: info: Starting Uninstall of plugin user_interface - now_playing Aug 24 04:14:46 volumio-vm volumio[1002]: info: Uninstalling plugin now_playing Aug 24 04:14:49 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:14:49 volumio-vm volumio[1002]: info: Listing playlists Aug 24 04:14:49 volumio-vm volumio[1002]: info: Listing playlists Aug 24 04:14:51 volumio-vm volumio[1002]: info: Starting Uninstall of plugin user_interface - now_playing Aug 24 04:14:51 volumio-vm volumio[1002]: info: Uninstalling plugin now_playing Aug 24 04:14:52 volumio-vm volumio[1002]: info: Enabling plugin now_playing Aug 24 04:14:52 volumio-vm volumio[1002]: info: Loading plugin "now_playing"... Aug 24 04:14:52 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:14:52 volumio-vm volumio[1002]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Aug 24 04:14:52 volumio-vm volumio[1002]: Require stack: Aug 24 04:14:52 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:14:52 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:14:52 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:14:52 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:14:52 volumio-vm volumio[1002]: error: Stack trace: Error: Cannot find module 'geo-tz' Aug 24 04:14:52 volumio-vm volumio[1002]: Require stack: Aug 24 04:14:52 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:14:52 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:14:52 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:14:52 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:14:52 volumio-vm volumio[1002]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Aug 24 04:14:52 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Aug 24 04:14:52 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:14:52 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:14:52 volumio-vm volumio[1002]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Aug 24 04:14:52 volumio-vm volumio[1002]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Aug 24 04:14:52 volumio-vm volumio[1002]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Aug 24 04:14:52 volumio-vm volumio[1002]: at Module.load (internal/modules/cjs/loader.js:979:32) Aug 24 04:14:52 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Aug 24 04:14:52 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:14:52 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:14:52 volumio-vm volumio[1002]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 24 04:14:52 volumio-vm volumio[1002]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 24 04:14:52 volumio-vm volumio[1002]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 24 04:14:52 volumio-vm volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 24 04:14:52 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:14:52 volumio-vm volumio[1002]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Aug 24 04:14:57 volumio-vm volumio[1002]: info: Starting Uninstall of plugin user_interface - now_playing Aug 24 04:14:57 volumio-vm volumio[1002]: info: Uninstalling plugin now_playing Aug 24 04:15:00 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:15:00 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:15:01 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 24 04:15:01 volumio-vm volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 24 04:15:03 volumio-vm volumio[1002]: info: Enabling plugin now_playing Aug 24 04:15:03 volumio-vm volumio[1002]: info: Loading plugin "now_playing"... Aug 24 04:15:03 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:15:03 volumio-vm volumio[1002]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Aug 24 04:15:03 volumio-vm volumio[1002]: Require stack: Aug 24 04:15:03 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:15:03 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:15:03 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:15:03 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:15:03 volumio-vm volumio[1002]: error: Stack trace: Error: Cannot find module 'geo-tz' Aug 24 04:15:03 volumio-vm volumio[1002]: Require stack: Aug 24 04:15:03 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:15:03 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:15:03 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:15:03 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:15:03 volumio-vm volumio[1002]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Aug 24 04:15:03 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Aug 24 04:15:03 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:15:03 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:15:03 volumio-vm volumio[1002]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Aug 24 04:15:03 volumio-vm volumio[1002]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Aug 24 04:15:03 volumio-vm volumio[1002]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Aug 24 04:15:03 volumio-vm volumio[1002]: at Module.load (internal/modules/cjs/loader.js:979:32) Aug 24 04:15:03 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Aug 24 04:15:03 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:15:03 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:15:03 volumio-vm volumio[1002]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 24 04:15:03 volumio-vm volumio[1002]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 24 04:15:03 volumio-vm volumio[1002]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 24 04:15:03 volumio-vm volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 24 04:15:03 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:15:03 volumio-vm volumio[1002]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Aug 24 04:15:05 volumio-vm volumio[1002]: info: Enabling plugin now_playing Aug 24 04:15:05 volumio-vm volumio[1002]: info: Loading plugin "now_playing"... Aug 24 04:15:05 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:15:05 volumio-vm volumio[1002]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Aug 24 04:15:05 volumio-vm volumio[1002]: Require stack: Aug 24 04:15:05 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:15:05 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:15:05 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:15:05 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:15:05 volumio-vm volumio[1002]: error: Stack trace: Error: Cannot find module 'geo-tz' Aug 24 04:15:05 volumio-vm volumio[1002]: Require stack: Aug 24 04:15:05 volumio-vm volumio[1002]: - /data/plugins/user_interface/now_playing/dist/index.js Aug 24 04:15:05 volumio-vm volumio[1002]: - /volumio/app/pluginmanager.js Aug 24 04:15:05 volumio-vm volumio[1002]: - /volumio/app/index.js Aug 24 04:15:05 volumio-vm volumio[1002]: - /volumio/index.js Aug 24 04:15:05 volumio-vm volumio[1002]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Aug 24 04:15:05 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Aug 24 04:15:05 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:15:05 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:15:05 volumio-vm volumio[1002]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Aug 24 04:15:05 volumio-vm volumio[1002]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Aug 24 04:15:05 volumio-vm volumio[1002]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Aug 24 04:15:05 volumio-vm volumio[1002]: at Module.load (internal/modules/cjs/loader.js:979:32) Aug 24 04:15:05 volumio-vm volumio[1002]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Aug 24 04:15:05 volumio-vm volumio[1002]: at Module.require (internal/modules/cjs/loader.js:1003:19) Aug 24 04:15:05 volumio-vm volumio[1002]: at require (internal/modules/cjs/helpers.js:107:18) Aug 24 04:15:05 volumio-vm volumio[1002]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Aug 24 04:15:05 volumio-vm volumio[1002]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 24 04:15:05 volumio-vm volumio[1002]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 24 04:15:05 volumio-vm volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 24 04:15:05 volumio-vm volumio[1002]: error: !!!! WARNING !!!! Aug 24 04:15:05 volumio-vm volumio[1002]: info: Error: TypeError: Cannot read property 'printToastMessage' of null Aug 24 04:15:10 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:15:10 volumio-vm volumio[1002]: info: Listing playlists Aug 24 04:15:10 volumio-vm volumio[1002]: info: Listing playlists Aug 24 04:15:20 volumio-vm volumio[1002]: info: CoreCommandRouter::volumioGetState Aug 24 04:15:27 volumio-vm volumio[1002]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/buster/amd64 Aug 24 04:15:27 volumio-vm volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 04:15:27 volumio-vm volumio[1002]: TypeError: Cannot read property 'broadcastMessage' of null Aug 24 04:15:27 volumio-vm volumio[1002]: at /volumio/app/index.js:1515:32 Aug 24 04:15:27 volumio-vm volumio[1002]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 24 04:15:27 volumio-vm volumio[1002]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 24 04:15:27 volumio-vm volumio[1002]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1513:13) Aug 24 04:15:27 volumio-vm volumio[1002]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Aug 24 04:15:27 volumio-vm volumio[1002]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Aug 24 04:15:27 volumio-vm volumio[1002]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1434:22) Aug 24 04:15:27 volumio-vm volumio[1002]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1195:47) Aug 24 04:15:27 volumio-vm volumio[1002]: at Socket.emit (events.js:400:28) Aug 24 04:15:27 volumio-vm volumio[1002]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 24 04:15:27 volumio-vm volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 24 04:15:27 volumio-vm volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 04:15:27 volumio-vm sudo[15738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 04:14 Aug 24 04:15:27 volumio-vm sudo[15738]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"