May 25 09:28:05 volumio volumio[1250]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 25 09:28:05 volumio volumio[1250]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 25 09:28:05 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:05 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:05 volumio volumio[1250]: info: Starting MyVolumio Remote Streaming Endpoints May 25 09:28:05 volumio volumio[1250]: info: MyVolumio login type: Token May 25 09:28:05 volumio volumio[1250]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 25 09:28:05 volumio volumio[1250]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 25 09:28:12 volumio volumio[1250]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 25 09:28:12 volumio volumio[1250]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 25 09:28:12 volumio volumio[1250]: info: Streaming services startup May 25 09:28:12 volumio volumio[1250]: info: Starting Streaming Daemon May 25 09:28:12 volumio volumio[1250]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 25 09:28:12 volumio sudo[2207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 25 09:28:12 volumio sudo[2207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:12 volumio sudo[2207]: pam_unix(sudo:session): session closed for user root May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 09:28:13 volumio volumio[1250]: error: Cannot start Volumio Streaming Daemon May 25 09:28:13 volumio volumio[1250]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 25 09:28:13 volumio volumio[1250]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 25 09:28:13 volumio volumio[1250]: error: updateQueue error: null May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:13 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:13 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:13 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:13 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 09:28:13 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 25 09:28:13 volumio sudo[2218]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 25 09:28:14 volumio sudo[2218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:14 volumio sudo[2223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:14 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:14 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root May 25 09:28:14 volumio sudo[2218]: pam_unix(sudo:session): session closed for user root May 25 09:28:14 volumio sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:14 volumio sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:14 volumio sudo[2230]: pam_unix(sudo:session): session closed for user root May 25 09:28:14 volumio sudo[2243]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:14 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:14 volumio sudo[2238]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:14 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:14 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root May 25 09:28:14 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root May 25 09:28:14 volumio sudo[2245]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:14 volumio sudo[2245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:15 volumio sudo[2245]: pam_unix(sudo:session): session closed for user root May 25 09:28:15 volumio volumio-remote-updater[736]: Test mode disabled May 25 09:28:15 volumio volumio-remote-updater[736]: Alpha mode disabled May 25 09:28:15 volumio volumio-remote-updater[736]: Alpha legacy test mode disabled May 25 09:28:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 25 09:28:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:16 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:16 volumio volumio[1250]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

NEW ADDITIONS

","title":"Update v4.119","updateavailable":true} May 25 09:28:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 25 09:28:17 volumio volumio[1250]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:18 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:18 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:18 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:19 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:19 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:19 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:19 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:19 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:19 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:28:20 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 25 09:28:20 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 25 09:28:20 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:28:20 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:20 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:20 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 09:28:21 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 25 09:28:22 volumio sudo[2272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 25 09:28:22 volumio sudo[2272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:22 volumio sudo[2278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:22 volumio sudo[2278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:22 volumio sudo[2272]: pam_unix(sudo:session): session closed for user root May 25 09:28:22 volumio sudo[2284]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:22 volumio sudo[2278]: pam_unix(sudo:session): session closed for user root May 25 09:28:22 volumio sudo[2288]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:22 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:22 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:22 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:22 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:22 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:22 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:22 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:22 volumio sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:22 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:22 volumio sudo[2284]: pam_unix(sudo:session): session closed for user root May 25 09:28:22 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root May 25 09:28:22 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root May 25 09:28:22 volumio sudo[2297]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:22 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:22 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root May 25 09:28:23 volumio volumio[1250]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 25 09:28:23 volumio volumio[1250]: info: MyVolumio token set successfully May 25 09:28:23 volumio volumio[1250]: info: MYVOLUMIO: Adding device May 25 09:28:23 volumio volumio[1250]: info: MYVOLUMIO: Evaluating Server May 25 09:28:26 volumio volumio[1250]: info: MyVolumio status changed May 25 09:28:26 volumio volumio[1250]: info: Streaming services startup May 25 09:28:26 volumio volumio[1250]: info: Starting Streaming Daemon May 25 09:28:26 volumio volumio[1250]: info: Removing browser output: myVolumio user plan is not superstar May 25 09:28:26 volumio volumio[1250]: info: Removing audio output: May 25 09:28:26 volumio volumio[1250]: info: Stoppping Tunnel 1 May 25 09:28:26 volumio sudo[2324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 25 09:28:26 volumio sudo[2324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:26 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 25 09:28:26 volumio sudo[2324]: pam_unix(sudo:session): session closed for user root May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetBrowseSources May 25 09:28:26 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 09:28:26 volumio sudo[2327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 25 09:28:26 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 25 09:28:27 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:27 volumio volumio[1250]: info: Setting Geolocation for MyVolumio to as1 May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:27 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:28 volumio volumio[1250]: error: Cannot start Volumio Streaming Daemon May 25 09:28:28 volumio volumio[1250]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 25 09:28:28 volumio volumio[1250]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 25 09:28:28 volumio volumio[1250]: info: Remote SSH Stopped May 25 09:28:29 volumio volumio[1250]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 25 09:28:29 volumio volumio[1250]: info: Updating MyVolumio device info May 25 09:28:29 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:29 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:29 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:29 volumio volumio[1250]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 25 09:28:30 volumio volumio[1250]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 25 09:28:30 volumio volumio[1250]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 25 09:28:30 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:30 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:30 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:30 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:30 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:30 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:31 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:31 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:32 volumio sudo[2347]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:32 volumio sudo[2347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:32 volumio sudo[2347]: pam_unix(sudo:session): session closed for user root May 25 09:28:32 volumio sudo[2345]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:32 volumio sudo[2345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:32 volumio sudo[2345]: pam_unix(sudo:session): session closed for user root May 25 09:28:32 volumio volumio[1250]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 25 09:28:32 volumio volumio[1250]: info: Completed starting MyVolumio Plugin May 25 09:28:32 volumio volumio[1250]: [Metrics] CommandRouter: 90s 164.85ms May 25 09:28:32 volumio volumio[1250]: info: CoreCommandRouter::volumiosetStartupVolume May 25 09:28:32 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 25 09:28:32 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:33 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent May 25 09:28:33 volumio volumio[1250]: info: CoreCommandRouter::Close All Modals sent May 25 09:28:33 volumio volumio[1250]: info: MYVOLUMIO: Adding device May 25 09:28:33 volumio volumio[1250]: info: MYVOLUMIO: Evaluating Server May 25 09:28:34 volumio volumio[1250]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 25 09:28:34 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 25 09:28:34 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 25 09:28:34 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 25 09:28:38 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 09:28:38 volumio volumio[1250]: info: Setting Geolocation for MyVolumio to as1 May 25 09:28:38 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:38 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:38 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 09:28:39 volumio volumio[1250]: info: Received Get System Info May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:39 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:39 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 1 May 25 09:28:39 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d1ed30 00000000 00000000 0e000000 03028001 May 25 09:28:39 volumio volumio[1250]: info: Listing playlists May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 09:28:39 volumio volumio[1250]: info: Received Get System Info May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:39 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 09:28:39 volumio volumio[1250]: info: Received Get System Info May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:39 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:39 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:39 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 09:28:40 volumio volumio[1250]: info: Updating MyVolumio device info May 25 09:28:40 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:40 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:40 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 25 09:28:40 volumio volumio[1250]: info: BOOT COMPLETED May 25 09:28:40 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:40 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:43 volumio volumio[1250]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 25 09:28:43 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 09:28:43 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:28:43 volumio volumio[1250]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 25 09:28:44 volumio sudo[2391]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:44 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:44 volumio sudo[2391]: pam_unix(sudo:session): session closed for user root May 25 09:28:44 volumio sudo[2394]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:44 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:44 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root May 25 09:28:44 volumio sudo[2397]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:44 volumio sudo[2397]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:44 volumio sudo[2397]: pam_unix(sudo:session): session closed for user root May 25 09:28:45 volumio sudo[2400]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:45 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:45 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root May 25 09:28:45 volumio sudo[2405]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:45 volumio sudo[2405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:45 volumio sudo[2405]: pam_unix(sudo:session): session closed for user root May 25 09:28:45 volumio sudo[2403]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:45 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:45 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root May 25 09:28:45 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 May 25 09:28:46 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 May 25 09:28:46 volumio sudo[2409]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:46 volumio sudo[2409]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:46 volumio sudo[2409]: pam_unix(sudo:session): session closed for user root May 25 09:28:46 volumio sudo[2411]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:46 volumio sudo[2411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:46 volumio sudo[2411]: pam_unix(sudo:session): session closed for user root May 25 09:28:46 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 May 25 09:28:47 volumio upmpdcli[2418]: writing RSA key May 25 09:28:47 volumio sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:47 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:48 volumio sudo[2416]: pam_unix(sudo:session): session closed for user root May 25 09:28:48 volumio sudo[2417]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:48 volumio sudo[2417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:48 volumio sudo[2417]: pam_unix(sudo:session): session closed for user root May 25 09:28:49 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:49 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:49 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:49 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:49 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:49 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:49 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:49 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:28:49 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 25 09:28:50 volumio sudo[2431]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 25 09:28:50 volumio sudo[2431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:50 volumio sudo[2431]: pam_unix(sudo:session): session closed for user root May 25 09:28:50 volumio sudo[2436]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:50 volumio sudo[2444]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:50 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:50 volumio sudo[2436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:50 volumio sudo[2448]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:50 volumio sudo[2448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:50 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root May 25 09:28:50 volumio sudo[2436]: pam_unix(sudo:session): session closed for user root May 25 09:28:50 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:50 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:50 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root May 25 09:28:50 volumio sudo[2448]: pam_unix(sudo:session): session closed for user root May 25 09:28:50 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:50 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:50 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:50 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:50 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:50 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:51 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:51 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:51 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:51 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:51 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:51 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 25 09:28:51 volumio sudo[2463]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 25 09:28:51 volumio sudo[2463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:51 volumio sudo[2463]: pam_unix(sudo:session): session closed for user root May 25 09:28:51 volumio sudo[2468]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:51 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:52 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root May 25 09:28:52 volumio sudo[2489]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:52 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:52 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root May 25 09:28:52 volumio sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:52 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:52 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:28:52 volumio volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 09:28:52 volumio sudo[2481]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 25 09:28:52 volumio sudo[2481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:52 volumio volumio[1250]: info: Received Get System Version May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 09:28:52 volumio volumio[1250]: info: Received Get System Info May 25 09:28:52 volumio sudo[2481]: pam_unix(sudo:session): session closed for user root May 25 09:28:52 volumio sudo[2487]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:52 volumio sudo[2487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:52 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:52 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:52 volumio sudo[2487]: pam_unix(sudo:session): session closed for user root May 25 09:28:52 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:52 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:52 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:52 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:52 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:53 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:28:53 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:28:53 volumio volumio[1250]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 25 09:28:55 volumio sudo[2510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:55 volumio sudo[2510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:55 volumio sudo[2510]: pam_unix(sudo:session): session closed for user root May 25 09:28:55 volumio sudo[2513]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:55 volumio sudo[2513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:55 volumio sudo[2513]: pam_unix(sudo:session): session closed for user root May 25 09:28:55 volumio sudo[2515]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:55 volumio sudo[2515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:55 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 May 25 09:28:55 volumio sudo[2515]: pam_unix(sudo:session): session closed for user root May 25 09:28:56 volumio sudo[2518]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:56 volumio sudo[2518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:56 volumio sudo[2518]: pam_unix(sudo:session): session closed for user root May 25 09:28:56 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 May 25 09:28:57 volumio sudo[2522]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:57 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:57 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root May 25 09:28:57 volumio sudo[2524]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:57 volumio sudo[2524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:57 volumio sudo[2524]: pam_unix(sudo:session): session closed for user root May 25 09:28:58 volumio sudo[2528]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 25 09:28:58 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:58 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root May 25 09:28:58 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 15 May 25 09:28:58 volumio sudo[2530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 25 09:28:58 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 25 09:28:58 volumio sudo[2530]: pam_unix(sudo:session): session closed for user root May 25 09:28:58 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49 from 192.168.0.36 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 16 May 25 09:28:58 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:59 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue May 25 09:28:59 volumio volumio[1250]: info: CoreStateMachine::getQueue May 25 09:28:59 volumio volumio[1250]: info: CorePlayQueue::getQueue May 25 09:28:59 volumio volumio[1250]: info: Listing playlists May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 09:28:59 volumio volumio[1250]: info: Received Get System Info May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:59 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:59 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:59 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 09:28:59 volumio volumio[1250]: info: Received Get System Info May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:28:59 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:28:59 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:28:59 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:29:00 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetVisibleSources May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue May 25 09:29:00 volumio volumio[1250]: info: CoreStateMachine::getQueue May 25 09:29:00 volumio volumio[1250]: info: CorePlayQueue::getQueue May 25 09:29:00 volumio volumio[1250]: info: Listing playlists May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 25 09:29:00 volumio volumio[1250]: info: Received Get System Info May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:29:00 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 09:29:00 volumio volumio[1250]: info: Received Get System Info May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:29:00 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:00 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 25 09:29:00 volumio volumio[1250]: verbose: New Socket.io Connection to 192.168.0.49:3000 from 192.168.0.36 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 17 May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 25 09:29:00 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 09:29:01 volumio volumio[1250]: info: Received Get System Info May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:29:01 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:01 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 25 09:29:01 volumio volumio[1250]: info: Received Get System Info May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:29:01 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:01 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:01 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 25 09:29:02 volumio volumio[1250]: info: Preload queue cleared May 25 09:29:02 volumio volumio[1250]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 09:29:02 volumio volumio[1250]: info: CoreStateMachine::ClearQueue May 25 09:29:02 volumio volumio[1250]: info: CoreStateMachine::stop May 25 09:29:02 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:02 volumio volumio[1250]: info: CorePlayQueue::clearPlayQueue May 25 09:29:02 volumio volumio[1250]: info: CorePlayQueue::saveQueue May 25 09:29:02 volumio volumio[1250]: info: CoreCommandRouter::volumioPushQueue May 25 09:29:02 volumio volumio[1250]: info: CoreStateMachine::addQueueItems May 25 09:29:02 volumio volumio[1250]: info: CorePlayQueue::addQueueItems May 25 09:29:02 volumio volumio[1250]: info: Preload queue cleared May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/155 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/155 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: Music Classic FM program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/156 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/156 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: LINN Classic program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/157 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/157 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: LINN Jazz program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/158 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/158 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/159 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/159 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: Naim Classical program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/160 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/160 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: Naim Radio program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/161 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/161 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: Naim Jazz program=no May 25 09:29:02 volumio volumio[1250]: info: Adding Item to queue: webcarrot/3 May 25 09:29:02 volumio volumio[1250]: info: Exploding uri webcarrot/3 in service carrot_radio May 25 09:29:02 volumio volumio[1250]: info: [explodeUri] currentStation set: KBS ClassicFM program=yes May 25 09:29:03 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=kbs&ch=1fm May 25 09:29:04 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/mpeg url: http://radio.linn.co.uk:8000/autodj May 25 09:29:04 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: http://radio.linn.co.uk:8000/autodj May 25 09:29:04 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/mpeg url: http://radio.linn.co.uk:8004/autodj May 25 09:29:04 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: http://radio.linn.co.uk:8004/autodj May 25 09:29:04 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/ogg url: https://mscp3.live-streams.nl:8252/class-flac.flac May 25 09:29:04 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://mscp3.live-streams.nl:8252/class-flac.flac May 25 09:29:04 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/ogg url: https://mscp3.live-streams.nl:8342/jazz-flac.flac May 25 09:29:04 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://mscp3.live-streams.nl:8342/jazz-flac.flac May 25 09:29:04 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/ogg url: https://mscp3.live-streams.nl:8362/flac.flac May 25 09:29:04 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://mscp3.live-streams.nl:8362/flac.flac May 25 09:29:05 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://edge2.sr.se/p2-flac May 25 09:29:05 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: audio/aac url: https://media-ssl.musicradio.com/ClassicFM May 25 09:29:05 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://media-ssl.musicradio.com/ClassicFM May 25 09:29:05 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__ May 25 09:29:05 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__ May 25 09:29:08 volumio volumio[1250]: info: CoreCommandRouter::volumioNext May 25 09:29:09 volumio volumio[1250]: info: CoreStateMachine::next May 25 09:29:09 volumio volumio[1250]: info: CoreStateMachine::stop May 25 09:29:09 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 25 09:29:09 volumio volumio[1250]: info: CoreStateMachine::play index undefined May 25 09:29:09 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:09 volumio volumio[1250]: info: CorePlayQueue::getTrack 1 May 25 09:29:11 volumio volumio[1250]: info: CoreCommandRouter::volumioPlay May 25 09:29:11 volumio volumio[1250]: info: CoreStateMachine::play index undefined May 25 09:29:11 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:11 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:12 volumio volumio[1250]: info: [_resolveRecursive] Content-Type: application/ogg url: https://edge2.sr.se/p2-flac May 25 09:29:12 volumio volumio[1250]: info: Preload queue cleared May 25 09:29:12 volumio volumio[1250]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 09:29:12 volumio volumio[1250]: info: CoreStateMachine::ClearQueue May 25 09:29:12 volumio volumio[1250]: info: CoreStateMachine::stop May 25 09:29:12 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:12 volumio volumio[1250]: info: CorePlayQueue::clearPlayQueue May 25 09:29:12 volumio volumio[1250]: info: CorePlayQueue::saveQueue May 25 09:29:12 volumio volumio[1250]: info: CoreCommandRouter::volumioPushQueue May 25 09:29:12 volumio volumio[1250]: info: CoreStateMachine::addQueueItems May 25 09:29:12 volumio volumio[1250]: info: CorePlayQueue::addQueueItems May 25 09:29:12 volumio volumio[1250]: info: Preload queue cleared May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/155 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/155 May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/156 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/156 May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/157 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/157 May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/158 May 25 09:29:12 volumio volumio[1250]: info: Exploding uri webcarrot/158 in service carrot_radio May 25 09:29:12 volumio volumio[1250]: info: [explodeUri] currentStation set: Sveriges Radio P2 program=no May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/159 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/159 May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/160 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/160 May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/161 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/161 May 25 09:29:12 volumio volumio[1250]: info: Adding Item to queue: webcarrot/3 May 25 09:29:12 volumio volumio[1250]: info: Using cached record of: webcarrot/3 May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 25 09:29:17 volumio volumio[1250]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 25 09:29:17 volumio volumio[1250]: info: Received Get System Version May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 25 09:29:17 volumio volumio[1250]: info: Received Get System Info May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 25 09:29:17 volumio volumio[1250]: info: Discovery: Getting this device information May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState May 25 09:29:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 0 May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 25 09:29:17 volumio volumio[1250]: info: [_parsePlaylistBody] unrecognized content, using: https://edge2.sr.se/p2-flac May 25 09:29:17 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://edge2.sr.se/p2-flac May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPushQueue May 25 09:29:17 volumio volumio[1250]: info: CorePlayQueue::saveQueue May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock May 25 09:29:17 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock May 25 09:29:17 volumio volumio[1250]: info: CoreCommandRouter::volumioPlay May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::play index 7 May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::addQueueItems May 25 09:29:17 volumio volumio[1250]: info: CorePlayQueue::addQueueItems May 25 09:29:17 volumio volumio[1250]: info: Preload queue cleared May 25 09:29:17 volumio volumio[1250]: info: Adding Item to queue: webcarrot/19 May 25 09:29:17 volumio volumio[1250]: info: Exploding uri webcarrot/19 in service carrot_radio May 25 09:29:17 volumio volumio[1250]: info: [explodeUri] currentStation set: CBS 음악FM program=yes May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::stop May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::play index undefined May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:17 volumio volumio[1250]: info: CoreStateMachine::startPlaybackTimer May 25 09:29:17 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:17 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop May 25 09:29:18 volumio volumio[1250]: info: sendMpdCommand stop took 247 milliseconds May 25 09:29:18 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear May 25 09:29:18 volumio volumio[1250]: info: May 25 09:29:18 volumio volumio[1250]: ---------------------------- MPD announces system playlist update May 25 09:29:18 volumio volumio[1250]: info: Ignoring MPD Status Update May 25 09:29:18 volumio volumio[1250]: info: sendMpdCommand clear took 105 milliseconds May 25 09:29:18 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__" May 25 09:29:18 volumio volumio[1250]: error: updateQueue error: null May 25 09:29:18 volumio volumio[1250]: info: May 25 09:29:18 volumio volumio[1250]: ---------------------------- MPD announces system playlist update May 25 09:29:18 volumio volumio[1250]: info: Ignoring MPD Status Update May 25 09:29:18 volumio volumio[1250]: info: ------------------------------ 88ms May 25 09:29:18 volumio volumio[1250]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__" took 85 milliseconds May 25 09:29:18 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand play May 25 09:29:18 volumio volumio[1250]: info: ------------------------------ 258ms May 25 09:29:18 volumio volumio[1250]: info: sendMpdCommand play took 163 milliseconds May 25 09:29:18 volumio volumio[1250]: info: [clearAddPlayTrack] title=KBS ClassicFM program=yes May 25 09:29:18 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:18 volumio volumio[1250]: info: [startProgramRefresh] KBS ClassicFM May 25 09:29:19 volumio volumio[1250]: info: [_resolveRecursive] 301 -> https://radio.yuntae.in/api/stream?stn=cbs&ch=mfm May 25 09:29:19 volumio volumio[1250]: info: Pushing Favourites {"uri":"webcarrot/3","favourite":false} May 25 09:29:20 volumio volumio[1250]: info: May 25 09:29:20 volumio volumio[1250]: ---------------------------- MPD announces state update: player May 25 09:29:20 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:20 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:21 volumio volumio[1250]: info: May 25 09:29:21 volumio volumio[1250]: ---------------------------- MPD announces state update: player May 25 09:29:21 volumio volumio[1250]: info: sendMpdCommand status took 751 milliseconds May 25 09:29:21 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:21 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:21 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:21 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:21 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://edge1.sr.se/p2-flac May 25 09:29:21 volumio volumio[1250]: info: [_resolveRecursive] 302 -> https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 May 25 09:29:21 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://m-aac.cbs.co.kr/mweb_cbs939/_definst_/cbs939.stream/playlist.m3u8 May 25 09:29:21 volumio volumio[1250]: info: CoreCommandRouter::volumioPushQueue May 25 09:29:22 volumio volumio[1250]: info: CorePlayQueue::saveQueue May 25 09:29:22 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock May 25 09:29:22 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock May 25 09:29:22 volumio volumio[1250]: info: sendMpdCommand status took 664 milliseconds May 25 09:29:22 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 616 milliseconds May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:22 volumio volumio[1250]: info: ControllerMpd::pushState May 25 09:29:22 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:22 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:22 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd May 25 09:29:22 volumio volumio[1250]: info: ------------------------------ 1531ms May 25 09:29:22 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 333 milliseconds May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:22 volumio volumio[1250]: info: ControllerMpd::pushState May 25 09:29:22 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:22 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:22 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd May 25 09:29:22 volumio volumio[1250]: info: ------------------------------ 1083ms May 25 09:29:22 volumio volumio[1250]: info: [fetchProgramInfo] https://radio.yuntae.in/api/onair/program?stn=kbs&ch=1fm -> 가정음악 May 25 09:29:22 volumio volumio[1250]: info: [programRefresh] fetched: 가정음악 May 25 09:29:22 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:22 volumio volumio[1250]: info: sendMpdCommand status took 94 milliseconds May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:22 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 92 milliseconds May 25 09:29:22 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:22 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:22 volumio volumio[1250]: info: [programRefresh] queue updated May 25 09:29:22 volumio volumio[1250]: info: [programRefresh] pushed: status=play program=가정음악 May 25 09:29:23 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:23 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:23 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2514,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"214 Kbps","isStreaming":false,"title":"KBS ClassicFM","artist":"가정음악","album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__","trackType":"","service":"carrot_radio","albumart":"https://padmin.static.kbs.co.kr/live/2021/5/28/1622175310040_252323.jpg","disableVolumioPause":true} May 25 09:29:23 volumio volumio[1250]: verbose: CURRENT POSITION 7 May 25 09:29:23 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play May 25 09:29:23 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop May 25 09:29:29 volumio volumio[1250]: error: [resolveStreamUrl] failed: Error: read ECONNRESET, fallback to: https://live1.sr.se/p2-flac May 25 09:29:29 volumio volumio[1250]: info: [ControllerCarrotRadio::explodeUri] resolved: https://live1.sr.se/p2-flac May 25 09:29:29 volumio volumio[1250]: info: CoreCommandRouter::volumioPushQueue May 25 09:29:29 volumio volumio[1250]: info: CorePlayQueue::saveQueue May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock May 25 09:29:29 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock May 25 09:29:29 volumio volumio[1250]: info: CoreCommandRouter::volumioPlay May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::play index 7 May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::addQueueItems May 25 09:29:29 volumio volumio[1250]: info: CorePlayQueue::addQueueItems May 25 09:29:29 volumio volumio[1250]: info: Preload queue cleared May 25 09:29:29 volumio volumio[1250]: info: Adding Item to queue: webcarrot/19 May 25 09:29:29 volumio volumio[1250]: info: Using cached record of: webcarrot/19 May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::stop May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::stPlaybackTimer May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock May 25 09:29:29 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock May 25 09:29:29 volumio volumio[1250]: info: CoreStateMachine::pushState May 25 09:29:29 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:29 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:29:29 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState May 25 09:29:30 volumio volumio[1250]: info: CoreStateMachine::serviceStop May 25 09:29:30 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:30 volumio volumio[1250]: info: CoreCommandRouter::serviceStop May 25 09:29:30 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop May 25 09:29:30 volumio volumio[1250]: info: CoreCommandRouter::volumioPushQueue May 25 09:29:30 volumio volumio[1250]: info: CorePlayQueue::saveQueue May 25 09:29:30 volumio volumio[1250]: info: CoreStateMachine::updateTrackBlock May 25 09:29:30 volumio volumio[1250]: info: CorePlayQueue::getTrackBlock May 25 09:29:30 volumio volumio[1250]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false May 25 09:29:31 volumio volumio[1250]: info: May 25 09:29:31 volumio volumio[1250]: ---------------------------- MPD announces state update: player May 25 09:29:31 volumio volumio[1250]: info: sendMpdCommand stop took 1056 milliseconds May 25 09:29:31 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::pushState May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: verbose: STATE SERVICE {"status":"stop","service":"carrot_radio","title":"","artist":"","album":"","albumart":"/albumart?sourceicon=music_service/carrot_radio/logos/carrot.png","uri":"","duration":0,"trackType":"webradio","disableVolumioPause":true} May 25 09:29:31 volumio volumio[1250]: verbose: CURRENT POSITION 7 May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::syncState stateService stop May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::pushState May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState May 25 09:29:31 volumio volumio[1250]: info: No code May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::pushState May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::play index undefined May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::startPlaybackTimer May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand stop May 25 09:29:31 volumio volumio[1250]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false May 25 09:29:31 volumio volumio[1250]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false May 25 09:29:31 volumio volumio[1250]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false May 25 09:29:31 volumio volumio[1250]: info: sendMpdCommand status took 587 milliseconds May 25 09:29:31 volumio volumio[1250]: info: sendMpdCommand stop took 305 milliseconds May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand clear May 25 09:29:31 volumio volumio[1250]: info: May 25 09:29:31 volumio volumio[1250]: ---------------------------- MPD announces system playlist update May 25 09:29:31 volumio volumio[1250]: info: Ignoring MPD Status Update May 25 09:29:31 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 86 milliseconds May 25 09:29:31 volumio volumio[1250]: info: sendMpdCommand clear took 97 milliseconds May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:31 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__" May 25 09:29:31 volumio volumio[1250]: info: ControllerMpd::pushState May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:31 volumio volumio[1250]: info: CoreStateMachine::pushState May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 09:29:31 volumio volumio[1250]: info: CoreCommandRouter::volumioPushState May 25 09:29:31 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:31 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd May 25 09:29:31 volumio volumio[1250]: info: ------------------------------ 838ms May 25 09:29:31 volumio volumio[1250]: error: updateQueue error: null May 25 09:29:32 volumio volumio[1250]: info: May 25 09:29:32 volumio volumio[1250]: ---------------------------- MPD announces system playlist update May 25 09:29:32 volumio volumio[1250]: info: Ignoring MPD Status Update May 25 09:29:32 volumio volumio[1250]: info: ------------------------------ 213ms May 25 09:29:32 volumio volumio[1250]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__" took 194 milliseconds May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand play May 25 09:29:32 volumio volumio[1250]: info: peppy_screensaver: pushState - status=stop service=carrot_radio volatile=false May 25 09:29:32 volumio volumio[1250]: info: ------------------------------ 367ms May 25 09:29:32 volumio volumio[1250]: info: sendMpdCommand play took 222 milliseconds May 25 09:29:32 volumio volumio[1250]: info: [clearAddPlayTrack] title=KBS ClassicFM program=yes May 25 09:29:32 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:32 volumio volumio[1250]: info: [startProgramRefresh] KBS ClassicFM May 25 09:29:32 volumio volumio[1250]: info: May 25 09:29:32 volumio volumio[1250]: ---------------------------- MPD announces state update: player May 25 09:29:32 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:32 volumio volumio[1250]: info: Pushing Favourites {"uri":"webcarrot/3","favourite":false} May 25 09:29:32 volumio volumio[1250]: info: May 25 09:29:32 volumio volumio[1250]: ---------------------------- MPD announces state update: player May 25 09:29:32 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:32 volumio volumio[1250]: info: sendMpdCommand status took 160 milliseconds May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:32 volumio volumio[1250]: info: sendMpdCommand status took 55 milliseconds May 25 09:29:32 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 54 milliseconds May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:32 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:32 volumio volumio[1250]: info: ControllerMpd::pushState May 25 09:29:32 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:32 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:32 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd May 25 09:29:32 volumio volumio[1250]: info: ------------------------------ 246ms May 25 09:29:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 85 milliseconds May 25 09:29:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:33 volumio volumio[1250]: info: ControllerMpd::pushState May 25 09:29:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:33 volumio volumio[1250]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current carrot_radio Received mpd May 25 09:29:33 volumio volumio[1250]: info: ------------------------------ 178ms May 25 09:29:33 volumio volumio[1250]: info: [fetchProgramInfo] https://radio.yuntae.in/api/onair/program?stn=kbs&ch=1fm -> 가정음악 May 25 09:29:33 volumio volumio[1250]: info: [programRefresh] fetched: 가정음악 May 25 09:29:33 volumio volumio[1250]: info: ControllerMpd::getState May 25 09:29:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand status May 25 09:29:33 volumio volumio[1250]: info: sendMpdCommand status took 16 milliseconds May 25 09:29:33 volumio volumio[1250]: verbose: ControllerMpd::parseState May 25 09:29:33 volumio volumio[1250]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 09:29:33 volumio volumio[1250]: info: sendMpdCommand playlistinfo took 4 milliseconds May 25 09:29:33 volumio volumio[1250]: verbose: ControllerMpd::parseTrackInfo May 25 09:29:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:33 volumio volumio[1250]: info: [programRefresh] queue updated May 25 09:29:33 volumio volumio[1250]: info: [programRefresh] pushed: status=play program=가정음악 May 25 09:29:33 volumio volumio[1250]: info: CoreCommandRouter::servicePushState May 25 09:29:33 volumio volumio[1250]: info: CorePlayQueue::getTrack 7 May 25 09:29:33 volumio volumio[1250]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1578,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"193 Kbps","isStreaming":false,"title":"KBS ClassicFM","artist":"가정음악","album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLyoiLCJDb25kaXRpb24iOnsiRGF0ZUxlc3NUaGFuIjp7IkFXUzpFcG9jaFRpbWUiOjE3Nzk4NDE2NjF9fX1dfQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA&Signature=fZ5~B5h762HMTJ5iPc5VgOCSZO7sqDwRGVIb1PWYI8Y1~AalrjmiZagdDzUd0JMjDf683mzfgJrMVgRUvIhEnx41JUaM0UDN20g1CgsJSXhGPiAPZY0gSJ-zUuUJtRfQboJeQjT3Q5R7B3AsB94g-bc7GNOQzeyXLgia~678~zJRdtN8~aqdB5ZtGkq~l2Ws-jP9mF4miCtloM5EBDjIIEJy5DlRD5z9Q0dk-f7wv3ka1kHtfkLer4Yc0~smd-BkMgc1IQGHmO6s7u1z3~XCMN-rdrbjRC~TxEQamDqzedKsCtUj8biOSKDhgzWJ-tiq7CnMPCFkwMOFu3PZ30-Adg__","trackType":"","service":"carrot_radio","albumart":"https://padmin.static.kbs.co.kr/live/2021/5/28/1622175310040_252323.jpg","disableVolumioPause":true} May 25 09:29:33 volumio volumio[1250]: verbose: CURRENT POSITION 7 May 25 09:29:33 volumio volumio[1250]: info: CoreStateMachine::syncState stateService play May 25 09:29:33 volumio volumio[1250]: info: CoreStateMachine::syncState currentStatus stop May 25 09:29:41 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 09:29:42 volumio volumio[1250]: Error: Unable to resolve or reject the same promise twice May 25 09:29:42 volumio volumio[1250]: at Promise.reject (/data/plugins/music_service/carrot_radio/node_modules/kew/kew.js:189:43) May 25 09:29:42 volumio volumio[1250]: at ClientRequest. (/data/plugins/music_service/carrot_radio/index.js:738:56) May 25 09:29:42 volumio volumio[1250]: at Object.onceWrapper (node:events:628:28) May 25 09:29:42 volumio volumio[1250]: at ClientRequest.emit (node:events:514:28) May 25 09:29:42 volumio volumio[1250]: at TLSSocket.emitRequestTimeout (node:_http_client:840:9) May 25 09:29:42 volumio volumio[1250]: at Object.onceWrapper (node:events:628:28) May 25 09:29:42 volumio volumio[1250]: at TLSSocket.emit (node:events:526:35) May 25 09:29:42 volumio volumio[1250]: at Socket._onTimeout (node:net:589:8) May 25 09:29:42 volumio volumio[1250]: at listOnTimeout (node:internal/timers:573:17) May 25 09:29:42 volumio volumio[1250]: at process.processTimers (node:internal/timers:514:7) May 25 09:29:42 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 09:29:45 volumio sudo[2685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 09:28' May 25 09:29:45 volumio sudo[2685]: 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"