-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Wed 2025-03-05 13:53:16 EET. -- Mar 05 13:52:03 gorky volumio-remote-updater[652]: [2025-03-05 13:52:03] [connect] Successful connection Mar 05 13:52:13 gorky volumio[1063]: info: Checking if install.sh is present Mar 05 13:52:13 gorky volumio[1063]: info: Executing install.sh Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 1 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 2 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 5 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 7 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 9 Mar 05 13:52:13 gorky sudo[3422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/install.sh Mar 05 13:52:13 gorky sudo[3422]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 11 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 13 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 14 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 17 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 19 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Mar 05 13:52:13 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 21 Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::volumioGetVisibleSources Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:13 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:13 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 05 13:52:13 gorky volumio[1063]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 05 13:52:13 gorky volumio[1063]: info: Received Get System Info Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 05 13:52:13 gorky volumio[1063]: info: Discovery: Getting this device information Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:13 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:13 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:13 gorky volumio[1063]: info: Listing playlists Mar 05 13:52:13 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 05 13:52:14 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 22 Mar 05 13:52:14 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 23 Mar 05 13:52:14 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::volumioGetVisibleSources Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:14 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 05 13:52:14 gorky volumio[1063]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 05 13:52:14 gorky volumio[1063]: info: Received Get System Info Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 05 13:52:14 gorky volumio[1063]: info: Discovery: Getting this device information Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:14 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 05 13:52:14 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:14 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:14 gorky volumio[1063]: info: Listing playlists Mar 05 13:52:15 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 25 Mar 05 13:52:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 05 13:52:15 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:15 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:15 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Mar 05 13:52:15 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:15 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:15 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Mar 05 13:52:15 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 28 Mar 05 13:52:15 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:15 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:17 gorky volumio[1063]: error: error Mar 05 13:52:18 gorky volumio[1063]: error: error Mar 05 13:52:18 gorky volumio-remote-updater[652]: [2025-03-05 13:52:18] [connect] Successful connection Mar 05 13:52:18 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 29 Mar 05 13:52:18 gorky volumio-remote-updater[652]: [2025-03-05 13:52:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741175538 101 Mar 05 13:52:31 gorky volumio[1063]: info: Enabling plugin now_playing Mar 05 13:52:31 gorky volumio[1063]: info: Loading plugin "now_playing"... Mar 05 13:52:31 gorky volumio[1063]: error: !!!! WARNING !!!! Mar 05 13:52:31 gorky volumio[1063]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Mar 05 13:52:31 gorky volumio[1063]: Require stack: Mar 05 13:52:31 gorky volumio[1063]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 05 13:52:31 gorky volumio[1063]: - /volumio/app/pluginmanager.js Mar 05 13:52:31 gorky volumio[1063]: - /volumio/app/index.js Mar 05 13:52:31 gorky volumio[1063]: - /volumio/index.js Mar 05 13:52:31 gorky volumio[1063]: error: Stack trace: Error: Cannot find module 'geo-tz' Mar 05 13:52:31 gorky volumio[1063]: Require stack: Mar 05 13:52:31 gorky volumio[1063]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 05 13:52:31 gorky volumio[1063]: - /volumio/app/pluginmanager.js Mar 05 13:52:31 gorky volumio[1063]: - /volumio/app/index.js Mar 05 13:52:31 gorky volumio[1063]: - /volumio/index.js Mar 05 13:52:31 gorky volumio[1063]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:880:15) Mar 05 13:52:31 gorky volumio[1063]: at Function.Module._load (internal/modules/cjs/loader.js:725:27) Mar 05 13:52:31 gorky volumio[1063]: at Module.require (internal/modules/cjs/loader.js:952:19) Mar 05 13:52:31 gorky volumio[1063]: at require (internal/modules/cjs/helpers.js:88:18) Mar 05 13:52:31 gorky volumio[1063]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Mar 05 13:52:31 gorky volumio[1063]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Mar 05 13:52:31 gorky volumio[1063]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Mar 05 13:52:31 gorky volumio[1063]: at Module.load (internal/modules/cjs/loader.js:928:32) Mar 05 13:52:31 gorky volumio[1063]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Mar 05 13:52:31 gorky volumio[1063]: at Module.require (internal/modules/cjs/loader.js:952:19) Mar 05 13:52:31 gorky volumio[1063]: at require (internal/modules/cjs/helpers.js:88:18) Mar 05 13:52:31 gorky volumio[1063]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Mar 05 13:52:31 gorky volumio[1063]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 05 13:52:31 gorky volumio[1063]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 05 13:52:31 gorky volumio[1063]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 05 13:52:31 gorky volumio[1063]: error: !!!! WARNING !!!! Mar 05 13:52:31 gorky volumio[1063]: info: Done. Mar 05 13:52:48 gorky volumio-remote-updater[652]: [2025-03-05 13:52:48] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 05 13:52:49 gorky volumio[1063]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 05 13:52:49 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:49 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:49 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 2 Mar 05 13:52:49 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 05 13:52:49 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 4 Mar 05 13:52:49 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 05 13:52:50 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 05 13:52:50 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 05 13:52:50 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:50 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:50 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 05 13:52:50 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:50 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:50 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 05 13:52:50 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:50 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:50 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 05 13:52:51 gorky volumio[1063]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 05 13:52:51 gorky volumio[1063]: verbose: New Socket.io Connection to 192.168.0.43 from 192.168.0.14 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:135.0) Gecko/20100101 Firefox/135.0 Engine version: 3 Transport: polling Total Clients: 11 Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::volumioGetVisibleSources Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:52 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:52 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 05 13:52:52 gorky volumio[1063]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 05 13:52:52 gorky volumio[1063]: info: Received Get System Info Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 05 13:52:52 gorky volumio[1063]: info: Discovery: Getting this device information Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:52 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:52 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:52 gorky volumio[1063]: info: Listing playlists Mar 05 13:52:52 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 05 13:52:53 gorky volumio-remote-updater[652]: [2025-03-05 13:52:53] [connect] Successful connection Mar 05 13:52:53 gorky volumio-remote-updater[652]: [2025-03-05 13:52:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741175573 101 Mar 05 13:52:53 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 12 Mar 05 13:52:53 gorky volumio[1063]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::volumioGetVisibleSources Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:53 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 05 13:52:53 gorky volumio[1063]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 05 13:52:53 gorky volumio[1063]: info: Received Get System Info Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 05 13:52:53 gorky volumio[1063]: info: Discovery: Getting this device information Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:53 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 05 13:52:53 gorky volumio[1063]: info: CoreCommandRouter::volumioGetState Mar 05 13:52:53 gorky volumio[1063]: info: CorePlayQueue::getTrack 0 Mar 05 13:52:53 gorky volumio[1063]: info: Listing playlists Mar 05 13:52:54 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 05 13:53:04 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 05 13:53:04 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 05 13:53:07 gorky volumio[1063]: error: error Mar 05 13:53:07 gorky volumio[1063]: error: error Mar 05 13:53:08 gorky volumio[1063]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 05 13:53:10 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 05 13:53:10 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 05 13:53:15 gorky volumio[1063]: info: Stopping peppymeterbasic service Mar 05 13:53:15 gorky volumio[1063]: info: CoreStateMachine::stop Mar 05 13:53:15 gorky volumio[1063]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 05 13:53:15 gorky volumio[1063]: info: Disabling plugin peppymeterbasic Mar 05 13:53:15 gorky volumio[1063]: info: Preparing to generate the ALSA configuration file Mar 05 13:53:15 gorky sudo[3603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 05 13:53:15 gorky sudo[3603]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky volumio[1063]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 05 13:53:15 gorky volumio[1063]: info: Reading ALSA contributions from plugins. Mar 05 13:53:15 gorky volumio[1063]: info: Asound.conf file written Mar 05 13:53:15 gorky sudo[3603]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 05 13:53:15 gorky sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky sudo[3606]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky volumio[1063]: info: Output device has changed, restarting MPD Mar 05 13:53:15 gorky sudo[3612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 05 13:53:15 gorky sudo[3612]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky sudo[3612]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky volumio[1063]: info: Output device has changed, restarting Shairport Sync Mar 05 13:53:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 13:53:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 13:53:15 gorky sudo[3615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 05 13:53:15 gorky sudo[3615]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky systemd[1]: Stopping Music Player Daemon... Mar 05 13:53:15 gorky systemd[1]: mpd.service: Succeeded. Mar 05 13:53:15 gorky volumio[1063]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 05 13:53:15 gorky systemd[1]: Stopped Music Player Daemon. Mar 05 13:53:15 gorky volumio[1063]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 05 13:53:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 13:53:15 gorky vtcs[3169]: [2025-03-05 13:53:15.237] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Mar 05 13:53:15 gorky volumio[1063]: info: Done. Mar 05 13:53:15 gorky sudo[3624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 05 13:53:15 gorky vtcs[3169]: [2025-03-05 13:53:15.239] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Mar 05 13:53:15 gorky vtcs[3169]: [2025-03-05 13:53:15.239] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Mar 05 13:53:15 gorky systemd[1]: Starting Music Player Daemon... Mar 05 13:53:15 gorky sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 05 13:53:15 gorky sudo[3627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 05 13:53:15 gorky volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 05 13:53:15 gorky sudo[3627]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 05 13:53:15 gorky volumio[1063]: info: MPD Permissions set Mar 05 13:53:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 13:53:15 gorky systemd[1]: Stopping Volumio Tidal Connect Service... Mar 05 13:53:15 gorky systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Mar 05 13:53:15 gorky systemd[1]: vtcs.service: Succeeded. Mar 05 13:53:15 gorky systemd[1]: Stopped Volumio Tidal Connect Service. Mar 05 13:53:15 gorky sudo[3624]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky sudo[3627]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 05 13:53:15 gorky volumio[1063]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 05 13:53:15 gorky sudo[3620]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 05 13:53:15 gorky sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 05 13:53:15 gorky sudo[3620]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky volumio[1063]: info: Starting Shairport Sync Mar 05 13:53:15 gorky sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 05 13:53:15 gorky sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 05 13:53:15 gorky systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 05 13:53:15 gorky systemd[1]: shairport-sync.service: Succeeded. Mar 05 13:53:15 gorky systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 05 13:53:15 gorky systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 05 13:53:15 gorky volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 05 13:53:15 gorky volumio[1063]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 05 13:53:15 gorky sudo[3638]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:15 gorky volumio[1063]: info: Shairport-Sync Started Mar 05 13:53:16 gorky mpd[3636]: Mar 05 13:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 05 13:53:16 gorky systemd[1]: Started Music Player Daemon. Mar 05 13:53:16 gorky sudo[3615]: pam_unix(sudo:session): session closed for user root Mar 05 13:53:16 gorky volumio[1063]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 13:53:16 gorky volumio[1063]: TypeError: Cannot read property 'printToastMessage' of null Mar 05 13:53:16 gorky volumio[1063]: at /volumio/app/index.js:1214:32 Mar 05 13:53:16 gorky volumio[1063]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Mar 05 13:53:16 gorky volumio[1063]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Mar 05 13:53:16 gorky volumio[1063]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Mar 05 13:53:16 gorky volumio[1063]: at /volumio/app/plugins/music_service/mpd/index.js:798:30 Mar 05 13:53:16 gorky volumio[1063]: at /volumio/app/plugins/music_service/mpd/index.js:942:9 Mar 05 13:53:16 gorky volumio[1063]: at ChildProcess.exithandler (child_process.js:299:7) Mar 05 13:53:16 gorky volumio[1063]: at ChildProcess.emit (events.js:315:20) Mar 05 13:53:16 gorky volumio[1063]: at maybeClose (internal/child_process.js:1048:16) Mar 05 13:53:16 gorky volumio[1063]: at Socket. (internal/child_process.js:439:11) Mar 05 13:53:16 gorky volumio[1063]: at Socket.emit (events.js:315:20) Mar 05 13:53:16 gorky volumio[1063]: at Pipe. (net.js:673:12) Mar 05 13:53:16 gorky volumio[1063]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 05 13:53:16 gorky sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-05 13:52 Mar 05 13:53:16 gorky sudo[3654]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"