-- Logs begin at Fri 2025-05-02 08:31:47 CEST, end at Fri 2025-05-02 08:34:36 CEST. -- May 02 08:33:05 volumio2025 volumio[1089]: info: Adding plugin smart_inputs to MyMusic Plugins May 02 08:33:05 volumio2025 volumio[1089]: info: Adding plugin tidalconnect to MyMusic Plugins May 02 08:33:05 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 02 08:33:17 volumio2025 volumio-remote-updater[727]: [2025-05-02 08:33:17] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] May 02 08:33:22 volumio2025 volumio-remote-updater[727]: [2025-05-02 08:33:22] [connect] Successful connection May 02 08:33:35 volumio2025 volumio-remote-updater[727]: [2025-05-02 08:33:35] [connect] Successful connection May 02 08:33:36 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 02 08:33:36 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 02 08:33:36 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:33:36 volumio2025 volumio[1089]: info: Starting MyVolumio Remote Streaming Endpoints May 02 08:33:36 volumio2025 volumio[1089]: info: MyVolumio login type: Token May 02 08:33:36 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 02 08:33:36 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 02 08:33:37 volumio2025 volumio[1089]: info: Starting Streaming Service Transparent Proxy May 02 08:33:37 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 02 08:33:37 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 02 08:33:37 volumio2025 volumio[1089]: info: Streaming services startup May 02 08:33:37 volumio2025 volumio[1089]: info: Starting Streaming Daemon May 02 08:33:37 volumio2025 sudo[2084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 02 08:33:37 volumio2025 sudo[2084]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:33:37 volumio2025 sudo[2084]: pam_unix(sudo:session): session closed for user root May 02 08:33:37 volumio2025 volumio[1089]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 02 08:33:37 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 08:33:37 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:33:37 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 08:33:37 volumio2025 volumio[1089]: error: Cannot start Volumio Streaming Daemon May 02 08:33:37 volumio2025 volumio[1089]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 02 08:33:37 volumio2025 volumio[1089]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 02 08:33:38 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 192.168.1.195:3000 from 192.168.1.196 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 May 02 08:33:38 volumio2025 volumio-remote-updater[727]: [2025-05-02 08:33:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746167615 101 May 02 08:33:38 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 May 02 08:33:38 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 02 08:33:38 volumio2025 volumio[1089]: info: Volumio called home May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:38 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:38 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:38 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:38 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:38 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:38 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:38 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:38 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:38 volumio2025 volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 02 08:33:38 volumio2025 volumio[1089]: error: MyVolumio Custom Token format not valid, refreshing it May 02 08:33:38 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 192.168.1.195:3000 from 192.168.1.196 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:38 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 02 08:33:38 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 02 08:33:38 volumio2025 volumio[1089]: STREAMING PROXY: Starting server on port 3245 May 02 08:33:38 volumio2025 volumio[1089]: Node JS runtime: 14 May 02 08:33:39 volumio2025 volumio-remote-updater[727]: No test mode May 02 08:33:39 volumio2025 volumio-remote-updater[727]: No alpha test mode May 02 08:33:39 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 02 08:33:39 volumio2025 volumio[1089]: info: MyVolumio login type: Token May 02 08:33:40 volumio2025 volumio[1089]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 02 08:33:40 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetVisibleSources May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:40 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:40 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 08:33:40 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:40 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:40 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:40 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:40 volumio2025 volumio[1089]: info: Listing playlists May 02 08:33:40 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:41 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:33:41 volumio2025 volumio[1089]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 02 08:33:41 volumio2025 volumio[1089]: info: Received Get System Version May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 08:33:41 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:41 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:41 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:41 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:42 volumio2025 sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 08:33:42 volumio2025 sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:33:42 volumio2025 sudo[2100]: pam_unix(sudo:session): session closed for user root May 02 08:33:42 volumio2025 sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 08:33:42 volumio2025 sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:33:42 volumio2025 sudo[2103]: pam_unix(sudo:session): session closed for user root May 02 08:33:42 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 192.168.1.195 from 192.168.1.196 UA: Mozilla/5.0 (Linux; Android 14; moto g84 5G Build/U1TCS34.22-64-19-4-4; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetVisibleSources May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:43 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 08:33:43 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:43 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:43 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:43 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:43 volumio2025 volumio[1089]: info: Listing playlists May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 02 08:33:43 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 08:33:44 volumio2025 sudo[2107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 08:33:44 volumio2025 sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:33:44 volumio2025 sudo[2107]: pam_unix(sudo:session): session closed for user root May 02 08:33:44 volumio2025 sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 08:33:44 volumio2025 sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:33:44 volumio2025 sudo[2110]: pam_unix(sudo:session): session closed for user root May 02 08:33:44 volumio2025 volumio[1089]: verbose: New Socket.io Connection to 192.168.1.195 from 192.168.1.196 UA: Mozilla/5.0 (Linux; Android 14; moto g84 5G Build/U1TCS34.22-64-19-4-4; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.100 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetVisibleSources May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:44 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 08:33:44 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:44 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:44 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:44 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:44 volumio2025 volumio[1089]: info: Listing playlists May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 02 08:33:44 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 08:33:46 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:46 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:46 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 02 08:33:46 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 02 08:33:47 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPlay May 02 08:33:47 volumio2025 volumio[1089]: info: CoreStateMachine::play index undefined May 02 08:33:47 volumio2025 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:33:47 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:47 volumio2025 volumio[1089]: info: CoreStateMachine::startPlaybackTimer May 02 08:33:47 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:47 volumio2025 volumio[1089]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Balorda%20nostalgia%22%2C%22artist%22%3A%22Olly%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FguIQwWgjATY%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3lavqF_hGnFzvKlJpQbmFlGfq_gpA%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22guIQwWgjATY%22%2C%22playlistId%22%3A%22RDCLAK5uy_ln-TybP1LA9wDmD21Xd2wOPuVYd3kZJYA%22%2C%22params%22%3A%228gECGAM%253D%22%2C%22playlistSetVideoId%22%3A%2256B44F6D10557CC6%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22RDCLAK5uy_ln-TybP1LA9wDmD21Xd2wOPuVYd3kZJYA%22%2C%22params%22%3A%228gECGAM%253D%22%2C%22videoId%22%3A%22VYN2fV4AuHE%22%7D%7D%7D%7D May 02 08:33:47 volumio2025 volumio[1089]: info: [ytmusic] InnertubeLoader: creating Innertube instance... May 02 08:33:55 volumio2025 volumio[1089]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. May 02 08:33:55 volumio2025 volumio[1089]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 08:33:55 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:33:55 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:55 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 08:33:55 volumio2025 volumio[1089]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 02 08:33:55 volumio2025 volumio[1089]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 02 08:33:55 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:33:55 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetBrowseSources May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 08:33:56 volumio2025 volumio[1089]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) May 02 08:33:56 volumio2025 volumio[1089]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token May 02 08:33:56 volumio2025 volumio[1089]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... May 02 08:33:56 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 02 08:33:57 volumio2025 volumio[1089]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 02 08:33:57 volumio2025 volumio[1089]: info: Completed starting MyVolumio Plugin May 02 08:33:57 volumio2025 volumio[1089]: [Metrics] CommandRouter: 98s 271.99ms May 02 08:33:57 volumio2025 volumio[1089]: info: CoreCommandRouter::volumiosetStartupVolume May 02 08:33:57 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 02 08:33:57 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:33:57 volumio2025 volumio[1089]: info: CoreCommandRouter::Close All Modals sent May 02 08:33:57 volumio2025 volumio[1089]: info: CoreCommandRouter::Close All Modals sent May 02 08:34:01 volumio2025 volumio[1089]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. May 02 08:34:01 volumio2025 volumio[1089]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds May 02 08:34:01 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 02 08:34:01 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 08:34:01 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 02 08:34:01 volumio2025 volumio[1089]: info: MyVolumio token set successfully May 02 08:34:01 volumio2025 volumio[1089]: info: MYVOLUMIO: Adding device May 02 08:34:01 volumio2025 volumio[1089]: info: MYVOLUMIO: Evaluating Server May 02 08:34:02 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand stop May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand stop took 3 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand clear May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand clear took 1 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand addid "https://rr1---sn-fpoq-cgpz.googlevideo.com/videoplayback?expire=1746189241&ei=WWcUaIakJOeP6dsPzPejsQk&ip=151.46.208.253&id=o-AEsV62OfFqJkI0zcneul_zdlgBP_jdLGB6Ub5QbTedtY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746167641%2C&mh=qE&mm=31%2C29&mn=sn-fpoq-cgpz%2Csn-hpa7kn76&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&gcr=it&initcwndbps=1733750&bui=AecWEAYDkgKLLj2qzMx8DqAcAo1V-I4Rfr8xiOQW5PD6fotWbqKFzBEswohrKFDRkiJLopecJesM-3ZT&spc=wk1kZktZV5bYnkbvMm9i7_vrX5niS9f6Jf-ncV_r8D8MEzPG-_pIYdDxcMEUlJV1LSg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=95ra1RiPqHDQdF1NlJCJhDIQ&rqh=1&gir=yes&clen=3593342&dur=203.821&lmt=1739966744525940&mt=1746167339&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532534&n=130Td3FeaCE8J0Bj-L-_w8_XqzqP5LibboTHpyPmUqA6r&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPLtsx6m0AZBuPH873NFguwMpGVnHaCsroL4oYTvZuTwAiEAkTsXFpyqKC7mF9D1B7Iqw9AzSXHiml4TT_g2C4-361c%3D&pot=MpgB1qsYaNvqYdrWl_T5Ms_tycTIcPQ2AxdlRrpKhNT-E4avCQNZ7h--p8xTYzEMavZ8OWuZVdq7KRDxpOl9Pd38bRiISIf6OJNS-TFJtCL2WX2slSsl9Y9mkmxCtW921XarLyAy3LWhfVHnjYzMwEbqQQltbVIGAbuCiY70m25Ba4So8qizZ0pkYzlrXixkczFIDBpPNtJJYf8%3D&cver=1.20250219.01.00&t.YouTube" May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand addid "https://rr1---sn-fpoq-cgpz.googlevideo.com/videoplayback?expire=1746189241&ei=WWcUaIakJOeP6dsPzPejsQk&ip=151.46.208.253&id=o-AEsV62OfFqJkI0zcneul_zdlgBP_jdLGB6Ub5QbTedtY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746167641%2C&mh=qE&mm=31%2C29&mn=sn-fpoq-cgpz%2Csn-hpa7kn76&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&gcr=it&initcwndbps=1733750&bui=AecWEAYDkgKLLj2qzMx8DqAcAo1V-I4Rfr8xiOQW5PD6fotWbqKFzBEswohrKFDRkiJLopecJesM-3ZT&spc=wk1kZktZV5bYnkbvMm9i7_vrX5niS9f6Jf-ncV_r8D8MEzPG-_pIYdDxcMEUlJV1LSg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=95ra1RiPqHDQdF1NlJCJhDIQ&rqh=1&gir=yes&clen=3593342&dur=203.821&lmt=1739966744525940&mt=1746167339&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532534&n=130Td3FeaCE8J0Bj-L-_w8_XqzqP5LibboTHpyPmUqA6r&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPLtsx6m0AZBuPH873NFguwMpGVnHaCsroL4oYTvZuTwAiEAkTsXFpyqKC7mF9D1B7Iqw9AzSXHiml4TT_g2C4-361c%3D&pot=MpgB1qsYaNvqYdrWl_T5Ms_tycTIcPQ2AxdlRrpKhNT-E4avCQNZ7h--p8xTYzEMavZ8OWuZVdq7KRDxpOl9Pd38bRiISIf6OJNS-TFJtCL2WX2slSsl9Y9mkmxCtW921XarLyAy3LWhfVHnjYzMwEbqQQltbVIGAbuCiY70m25Ba4So8qizZ0pkYzlrXixkczFIDBpPNtJJYf8%3D&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: verbose: MPD COMMAND [object Object] May 02 08:34:02 volumio2025 volumio[1089]: verbose: MPD COMMAND [object Object] May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: error: Upnp client error: Error: This socket has been ended by the other party May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 12ms May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 11ms May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 8ms May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 7ms May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::setConsumeUpdateService mpd May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand play May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces system playlist update May 02 08:34:02 volumio2025 volumio[1089]: info: Ignoring MPD Status Update May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 6ms May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand play took 4 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 3ms May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 15ms May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces state update: player May 02 08:34:02 volumio2025 volumio[1089]: info: ControllerMpd::getState May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces state update: player May 02 08:34:02 volumio2025 volumio[1089]: info: ControllerMpd::getState May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 02 08:34:02 volumio2025 volumio[1089]: info: May 02 08:34:02 volumio2025 volumio[1089]: ---------------------------- MPD announces state update: player May 02 08:34:02 volumio2025 volumio[1089]: info: ControllerMpd::getState May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand status May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand clearerror May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand status took 11 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand clearerror May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand status took 11 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::parseState May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::parseState May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand clearerror May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand status took 24 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand clearerror took 16 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand clearerror took 15 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: info: sendMpdCommand playlistinfo took 8 milliseconds May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::parseState May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 02 08:34:02 volumio2025 volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 02 08:34:02 volumio2025 volumio[1089]: info: ControllerMpd::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::servicePushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:02 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:34:02 volumio2025 volumio[1089]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Balorda nostalgia","artist":"Olly","album":null,"uri":"https://rr1---sn-fpoq-cgpz.googlevideo.com/videoplayback?expire=1746189241&ei=WWcUaIakJOeP6dsPzPejsQk&ip=151.46.208.253&id=o-AEsV62OfFqJkI0zcneul_zdlgBP_jdLGB6Ub5QbTedtY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746167641%2C&mh=qE&mm=31%2C29&mn=sn-fpoq-cgpz%2Csn-hpa7kn76&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&gcr=it&initcwndbps=1733750&bui=AecWEAYDkgKLLj2qzMx8DqAcAo1V-I4Rfr8xiOQW5PD6fotWbqKFzBEswohrKFDRkiJLopecJesM-3ZT&spc=wk1kZktZV5bYnkbvMm9i7_vrX5niS9f6Jf-ncV_r8D8MEzPG-_pIYdDxcMEUlJV1LSg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=95ra1RiPqHDQdF1NlJCJhDIQ&rqh=1&gir=yes&clen=3593342&dur=203.821&lmt=1739966744525940&mt=1746167339&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532534&n=130Td3FeaCE8J0Bj-L-_w8_XqzqP5LibboTHpyPmUqA6r&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPLtsx6m0AZBuPH873NFguwMpGVnHaCsroL4oYTvZuTwAiEAkTsXFpyqKC7mF9D1B7Iqw9AzSXHiml4TT_g2C4-361c%3D&pot=MpgB1qsYaNvqYdrWl_T5Ms_tycTIcPQ2AxdlRrpKhNT-E4avCQNZ7h--p8xTYzEMavZ8OWuZVdq7KRDxpOl9Pd38bRiISIf6OJNS-TFJtCL2WX2slSsl9Y9mkmxCtW921XarLyAy3LWhfVHnjYzMwEbqQQltbVIGAbuCiY70m25Ba4So8qizZ0pkYzlrXixkczFIDBpPNtJJYf8%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 02 08:34:02 volumio2025 volumio[1089]: verbose: CURRENT POSITION 0 May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::syncState stateService stop May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:02 volumio2025 volumio[1089]: info: No code May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:02 volumio2025 volumio[1089]: info: ControllerMpd::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::servicePushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:02 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:34:02 volumio2025 volumio[1089]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Balorda nostalgia","artist":"Olly","album":null,"uri":"https://rr1---sn-fpoq-cgpz.googlevideo.com/videoplayback?expire=1746189241&ei=WWcUaIakJOeP6dsPzPejsQk&ip=151.46.208.253&id=o-AEsV62OfFqJkI0zcneul_zdlgBP_jdLGB6Ub5QbTedtY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746167641%2C&mh=qE&mm=31%2C29&mn=sn-fpoq-cgpz%2Csn-hpa7kn76&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&gcr=it&initcwndbps=1733750&bui=AecWEAYDkgKLLj2qzMx8DqAcAo1V-I4Rfr8xiOQW5PD6fotWbqKFzBEswohrKFDRkiJLopecJesM-3ZT&spc=wk1kZktZV5bYnkbvMm9i7_vrX5niS9f6Jf-ncV_r8D8MEzPG-_pIYdDxcMEUlJV1LSg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=95ra1RiPqHDQdF1NlJCJhDIQ&rqh=1&gir=yes&clen=3593342&dur=203.821&lmt=1739966744525940&mt=1746167339&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532534&n=130Td3FeaCE8J0Bj-L-_w8_XqzqP5LibboTHpyPmUqA6r&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPLtsx6m0AZBuPH873NFguwMpGVnHaCsroL4oYTvZuTwAiEAkTsXFpyqKC7mF9D1B7Iqw9AzSXHiml4TT_g2C4-361c%3D&pot=MpgB1qsYaNvqYdrWl_T5Ms_tycTIcPQ2AxdlRrpKhNT-E4avCQNZ7h--p8xTYzEMavZ8OWuZVdq7KRDxpOl9Pd38bRiISIf6OJNS-TFJtCL2WX2slSsl9Y9mkmxCtW921XarLyAy3LWhfVHnjYzMwEbqQQltbVIGAbuCiY70m25Ba4So8qizZ0pkYzlrXixkczFIDBpPNtJJYf8%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 02 08:34:02 volumio2025 volumio[1089]: verbose: CURRENT POSITION 0 May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::syncState stateService stop May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:02 volumio2025 volumio[1089]: info: No code May 02 08:34:02 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:02 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 98ms May 02 08:34:02 volumio2025 volumio[1089]: info: ------------------------------ 98ms May 02 08:34:03 volumio2025 volumio[1089]: info: MyVolumio status changed May 02 08:34:03 volumio2025 volumio[1089]: info: Streaming services startup May 02 08:34:03 volumio2025 volumio[1089]: info: Starting Streaming Daemon May 02 08:34:03 volumio2025 volumio[1089]: info: Removing browser output: myVolumio user plan is not superstar May 02 08:34:03 volumio2025 volumio[1089]: info: Removing audio output: May 02 08:34:03 volumio2025 volumio[1089]: info: Stoppping Tunnel 1 May 02 08:34:03 volumio2025 sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 02 08:34:03 volumio2025 sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:34:03 volumio2025 sudo[2210]: pam_unix(sudo:session): session closed for user root May 02 08:34:04 volumio2025 volumio[1089]: info: sendMpdCommand clearerror took 1173 milliseconds May 02 08:34:04 volumio2025 volumio[1089]: info: sendMpdCommand playlistinfo took 1172 milliseconds May 02 08:34:04 volumio2025 volumio[1089]: verbose: ControllerMpd::parseTrackInfo May 02 08:34:04 volumio2025 sudo[2213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 02 08:34:04 volumio2025 sudo[2213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:34:04 volumio2025 volumio[1089]: info: ControllerMpd::pushState May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::servicePushState May 02 08:34:04 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:04 volumio2025 volumio[1089]: info: CorePlayQueue::getTrack 0 May 02 08:34:04 volumio2025 volumio[1089]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Balorda nostalgia","artist":"Olly","album":null,"uri":"https://rr1---sn-fpoq-cgpz.googlevideo.com/videoplayback?expire=1746189241&ei=WWcUaIakJOeP6dsPzPejsQk&ip=151.46.208.253&id=o-AEsV62OfFqJkI0zcneul_zdlgBP_jdLGB6Ub5QbTedtY&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746167641%2C&mh=qE&mm=31%2C29&mn=sn-fpoq-cgpz%2Csn-hpa7kn76&ms=au%2Crdu&mv=m&mvi=1&pl=19&rms=au%2Cau&gcr=it&initcwndbps=1733750&bui=AecWEAYDkgKLLj2qzMx8DqAcAo1V-I4Rfr8xiOQW5PD6fotWbqKFzBEswohrKFDRkiJLopecJesM-3ZT&spc=wk1kZktZV5bYnkbvMm9i7_vrX5niS9f6Jf-ncV_r8D8MEzPG-_pIYdDxcMEUlJV1LSg&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=95ra1RiPqHDQdF1NlJCJhDIQ&rqh=1&gir=yes&clen=3593342&dur=203.821&lmt=1739966744525940&mt=1746167339&fvip=4&keepalive=yes&c=WEB_REMIX&sefc=1&txp=4532534&n=130Td3FeaCE8J0Bj-L-_w8_XqzqP5LibboTHpyPmUqA6r&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPLtsx6m0AZBuPH873NFguwMpGVnHaCsroL4oYTvZuTwAiEAkTsXFpyqKC7mF9D1B7Iqw9AzSXHiml4TT_g2C4-361c%3D&pot=MpgB1qsYaNvqYdrWl_T5Ms_tycTIcPQ2AxdlRrpKhNT-E4avCQNZ7h--p8xTYzEMavZ8OWuZVdq7KRDxpOl9Pd38bRiISIf6OJNS-TFJtCL2WX2slSsl9Y9mkmxCtW921XarLyAy3LWhfVHnjYzMwEbqQQltbVIGAbuCiY70m25Ba4So8qizZ0pkYzlrXixkczFIDBpPNtJJYf8%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} May 02 08:34:04 volumio2025 volumio[1089]: verbose: CURRENT POSITION 0 May 02 08:34:04 volumio2025 volumio[1089]: info: CoreStateMachine::syncState stateService stop May 02 08:34:04 volumio2025 volumio[1089]: info: CoreStateMachine::syncState currentStatus stop May 02 08:34:04 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:04 volumio2025 volumio[1089]: info: No code May 02 08:34:04 volumio2025 volumio[1089]: info: CoreStateMachine::pushState May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPushState May 02 08:34:04 volumio2025 sudo[2213]: pam_unix(sudo:session): session closed for user root May 02 08:34:04 volumio2025 volumio[1089]: info: ------------------------------ 1243ms May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:34:04 volumio2025 volumio[1089]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 02 08:34:04 volumio2025 volumio[1089]: info: Received Get System Version May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 08:34:04 volumio2025 volumio[1089]: info: Received Get System Info May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:34:04 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:34:04 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:34:04 volumio2025 volumio[1089]: error: Cannot start Volumio Streaming Daemon May 02 08:34:04 volumio2025 volumio[1089]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 02 08:34:04 volumio2025 volumio[1089]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 02 08:34:04 volumio2025 volumio[1089]: info: Remote SSH Stopped May 02 08:34:04 volumio2025 volumio[1089]: info: BOOT COMPLETED May 02 08:34:05 volumio2025 volumio[1089]: info: mpdhttpout ---Boot completed detected! Patching mpd now! May 02 08:34:05 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:34:05 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioPause May 02 08:34:05 volumio2025 volumio[1089]: info: CoreStateMachine::pause May 02 08:34:05 volumio2025 volumio[1089]: info: mpdhttpout --- Volumio set on pause May 02 08:34:05 volumio2025 volumio[1089]: info: mpdhttpout ---Configuration successfully added to mpd.conf. May 02 08:34:07 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd May 02 08:34:07 volumio2025 sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 02 08:34:07 volumio2025 sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:34:07 volumio2025 systemd[1]: Stopping Music Player Daemon... May 02 08:34:07 volumio2025 systemd[1]: mpd.service: Succeeded. May 02 08:34:07 volumio2025 systemd[1]: Stopped Music Player Daemon. May 02 08:34:07 volumio2025 systemd[1]: Starting Music Player Daemon... May 02 08:34:07 volumio2025 sudo[2236]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 02 08:34:07 volumio2025 sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:34:07 volumio2025 sudo[2236]: pam_unix(sudo:session): session closed for user root May 02 08:34:08 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:34:08 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:34:08 volumio2025 volumio[1089]: info: Discovery: Getting this device information May 02 08:34:08 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetState May 02 08:34:08 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:34:08 volumio2025 mpd[2238]: May 02 08:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 02 08:34:08 volumio2025 systemd[1]: Started Music Player Daemon. May 02 08:34:08 volumio2025 sudo[2234]: pam_unix(sudo:session): session closed for user root May 02 08:34:08 volumio2025 volumio[1089]: error: updateQueue error: null May 02 08:34:14 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetQueue May 02 08:34:14 volumio2025 volumio[1089]: info: CoreStateMachine::getQueue May 02 08:34:14 volumio2025 volumio[1089]: info: CorePlayQueue::getQueue May 02 08:34:19 volumio2025 volumio[1089]: info: Setting Geolocation for MyVolumio to eu10 May 02 08:34:19 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:34:19 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:34:19 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:34:19 volumio2025 volumio[1089]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 02 08:34:20 volumio2025 volumio[1089]: info: Updating MyVolumio device info May 02 08:34:20 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:34:20 volumio2025 volumio[1089]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 02 08:34:23 volumio2025 volumio[1089]: info: MYVOLUMIO: Adding device May 02 08:34:23 volumio2025 volumio[1089]: info: MYVOLUMIO: Evaluating Server May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:34:26 volumio2025 volumio[1089]: info: Retrieving Cloud Streaming UI May 02 08:34:26 volumio2025 volumio[1089]: info: Getting Tidal Cloud Configuration May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:34:26 volumio2025 volumio[1089]: info: Getting Qobuz Cloud Configuration May 02 08:34:26 volumio2025 volumio[1089]: info: Asking plugin for UI Config May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:34:26 volumio2025 volumio[1089]: info: Getting Spotify Cloud Configuration May 02 08:34:26 volumio2025 volumio[1089]: info: Asking plugin for UI Config May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:34:26 volumio2025 volumio[1089]: info: Saving Spotify Acccount May 02 08:34:26 volumio2025 volumio[1089]: info: Got Tidal Cloud Configuration May 02 08:34:26 volumio2025 volumio[1089]: info: Got it May 02 08:34:26 volumio2025 volumio[1089]: info: Got it May 02 08:34:26 volumio2025 volumio[1089]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:34:26 volumio2025 volumio[1089]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetBrowseSources May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetBrowseSources May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::volumioGetBrowseSources May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 08:34:26 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 02 08:34:30 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 08:34:34 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 02 08:34:35 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares May 02 08:34:35 volumio2025 volumio[1089]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService May 02 08:34:35 volumio2025 volumio[1089]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 08:34:35 volumio2025 volumio[1089]: Error: getaddrinfo -3007 May 02 08:34:35 volumio2025 volumio[1089]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) May 02 08:34:35 volumio2025 volumio[1089]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) May 02 08:34:35 volumio2025 volumio[1089]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { May 02 08:34:35 volumio2025 volumio[1089]: code: -3007, May 02 08:34:35 volumio2025 volumio[1089]: errno: -3007, May 02 08:34:35 volumio2025 volumio[1089]: syscall: 'getaddrinfo' May 02 08:34:35 volumio2025 volumio[1089]: } May 02 08:34:35 volumio2025 volumio[1089]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 08:34:36 volumio2025 sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-02 08:33 May 02 08:34:36 volumio2025 sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Apr 2025 10:07:11 AM CEST" VOLUMIO_VERSION="3.804" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="dc77d77f02ef88ea816c3d31de871e8f"