Feb 06 19:45:03 volumio sudo[2025]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 06 19:45:03 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:03 volumio sudo[2025]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:03 volumio sudo[2027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 06 19:45:03 volumio sudo[2027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:03 volumio sudo[2027]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:03 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 06 19:45:03 volumio volumio[1251]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 06 19:45:03 volumio volumio[1251]: info: Completed starting MyVolumio Plugin Feb 06 19:45:03 volumio volumio[1251]: [Metrics] CommandRouter: 107s 36.71ms Feb 06 19:45:03 volumio volumio[1251]: info: CoreCommandRouter::volumiosetStartupVolume Feb 06 19:45:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 06 19:45:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 06 19:45:03 volumio volumio[1251]: info: CoreCommandRouter::Close All Modals sent Feb 06 19:45:04 volumio volumio[1251]: info: CoreCommandRouter::Close All Modals sent Feb 06 19:45:04 volumio sudo[2035]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 06 19:45:04 volumio sudo[2035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:04 volumio sudo[2033]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 06 19:45:04 volumio sudo[2035]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:04 volumio volumio[1251]: info: AutoStart - Check #9/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 06 19:45:04 volumio sudo[2033]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:04 volumio sudo[2033]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 06 19:45:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 06 19:45:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 06 19:45:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 06 19:45:05 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 06 19:45:05 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 06 19:45:06 volumio sudo[2045]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 06 19:45:06 volumio sudo[2045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:06 volumio sudo[2045]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:06 volumio sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 06 19:45:06 volumio sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:06 volumio sudo[2043]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:06 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 06 19:45:06 volumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 06 19:45:06 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:07 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 06 19:45:07 volumio sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 06 19:45:07 volumio sudo[2051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:07 volumio sudo[2051]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:07 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 06 19:45:07 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetVisibleSources Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:08 volumio sudo[2070]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 06 19:45:08 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 06 19:45:08 volumio sudo[2072]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 06 19:45:08 volumio sudo[2072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 06 19:45:08 volumio sudo[2072]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:08 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:08 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:08 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:08 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Feb 06 19:45:08 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:09 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:09 volumio volumio[1251]: info: Listing playlists Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:09 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:09 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:09 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:09 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:09 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:09 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:09 volumio volumio[1251]: info: AutoStart - Check #10/60 - VOLUMIO_SYSTEM_STATUS = starting Feb 06 19:45:10 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Feb 06 19:45:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:10 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:10 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:10 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:10 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:10 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:10 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 06 19:45:11 volumio volumio[1251]: info: BOOT COMPLETED Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetVisibleSources Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 06 19:45:11 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:11 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:11 volumio volumio[1251]: info: Listing playlists Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:11 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:11 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:11 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 06 19:45:11 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:12 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:12 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:12 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:12 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:45:14 volumio volumio[1251]: info: AutoStart - Check #11/60 - VOLUMIO_SYSTEM_STATUS = ready Feb 06 19:45:14 volumio volumio[1251]: info: AutoStart - System ready state CONFIRMED after 11 checks Feb 06 19:45:14 volumio volumio[1251]: info: AutoStart - Setting startup volume to 50 Feb 06 19:45:15 volumio volumio[1251]: info: VolumeController::SetAlsaVolume50 Feb 06 19:45:15 volumio volumio[1251]: info: AutoStart - Startup volume set successfully to 50 Feb 06 19:45:15 volumio volumio[1251]: info: AutoStart - Applying additional delay of 5000ms before playback Feb 06 19:45:15 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 06 19:45:15 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:15 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:45:15 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 06 19:45:16 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 06 19:45:17 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 06 19:45:19 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 06 19:45:19 volumio volumio[1251]: info: Enabling plugin now_playing Feb 06 19:45:19 volumio volumio[1251]: info: Loading plugin "now_playing"... Feb 06 19:45:22 volumio volumio[1251]: error: !!!! WARNING !!!! Feb 06 19:45:22 volumio volumio[1251]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 06 19:45:22 volumio volumio[1251]: Require stack: Feb 06 19:45:22 volumio volumio[1251]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 06 19:45:22 volumio volumio[1251]: - /volumio/app/pluginmanager.js Feb 06 19:45:22 volumio volumio[1251]: - /volumio/app/index.js Feb 06 19:45:22 volumio volumio[1251]: - /volumio/index.js Feb 06 19:45:22 volumio volumio[1251]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 06 19:45:22 volumio volumio[1251]: Require stack: Feb 06 19:45:22 volumio volumio[1251]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 06 19:45:22 volumio volumio[1251]: - /volumio/app/pluginmanager.js Feb 06 19:45:22 volumio volumio[1251]: - /volumio/app/index.js Feb 06 19:45:22 volumio volumio[1251]: - /volumio/index.js Feb 06 19:45:22 volumio volumio[1251]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 06 19:45:22 volumio volumio[1251]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 06 19:45:22 volumio volumio[1251]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 06 19:45:22 volumio volumio[1251]: at require (node:internal/modules/helpers:130:18) Feb 06 19:45:22 volumio volumio[1251]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Feb 06 19:45:22 volumio volumio[1251]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 06 19:45:22 volumio volumio[1251]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 06 19:45:22 volumio volumio[1251]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 06 19:45:22 volumio volumio[1251]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 06 19:45:22 volumio volumio[1251]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 06 19:45:22 volumio volumio[1251]: at require (node:internal/modules/helpers:130:18) Feb 06 19:45:22 volumio volumio[1251]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 06 19:45:22 volumio volumio[1251]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 06 19:45:22 volumio volumio[1251]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 06 19:45:22 volumio volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 06 19:45:22 volumio volumio[1251]: error: !!!! WARNING !!!! Feb 06 19:45:22 volumio volumio[1251]: info: Done. Feb 06 19:45:22 volumio volumio[1251]: info: AutoStart - startPlayback called Feb 06 19:45:22 volumio volumio[1251]: info: CoreCommandRouter::volumioGetQueue Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::getQueue Feb 06 19:45:22 volumio volumio[1251]: info: CorePlayQueue::getQueue Feb 06 19:45:22 volumio volumio[1251]: info: AutoStart - Queue has 1 items Feb 06 19:45:22 volumio volumio[1251]: info: AutoStart - Playing from position 0 Feb 06 19:45:22 volumio volumio[1251]: info: CoreCommandRouter::volumioPlay Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::play index 0 Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::stop Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::play index undefined Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:45:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:22 volumio volumio[1251]: info: CoreStateMachine::startPlaybackTimer Feb 06 19:45:22 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:22 volumio volumio[1251]: info: [1770407122627] ControllerWebradio::clearAddPlayTrack Feb 06 19:45:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand stop Feb 06 19:45:22 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:45:22 volumio volumio[1251]: info: sendMpdCommand stop took 106 milliseconds Feb 06 19:45:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand clear Feb 06 19:45:22 volumio volumio[1251]: info: Feb 06 19:45:22 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:22 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:22 volumio volumio[1251]: info: sendMpdCommand clear took 78 milliseconds Feb 06 19:45:22 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand load "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" Feb 06 19:45:22 volumio volumio[1251]: info: Feb 06 19:45:22 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:22 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:22 volumio volumio[1251]: info: Feb 06 19:45:22 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:22 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:23 volumio volumio[1251]: error: updateQueue error: null Feb 06 19:45:23 volumio volumio[1251]: info: ------------------------------ 205ms Feb 06 19:45:24 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 06 19:45:24 volumio volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 06 19:45:24 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 06 19:45:25 volumio volumio[1251]: error: Failed callmethod call: TypeError: Cannot read properties of null (reading 'broadcastMessage') Feb 06 19:45:25 volumio volumio[1251]: info: Received Get System Version Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 06 19:45:25 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:25 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:25 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:25 volumio volumio[1251]: error: updateQueue error: null Feb 06 19:45:25 volumio volumio[1251]: error: updateQueue error: null Feb 06 19:45:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand add "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" Feb 06 19:45:25 volumio volumio[1251]: info: ------------------------------ 2432ms Feb 06 19:45:25 volumio volumio[1251]: info: ------------------------------ 2422ms Feb 06 19:45:25 volumio volumio[1251]: Upnp client error: Error: This socket has been ended by the other party Feb 06 19:45:25 volumio volumio[1251]: info: Feb 06 19:45:25 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:25 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:25 volumio volumio[1251]: info: sendMpdCommand add "https://listen-inspirefm.sharp-stream.com/inspirefm.mp3" took 68 milliseconds Feb 06 19:45:25 volumio volumio[1251]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 06 19:45:25 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand play Feb 06 19:45:25 volumio volumio[1251]: info: Feb 06 19:45:25 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:25 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:25 volumio volumio[1251]: info: Feb 06 19:45:25 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:25 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:25 volumio volumio[1251]: info: ------------------------------ 129ms Feb 06 19:45:25 volumio volumio[1251]: info: sendMpdCommand play took 139 milliseconds Feb 06 19:45:25 volumio volumio[1251]: info: ------------------------------ 139ms Feb 06 19:45:25 volumio volumio[1251]: info: ------------------------------ 138ms Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 06 19:45:25 volumio volumio[1251]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 06 19:45:25 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 06 19:45:26 volumio volumio[1251]: error: Failed callmethod call: TypeError: Cannot read properties of null (reading 'broadcastMessage') Feb 06 19:45:26 volumio volumio[1251]: info: Received Get System Version Feb 06 19:45:26 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 06 19:45:26 volumio volumio[1251]: info: Received Get System Info Feb 06 19:45:26 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:26 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:26 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:26 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:26 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces state update: player Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::getState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces state update: player Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::getState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces state update: player Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:27 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces state update: player Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::getState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand status took 128 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::getState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand status took 90 milliseconds Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:27 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces state update: player Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::getState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces system playlist update Feb 06 19:45:27 volumio volumio[1251]: info: Ignoring MPD Status Update Feb 06 19:45:27 volumio volumio[1251]: info: Feb 06 19:45:27 volumio volumio[1251]: ---------------------------- MPD announces state update: player Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::getState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand status took 344 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: ------------------------------ 349ms Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand status took 282 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 236 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 183 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: ------------------------------ 146ms Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand status took 116 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: ------------------------------ 85ms Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand status took 63 milliseconds Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseState Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::pushState Feb 06 19:45:27 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 06 19:45:27 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:27 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 06 19:45:27 volumio volumio[1251]: verbose: CURRENT POSITION 0 Feb 06 19:45:27 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Feb 06 19:45:27 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus stop Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::pushState Feb 06 19:45:27 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 06 19:45:27 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:27 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":917,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 06 19:45:27 volumio volumio[1251]: verbose: CURRENT POSITION 0 Feb 06 19:45:27 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Feb 06 19:45:27 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:45:27 volumio volumio[1251]: info: Received an update from plugin. extracting info from payload Feb 06 19:45:27 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 06 19:45:27 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:27 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:45:27 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 06 19:45:27 volumio volumio[1251]: info: ------------------------------ 758ms Feb 06 19:45:27 volumio volumio[1251]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Feb 06 19:45:27 volumio volumio[1251]: info: ------------------------------ 808ms Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 347 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 368 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 335 milliseconds Feb 06 19:45:27 volumio volumio[1251]: info: sendMpdCommand playlistinfo took 339 milliseconds Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:45:27 volumio volumio[1251]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:45:27 volumio volumio[1251]: info: ControllerMpd::pushState Feb 06 19:45:27 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 06 19:45:27 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:27 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1054,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 06 19:45:27 volumio volumio[1251]: verbose: CURRENT POSITION 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:45:28 volumio volumio[1251]: info: Received an update from plugin. extracting info from payload Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 06 19:45:28 volumio volumio[1251]: info: ControllerMpd::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1054,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 06 19:45:28 volumio volumio[1251]: verbose: CURRENT POSITION 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:45:28 volumio volumio[1251]: info: Received an update from plugin. extracting info from payload Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 06 19:45:28 volumio volumio[1251]: info: ControllerMpd::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1192,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 06 19:45:28 volumio volumio[1251]: verbose: CURRENT POSITION 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:45:28 volumio volumio[1251]: info: Received an update from plugin. extracting info from payload Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 06 19:45:28 volumio volumio[1251]: info: ControllerMpd::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::servicePushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1192,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"inspirefm.mp3","artist":"no name","album":null,"uri":"https://listen-inspirefm.sharp-stream.com/inspirefm.mp3","trackType":"mp3"} Feb 06 19:45:28 volumio volumio[1251]: verbose: CURRENT POSITION 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState stateService play Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:45:28 volumio volumio[1251]: info: Received an update from plugin. extracting info from payload Feb 06 19:45:28 volumio volumio[1251]: info: CoreStateMachine::pushState Feb 06 19:45:28 volumio volumio[1251]: info: CorePlayQueue::getTrack 0 Feb 06 19:45:28 volumio volumio[1251]: info: CoreCommandRouter::volumioPushState Feb 06 19:45:28 volumio volumio[1251]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Feb 06 19:45:28 volumio volumio[1251]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Feb 06 19:45:28 volumio volumio[1251]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Feb 06 19:45:28 volumio volumio[1251]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Feb 06 19:45:28 volumio volumio[1251]: info: ------------------------------ 1721ms Feb 06 19:45:28 volumio volumio[1251]: info: ------------------------------ 1719ms Feb 06 19:45:28 volumio volumio[1251]: info: ------------------------------ 1514ms Feb 06 19:45:28 volumio volumio[1251]: info: ------------------------------ 1452ms Feb 06 19:45:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:29 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:45:32 volumio volumio[1251]: info: Starting Uninstall of plugin user_interface - now_playing Feb 06 19:45:32 volumio volumio[1251]: info: Uninstalling plugin now_playing Feb 06 19:45:36 volumio volumio[1251]: info: Enabling plugin now_playing Feb 06 19:45:36 volumio volumio[1251]: info: Loading plugin "now_playing"... Feb 06 19:45:36 volumio volumio[1251]: error: !!!! WARNING !!!! Feb 06 19:45:36 volumio volumio[1251]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 06 19:45:36 volumio volumio[1251]: Require stack: Feb 06 19:45:36 volumio volumio[1251]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 06 19:45:36 volumio volumio[1251]: - /volumio/app/pluginmanager.js Feb 06 19:45:36 volumio volumio[1251]: - /volumio/app/index.js Feb 06 19:45:36 volumio volumio[1251]: - /volumio/index.js Feb 06 19:45:36 volumio volumio[1251]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 06 19:45:36 volumio volumio[1251]: Require stack: Feb 06 19:45:36 volumio volumio[1251]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 06 19:45:36 volumio volumio[1251]: - /volumio/app/pluginmanager.js Feb 06 19:45:36 volumio volumio[1251]: - /volumio/app/index.js Feb 06 19:45:36 volumio volumio[1251]: - /volumio/index.js Feb 06 19:45:36 volumio volumio[1251]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 06 19:45:36 volumio volumio[1251]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 06 19:45:36 volumio volumio[1251]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 06 19:45:36 volumio volumio[1251]: at require (node:internal/modules/helpers:130:18) Feb 06 19:45:36 volumio volumio[1251]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Feb 06 19:45:36 volumio volumio[1251]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 06 19:45:36 volumio volumio[1251]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 06 19:45:36 volumio volumio[1251]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 06 19:45:36 volumio volumio[1251]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 06 19:45:36 volumio volumio[1251]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 06 19:45:36 volumio volumio[1251]: at require (node:internal/modules/helpers:130:18) Feb 06 19:45:36 volumio volumio[1251]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 06 19:45:36 volumio volumio[1251]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 06 19:45:36 volumio volumio[1251]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 06 19:45:36 volumio volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 06 19:45:36 volumio volumio[1251]: error: !!!! WARNING !!!! Feb 06 19:45:37 volumio volumio[1251]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Feb 06 19:45:39 volumio volumio[1251]: info: Starting Uninstall of plugin user_interface - now_playing Feb 06 19:45:39 volumio volumio[1251]: info: Uninstalling plugin now_playing Feb 06 19:45:42 volumio volumio[1251]: verbose: New Socket.io Connection to 192.168.5.185:3000 from 192.168.5.46 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 06 19:45:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:45:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:45:42 volumio volumio[1251]: info: Discovery: Getting this device information Feb 06 19:45:42 volumio volumio[1251]: info: CoreCommandRouter::volumioGetState Feb 06 19:45:42 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:45:43 volumio volumio[1251]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 06 19:45:46 volumio volumio[1251]: info: Starting Uninstall of plugin user_interface - now_playing Feb 06 19:45:46 volumio volumio[1251]: info: Uninstalling plugin now_playing Feb 06 19:45:48 volumio volumio[1251]: info: Enabling plugin now_playing Feb 06 19:45:48 volumio volumio[1251]: info: Loading plugin "now_playing"... Feb 06 19:45:48 volumio volumio[1251]: error: !!!! WARNING !!!! Feb 06 19:45:48 volumio volumio[1251]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 06 19:45:48 volumio volumio[1251]: Require stack: Feb 06 19:45:48 volumio volumio[1251]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 06 19:45:48 volumio volumio[1251]: - /volumio/app/pluginmanager.js Feb 06 19:45:48 volumio volumio[1251]: - /volumio/app/index.js Feb 06 19:45:48 volumio volumio[1251]: - /volumio/index.js Feb 06 19:45:48 volumio volumio[1251]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 06 19:45:48 volumio volumio[1251]: Require stack: Feb 06 19:45:48 volumio volumio[1251]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 06 19:45:48 volumio volumio[1251]: - /volumio/app/pluginmanager.js Feb 06 19:45:48 volumio volumio[1251]: - /volumio/app/index.js Feb 06 19:45:48 volumio volumio[1251]: - /volumio/index.js Feb 06 19:45:48 volumio volumio[1251]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 06 19:45:48 volumio volumio[1251]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 06 19:45:48 volumio volumio[1251]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 06 19:45:48 volumio volumio[1251]: at require (node:internal/modules/helpers:130:18) Feb 06 19:45:48 volumio volumio[1251]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Feb 06 19:45:48 volumio volumio[1251]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 06 19:45:48 volumio volumio[1251]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 06 19:45:48 volumio volumio[1251]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 06 19:45:48 volumio volumio[1251]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 06 19:45:48 volumio volumio[1251]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 06 19:45:48 volumio volumio[1251]: at require (node:internal/modules/helpers:130:18) Feb 06 19:45:48 volumio volumio[1251]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 06 19:45:48 volumio volumio[1251]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 06 19:45:48 volumio volumio[1251]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 06 19:45:48 volumio volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 06 19:45:48 volumio volumio[1251]: error: !!!! WARNING !!!! Feb 06 19:45:49 volumio volumio[1251]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Feb 06 19:45:49 volumio volumio[1251]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:45:50 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 06 19:45:50 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:45:50 volumio upmpdcli[2189]: writing RSA key Feb 06 19:46:00 volumio volumio[1251]: info: Starting Uninstall of plugin user_interface - now_playing Feb 06 19:46:00 volumio volumio[1251]: info: Uninstalling plugin now_playing Feb 06 19:46:01 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:46:02 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:46:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:46:03 volumio volumio[1251]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 06 19:46:04 volumio volumio[1251]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/1.0.2/volumio/bookworm/armhf Feb 06 19:46:04 volumio volumio[1251]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 19:46:04 volumio volumio[1251]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Feb 06 19:46:04 volumio volumio[1251]: at /volumio/app/index.js:1534:32 Feb 06 19:46:04 volumio volumio[1251]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 06 19:46:04 volumio volumio[1251]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 06 19:46:04 volumio volumio[1251]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Feb 06 19:46:04 volumio volumio[1251]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Feb 06 19:46:04 volumio volumio[1251]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Feb 06 19:46:04 volumio volumio[1251]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1453:22) Feb 06 19:46:04 volumio volumio[1251]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1220:47) Feb 06 19:46:04 volumio volumio[1251]: at Socket.emit (node:events:514:28) Feb 06 19:46:04 volumio volumio[1251]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 06 19:46:04 volumio volumio[1251]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 06 19:46:04 volumio volumio[1251]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 19:46:18 volumio sudo[2263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-06 19:45' Feb 06 19:46:18 volumio sudo[2263]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"