-- Logs begin at Wed 2025-04-30 22:25:28 EDT, end at Wed 2025-04-30 22:42:51 EDT. --
Apr 30 22:41:00 main-stereo volumio-remote-updater[593]: [2025-04-30 22:41:00] [connect] Successful connection
Apr 30 22:41:00 main-stereo volumio[984]: 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: 5
Apr 30 22:41:00 main-stereo volumio-remote-updater[593]: [2025-04-30 22:41:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1746067260 101
Apr 30 22:41:01 main-stereo volumio[984]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory
Apr 30 22:41:02 main-stereo volumio[1247]: .................................................................++++
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 30 22:41:05 main-stereo volumio[984]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 30 22:41:05 main-stereo volumio[984]: info: Adding plugin multiroom to MyMusic Plugins
Apr 30 22:41:05 main-stereo volumio[984]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 30 22:41:05 main-stereo volumio[984]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 30 22:41:05 main-stereo volumio[984]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 30 22:41:05 main-stereo volumio[984]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 30 22:41:05 main-stereo volumio[984]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 30 22:41:06 main-stereo volumio[984]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 30 22:41:06 main-stereo volumio[984]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 30 22:41:06 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:06 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:06 main-stereo volumio[984]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 30 22:41:06 main-stereo volumio[984]: info: MyVolumio login type: Token
Apr 30 22:41:06 main-stereo volumio[984]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 30 22:41:06 main-stereo volumio[984]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 30 22:41:07 main-stereo volumio[984]: info: Starting Streaming Service Transparent Proxy
Apr 30 22:41:07 main-stereo volumio[984]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 30 22:41:07 main-stereo volumio[984]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 30 22:41:07 main-stereo volumio[984]: info: Streaming services startup
Apr 30 22:41:07 main-stereo volumio[984]: info: Starting Streaming Daemon
Apr 30 22:41:07 main-stereo sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 30 22:41:07 main-stereo sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:41:07 main-stereo sudo[1297]: pam_unix(sudo:session): session closed for user root
Apr 30 22:41:07 main-stereo volumio[984]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 30 22:41:07 main-stereo volumio[984]: error: Cannot start Volumio Streaming Daemon
Apr 30 22:41:07 main-stereo volumio[984]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 30 22:41:07 main-stereo volumio[984]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 30 22:41:07 main-stereo volumio[984]: error: MyVolumio Custom Token format not valid, refreshing it
Apr 30 22:41:08 main-stereo volumio[984]: STREAMING PROXY: Starting server on port 3245
Apr 30 22:41:08 main-stereo volumio[984]: Node JS runtime: 14
Apr 30 22:41:08 main-stereo volumio[984]: info: MyVolumio login type: Token
Apr 30 22:41:08 main-stereo volumio[984]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 30 22:41:09 main-stereo volumio[984]: info: MyVolumio token set successfully
Apr 30 22:41:09 main-stereo volumio[984]: info: MYVOLUMIO: Adding device
Apr 30 22:41:09 main-stereo volumio[984]: info: MYVOLUMIO: Evaluating Server
Apr 30 22:41:09 main-stereo volumio[984]: info: MyVolumio status changed
Apr 30 22:41:09 main-stereo volumio[984]: info: Streaming services startup
Apr 30 22:41:09 main-stereo volumio[984]: info: Starting Streaming Daemon
Apr 30 22:41:09 main-stereo volumio[984]: info: Removing browser output: myVolumio user plan is not superstar
Apr 30 22:41:09 main-stereo volumio[984]: info: Removing audio output:
Apr 30 22:41:09 main-stereo volumio[984]: info: Stoppping Tunnel 1
Apr 30 22:41:09 main-stereo sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 30 22:41:09 main-stereo sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:41:09 main-stereo sudo[1322]: pam_unix(sudo:session): session closed for user root
Apr 30 22:41:09 main-stereo sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Apr 30 22:41:09 main-stereo sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:41:09 main-stereo volumio[984]: error: Cannot start Volumio Streaming Daemon
Apr 30 22:41:09 main-stereo volumio[984]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 30 22:41:09 main-stereo volumio[984]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 30 22:41:09 main-stereo sudo[1325]: pam_unix(sudo:session): session closed for user root
Apr 30 22:41:09 main-stereo volumio[984]: info: Remote SSH Stopped
Apr 30 22:41:09 main-stereo volumio[984]: info: Setting Geolocation for MyVolumio to us3
Apr 30 22:41:09 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:09 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:09 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:10 main-stereo volumio[984]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 30 22:41:10 main-stereo volumio[984]: info: Updating MyVolumio device info
Apr 30 22:41:10 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:11 main-stereo volumio[984]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 30 22:41:14 main-stereo systemd[1]: systemd-timedated.service: Succeeded.
Apr 30 22:41:14 main-stereo volumio[984]: info: MYVOLUMIO: Adding device
Apr 30 22:41:14 main-stereo volumio[984]: info: MYVOLUMIO: Evaluating Server
Apr 30 22:41:14 main-stereo volumio[984]: info: Setting Geolocation for MyVolumio to us3
Apr 30 22:41:14 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:14 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:14 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:15 main-stereo volumio[984]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 30 22:41:15 main-stereo volumio[984]: info: Updating MyVolumio device info
Apr 30 22:41:15 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:16 main-stereo volumio[984]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 30 22:41:18 main-stereo volumio[1247]: ...........................................................................................................................................................................++++
Apr 30 22:41:18 main-stereo volumio[1247]: e is 65537 (0x010001)
Apr 30 22:41:18 main-stereo volumio[1247]: writing RSA key
Apr 30 22:41:21 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 30 22:41:21 main-stereo volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Apr 30 22:41:21 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:41:21 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:41:22 main-stereo volumiologrotate[602]: ls: cannot access '/var/log/samba/log.wb-MAIN': No such file or directory
Apr 30 22:41:22 main-stereo volumiologrotate[602]: ls: cannot access 'STEREO': No such file or directory
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 30 22:41:26 main-stereo volumio-remote-updater[593]: No test mode
Apr 30 22:41:26 main-stereo volumio-remote-updater[593]: No alpha test mode
Apr 30 22:41:26 main-stereo volumio[984]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
- Slow down CD read speed
- Fix NAS Mounting
NEW ADDITIONS
- Revert TIDAL Connect to working version
- Fix for USB DAC not recognized after OTA update
","title":"Update v3.804","updateavailable":true}
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 30 22:41:26 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 22:41:27 main-stereo volumio[984]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 30 22:41:27 main-stereo volumio[984]: info: Completed starting MyVolumio Plugin
Apr 30 22:41:27 main-stereo volumio[984]: [Metrics] CommandRouter: 44s 124.84ms
Apr 30 22:41:27 main-stereo volumio[984]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 30 22:41:27 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 30 22:41:27 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 30 22:41:27 main-stereo volumio[984]: info: CoreCommandRouter::Close All Modals sent
Apr 30 22:41:27 main-stereo volumio[984]: info: CoreCommandRouter::Close All Modals sent
Apr 30 22:41:28 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 30 22:41:28 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 30 22:41:28 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 30 22:41:30 main-stereo ntpd[768]: Soliciting pool server 54.39.196.172
Apr 30 22:41:31 main-stereo ntpd[768]: Soliciting pool server 51.161.35.150
Apr 30 22:41:31 main-stereo ntpd[768]: Soliciting pool server 206.108.0.132
Apr 30 22:41:31 main-stereo ntpd[768]: Soliciting pool server 96.53.17.246
Apr 30 22:41:32 main-stereo ntpd[768]: Soliciting pool server 173.183.146.26
Apr 30 22:41:32 main-stereo ntpd[768]: Soliciting pool server 162.159.200.123
Apr 30 22:41:32 main-stereo ntpd[768]: Soliciting pool server 162.159.200.1
Apr 30 22:41:33 main-stereo ntpd[768]: Soliciting pool server 148.113.209.151
Apr 30 22:41:33 main-stereo ntpd[768]: Soliciting pool server 149.56.19.163
Apr 30 22:41:33 main-stereo ntpd[768]: Soliciting pool server 216.232.132.102
Apr 30 22:41:33 main-stereo ntpd[768]: Soliciting pool server 23.133.168.244
Apr 30 22:41:34 main-stereo volumio[984]: info: BOOT COMPLETED
Apr 30 22:41:34 main-stereo ntpd[768]: Soliciting pool server 208.73.56.29
Apr 30 22:41:34 main-stereo ntpd[768]: Soliciting pool server 207.34.48.31
Apr 30 22:41:34 main-stereo ntpd[768]: Soliciting pool server 23.133.168.245
Apr 30 22:41:35 main-stereo ntpd[768]: Soliciting pool server 167.114.93.151
Apr 30 22:41:35 main-stereo ntpd[768]: Soliciting pool server 2607:4100:2:ff::2
Apr 30 22:41:35 main-stereo ntpd[768]: Soliciting pool server 198.50.127.72
Apr 30 22:41:36 main-stereo ntpd[768]: Soliciting pool server 216.128.178.20
Apr 30 22:41:36 main-stereo ntpd[768]: Soliciting pool server 173.206.46.40
Apr 30 22:41:39 main-stereo ntpd[768]: receive: Unexpected origin timestamp 0xebbd5de2.cb8c6a00 does not match aorg 0000000000.00000000 from server@23.133.168.244 xmt 0xebbd5de3.2955d9f0
Apr 30 22:41:39 main-stereo ntpd[768]: receive: Unexpected origin timestamp 0xebbd5de2.cb8a7b30 does not match aorg 0000000000.00000000 from server@23.133.168.245 xmt 0xebbd5de3.29546dd2
Apr 30 22:41:39 main-stereo ntpd[768]: receive: Unexpected origin timestamp 0xebbd5de2.cb8ed755 does not match aorg 0000000000.00000000 from server@96.53.17.246 xmt 0xebbd5de3.2e8867ff
Apr 30 22:41:39 main-stereo ntpd[768]: receive: Unexpected origin timestamp 0xebbd5de2.cb8b7000 does not match aorg 0000000000.00000000 from server@216.232.132.102 xmt 0xebbd5de3.2e9fa246
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:41:58 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:41:58 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:41:58 main-stereo volumio[984]: verbose: New Socket.io Connection to 10.0.0.139:3000 from 10.0.0.144 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:41:58 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Apr 30 22:41:58 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Apr 30 22:42:00 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:00 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:01 main-stereo sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 22:42:01 main-stereo sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:01 main-stereo sudo[1484]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:01 main-stereo sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 22:42:01 main-stereo sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:01 main-stereo sudo[1487]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:01 main-stereo volumio[984]: verbose: New Socket.io Connection to 10.0.0.139 from 10.0.0.144 UA: Mozilla/5.0 (Linux; Android 15; Pixel 7 Pro Build/BP1A.250405.007.B1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Apr 30 22:42:01 main-stereo sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 22:42:01 main-stereo sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:01 main-stereo sudo[1490]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:01 main-stereo sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 22:42:01 main-stereo sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:01 main-stereo sudo[1493]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:01 main-stereo volumio[984]: verbose: New Socket.io Connection to 10.0.0.139 from 10.0.0.144 UA: Mozilla/5.0 (Linux; Android 15; Pixel 7 Pro Build/BP1A.250405.007.B1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:02 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 30 22:42:02 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:02 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:02 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:02 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:02 main-stereo volumio[984]: info: Listing playlists
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:02 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:02 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:02 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:03 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Apr 30 22:42:03 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 30 22:42:03 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:03 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:03 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:03 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:03 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:03 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:03 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:04 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 30 22:42:04 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:04 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:04 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:04 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:04 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:04 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:04 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:11 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 30 22:42:13 main-stereo volumio[984]: info: CoreCommandRouter::Close All Modals sent
Apr 30 22:42:13 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 30 22:42:20 main-stereo volumio[984]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Apr 30 22:42:20 main-stereo volumio[984]: info: Received Get System Version
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 30 22:42:20 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:20 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:20 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:20 main-stereo sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 22:42:20 main-stereo sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:20 main-stereo sudo[1530]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:20 main-stereo sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 22:42:20 main-stereo sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:20 main-stereo sudo[1533]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:20 main-stereo volumio[984]: verbose: New Socket.io Connection to 10.0.0.139 from 10.0.0.144 UA: Mozilla/5.0 (Linux; Android 15; Pixel 7 Pro Build/BP1A.250405.007.B1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Apr 30 22:42:20 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:20 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:20 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:20 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:20 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 30 22:42:20 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:20 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:20 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:20 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:20 main-stereo volumio[984]: info: Listing playlists
Apr 30 22:42:20 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 30 22:42:22 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Apr 30 22:42:22 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:22 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:22 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:22 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:22 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:22 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:22 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:23 main-stereo volumiologrotate[602]: ls: cannot access '/var/log/samba/log.wb-MAIN': No such file or directory
Apr 30 22:42:23 main-stereo volumiologrotate[602]: ls: cannot access 'STEREO': No such file or directory
Apr 30 22:42:24 main-stereo sudo[1577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 30 22:42:24 main-stereo sudo[1577]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:24 main-stereo sudo[1577]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:24 main-stereo sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 30 22:42:24 main-stereo sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 30 22:42:24 main-stereo sudo[1580]: pam_unix(sudo:session): session closed for user root
Apr 30 22:42:24 main-stereo volumio[984]: verbose: New Socket.io Connection to 10.0.0.139 from 10.0.0.144 UA: Mozilla/5.0 (Linux; Android 15; Pixel 7 Pro Build/BP1A.250405.007.B1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Apr 30 22:42:24 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:24 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Apr 30 22:42:24 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:24 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: Listing playlists
Apr 30 22:42:24 main-stereo volumio[984]: info: Received Get System Info
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:24 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:24 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:24 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Apr 30 22:42:27 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetQueue
Apr 30 22:42:27 main-stereo volumio[984]: info: CoreStateMachine::getQueue
Apr 30 22:42:27 main-stereo volumio[984]: info: CorePlayQueue::getQueue
Apr 30 22:42:27 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Apr 30 22:42:27 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Apr 30 22:42:27 main-stereo volumio[984]: info: Discovery: Getting this device information
Apr 30 22:42:27 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:27 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:27 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreCommandRouter::volumioPlay
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::play index 0
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::stop
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::play index undefined
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 30 22:42:28 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:28 main-stereo volumio[984]: info: CoreStateMachine::startPlaybackTimer
Apr 30 22:42:28 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:28 main-stereo volumio[984]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Set%20Fire%20to%20the%20Rain%22%2C%22artist%22%3A%22Adele%22%2C%22album%22%3A%2221%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fy6b4yT6dCKEVmzBvATUWodOFLYc81vwxuK0nTgE-scZ3BvyuY9639NL_UyGc6zc_ASoELG67fDUNta0%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22a2giXO6eyuI%22%2C%22playlistId%22%3A%22RDAMVMa2giXO6eyuI%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22a2giXO6eyuI%22%2C%22playlistId%22%3A%22RDAMVMa2giXO6eyuI%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D
Apr 30 22:42:28 main-stereo volumio[984]: info: [ytmusic] InnertubeLoader: creating Innertube instance...
Apr 30 22:42:33 main-stereo volumio[984]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm.
Apr 30 22:42:33 main-stereo volumio[984]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData...
Apr 30 22:42:33 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 30 22:42:33 main-stereo volumio[984]: info: CoreCommandRouter::volumioPlay
Apr 30 22:42:33 main-stereo volumio[984]: info: CoreStateMachine::play index undefined
Apr 30 22:42:33 main-stereo volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 30 22:42:33 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:33 main-stereo volumio[984]: info: CoreStateMachine::startPlaybackTimer
Apr 30 22:42:33 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:33 main-stereo volumio[984]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Set%20Fire%20to%20the%20Rain%22%2C%22artist%22%3A%22Adele%22%2C%22album%22%3A%2221%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2Fy6b4yT6dCKEVmzBvATUWodOFLYc81vwxuK0nTgE-scZ3BvyuY9639NL_UyGc6zc_ASoELG67fDUNta0%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22a2giXO6eyuI%22%2C%22playlistId%22%3A%22RDAMVMa2giXO6eyuI%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22a2giXO6eyuI%22%2C%22playlistId%22%3A%22RDAMVMa2giXO6eyuI%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D
Apr 30 22:42:34 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Apr 30 22:42:34 main-stereo volumio[984]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds)
Apr 30 22:42:34 main-stereo volumio[984]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token
Apr 30 22:42:34 main-stereo volumio[984]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token...
Apr 30 22:42:37 main-stereo volumio[984]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm.
Apr 30 22:42:37 main-stereo volumio[984]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds
Apr 30 22:42:37 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand stop
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand stop took 8 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand clear
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand clear took 4 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaISSCumlir4PlYzv8QM&ip=99.245.206.199&id=o-AKscQvqHByLcOu1LOiq0Va90Sx75WVvR7yYurFCLxnAL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aedn7l&ms=au%2Conr&mv=m&mvi=3&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4341250&bui=AecWEAZau2M8uGwZtL7aFEalTYRhOt6yVXdV6fo6USFG_ircDlYNhjH94-JAYpaH3nA2xAzpJglMiCH3&spc=wk1kZg6DMFeDWmo83UgxD5aC3tAhBf-fMqdzSMpsGn0X6cIsJGTjbU0l2HftYiOxvQI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KIebVHZ26HWRYuo8AoKPR_0Q&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067265&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_QbYGNKYr3Ye9gkCr6R&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=ACuhMU0wRQIgaC6YfpAugCD1G0i7c1tOht43knp7nmZi3AzSdEWS2CsCIQCp330uJhewi-vQDWiadOAAT1lR6MpmCgXb31psLLE8gQ%3D%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube"
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: error: updateQueue error: null
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 9ms
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand addid "https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaISSCumlir4PlYzv8QM&ip=99.245.206.199&id=o-AKscQvqHByLcOu1LOiq0Va90Sx75WVvR7yYurFCLxnAL&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aedn7l&ms=au%2Conr&mv=m&mvi=3&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4341250&bui=AecWEAZau2M8uGwZtL7aFEalTYRhOt6yVXdV6fo6USFG_ircDlYNhjH94-JAYpaH3nA2xAzpJglMiCH3&spc=wk1kZg6DMFeDWmo83UgxD5aC3tAhBf-fMqdzSMpsGn0X6cIsJGTjbU0l2HftYiOxvQI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=KIebVHZ26HWRYuo8AoKPR_0Q&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067265&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_QbYGNKYr3Ye9gkCr6R&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=ACuhMU0wRQIgaC6YfpAugCD1G0i7c1tOht43knp7nmZi3AzSdEWS2CsCIQCp330uJhewi-vQDWiadOAAT1lR6MpmCgXb31psLLE8gQ%3D%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube" took 7 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 6ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 5ms
Apr 30 22:42:37 main-stereo volumio[984]: verbose: MPD COMMAND [object Object]
Apr 30 22:42:37 main-stereo volumio[984]: verbose: MPD COMMAND [object Object]
Apr 30 22:42:37 main-stereo volumio[984]: verbose: MPD COMMAND [object Object]
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: error: Upnp client error: Error: This socket has been ended by the other party
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 8ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 5ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 4ms
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand play
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 6ms
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand play took 5 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 4ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 3ms
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand stop
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand stop took 2 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand clear
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces state update: player
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: ControllerMpd::getState
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand status
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand clear took 2 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaIOtCriilu8PloP5iA0&ip=99.245.206.199&id=o-AFhpiEz1Fxiqe9FDnr0WU5vs3-ILEBOp4z7XFiHsLANF&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aekn7e&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4297500&bui=AecWEAajLM2xe3rwEYD5kp_G__G8FCaOJAO7RICmmHZhk_6AFrchml_izXCtHF0WApUJbqZFmMsfFvzC&spc=wk1kZizF3hnPYsQajuQkAUEJIyYI6PAw1ohwAM4cYXie-9j4Ji-ViRqo8cS_1oHxMvo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IPyaNQAouW1NCfRls9IysgAQ&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067022&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_FfRC_fP2jiAlj7hONz&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%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPleliWKvHGZ_qvvOQcRT1hqKpnUj0yCR-N9e8k40jdxAiEAoMaGZiulavynwYYkOhYWhfUOdCAkBChxqydHzv4nHzo%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube"
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces state update: player
Apr 30 22:42:37 main-stereo volumio[984]: info: ControllerMpd::getState
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand status
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces state update: player
Apr 30 22:42:37 main-stereo volumio[984]: info: ControllerMpd::getState
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand status
Apr 30 22:42:37 main-stereo volumio[984]: error: updateQueue error: null
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand status took 6 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 7ms
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand addid "https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaIOtCriilu8PloP5iA0&ip=99.245.206.199&id=o-AFhpiEz1Fxiqe9FDnr0WU5vs3-ILEBOp4z7XFiHsLANF&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aekn7e&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4297500&bui=AecWEAajLM2xe3rwEYD5kp_G__G8FCaOJAO7RICmmHZhk_6AFrchml_izXCtHF0WApUJbqZFmMsfFvzC&spc=wk1kZizF3hnPYsQajuQkAUEJIyYI6PAw1ohwAM4cYXie-9j4Ji-ViRqo8cS_1oHxMvo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IPyaNQAouW1NCfRls9IysgAQ&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067022&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_FfRC_fP2jiAlj7hONz&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%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPleliWKvHGZ_qvvOQcRT1hqKpnUj0yCR-N9e8k40jdxAiEAoMaGZiulavynwYYkOhYWhfUOdCAkBChxqydHzv4nHzo%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube" took 5 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand status took 4 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand status took 2 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::parseState
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::parseState
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::parseState
Apr 30 22:42:37 main-stereo volumio[984]: info: ControllerMpd::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::servicePushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:37 main-stereo volumio[984]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 30 22:42:37 main-stereo volumio[984]: verbose: CURRENT POSITION 0
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::syncState stateService stop
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::syncState currentStatus stop
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: No code
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: verbose: MPD COMMAND [object Object]
Apr 30 22:42:37 main-stereo volumio[984]: verbose: MPD COMMAND [object Object]
Apr 30 22:42:37 main-stereo volumio[984]: verbose: MPD COMMAND [object Object]
Apr 30 22:42:37 main-stereo volumio[984]: info: ControllerMpd::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::servicePushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:37 main-stereo volumio[984]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 30 22:42:37 main-stereo volumio[984]: verbose: CURRENT POSITION 0
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::syncState stateService stop
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::syncState currentStatus stop
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: No code
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: ControllerMpd::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::servicePushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:37 main-stereo volumio[984]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Apr 30 22:42:37 main-stereo volumio[984]: verbose: CURRENT POSITION 0
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::syncState stateService stop
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::syncState currentStatus stop
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: No code
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 62ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 59ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 59ms
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 61ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 3ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 2ms
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 30 22:42:37 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand play
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info:
Apr 30 22:42:37 main-stereo volumio[984]: ---------------------------- MPD announces system playlist update
Apr 30 22:42:37 main-stereo volumio[984]: info: Ignoring MPD Status Update
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 37ms
Apr 30 22:42:37 main-stereo volumio[984]: info: sendMpdCommand play took 35 milliseconds
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 34ms
Apr 30 22:42:37 main-stereo volumio[984]: info: ------------------------------ 34ms
Apr 30 22:42:37 main-stereo volumio[984]: info: CoreCommandRouter::volumioGetState
Apr 30 22:42:38 main-stereo volumio[984]: info:
Apr 30 22:42:38 main-stereo volumio[984]: ---------------------------- MPD announces state update: player
Apr 30 22:42:38 main-stereo volumio[984]: info: ControllerMpd::getState
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand status
Apr 30 22:42:38 main-stereo volumio[984]: info:
Apr 30 22:42:38 main-stereo volumio[984]: ---------------------------- MPD announces state update: player
Apr 30 22:42:38 main-stereo volumio[984]: info: ControllerMpd::getState
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand status
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand status took 6 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::parseState
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 30 22:42:38 main-stereo volumio[984]: info:
Apr 30 22:42:38 main-stereo volumio[984]: ---------------------------- MPD announces state update: player
Apr 30 22:42:38 main-stereo volumio[984]: info: ControllerMpd::getState
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand status
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand clearerror
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand status took 10 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand clearerror took 6 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand playlistinfo took 6 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand status took 5 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::parseState
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::parseTrackInfo
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::parseState
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 30 22:42:38 main-stereo volumio[984]: info: ControllerMpd::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::servicePushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:38 main-stereo volumio[984]: 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":"Set Fire to the Rain","artist":"Adele","album":"21","uri":"https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaIOtCriilu8PloP5iA0&ip=99.245.206.199&id=o-AFhpiEz1Fxiqe9FDnr0WU5vs3-ILEBOp4z7XFiHsLANF&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aekn7e&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4297500&bui=AecWEAajLM2xe3rwEYD5kp_G__G8FCaOJAO7RICmmHZhk_6AFrchml_izXCtHF0WApUJbqZFmMsfFvzC&spc=wk1kZizF3hnPYsQajuQkAUEJIyYI6PAw1ohwAM4cYXie-9j4Ji-ViRqo8cS_1oHxMvo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IPyaNQAouW1NCfRls9IysgAQ&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067022&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_FfRC_fP2jiAlj7hONz&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%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPleliWKvHGZ_qvvOQcRT1hqKpnUj0yCR-N9e8k40jdxAiEAoMaGZiulavynwYYkOhYWhfUOdCAkBChxqydHzv4nHzo%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"}
Apr 30 22:42:38 main-stereo volumio[984]: verbose: CURRENT POSITION 0
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::syncState stateService stop
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::syncState currentStatus stop
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: No code
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: ------------------------------ 34ms
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand clearerror took 25 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand playlistinfo took 23 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: info: sendMpdCommand playlistinfo took 23 milliseconds
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::parseTrackInfo
Apr 30 22:42:38 main-stereo volumio[984]: verbose: ControllerMpd::parseTrackInfo
Apr 30 22:42:38 main-stereo volumio[984]: info: ControllerMpd::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::servicePushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:38 main-stereo volumio[984]: 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":"Set Fire to the Rain","artist":"Adele","album":"21","uri":"https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaIOtCriilu8PloP5iA0&ip=99.245.206.199&id=o-AFhpiEz1Fxiqe9FDnr0WU5vs3-ILEBOp4z7XFiHsLANF&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aekn7e&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4297500&bui=AecWEAajLM2xe3rwEYD5kp_G__G8FCaOJAO7RICmmHZhk_6AFrchml_izXCtHF0WApUJbqZFmMsfFvzC&spc=wk1kZizF3hnPYsQajuQkAUEJIyYI6PAw1ohwAM4cYXie-9j4Ji-ViRqo8cS_1oHxMvo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IPyaNQAouW1NCfRls9IysgAQ&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067022&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_FfRC_fP2jiAlj7hONz&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%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPleliWKvHGZ_qvvOQcRT1hqKpnUj0yCR-N9e8k40jdxAiEAoMaGZiulavynwYYkOhYWhfUOdCAkBChxqydHzv4nHzo%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"}
Apr 30 22:42:38 main-stereo volumio[984]: verbose: CURRENT POSITION 0
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::syncState stateService stop
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::syncState currentStatus stop
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: No code
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: ControllerMpd::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::servicePushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CorePlayQueue::getTrack 0
Apr 30 22:42:38 main-stereo volumio[984]: 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":"Set Fire to the Rain","artist":"Adele","album":"21","uri":"https://rr3---sn-gvbxgn-tt1es.googlevideo.com/videoplayback?expire=1746088957&ei=nd8SaIOtCriilu8PloP5iA0&ip=99.245.206.199&id=o-AFhpiEz1Fxiqe9FDnr0WU5vs3-ILEBOp4z7XFiHsLANF&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1746067357%2C&mh=dB&mm=31%2C26&mn=sn-gvbxgn-tt1es%2Csn-t0aekn7e&ms=au%2Conr&mv=m&mvi=3&pcm2cms=yes&pl=23&rms=au%2Cau&gcr=ca&initcwndbps=4297500&bui=AecWEAajLM2xe3rwEYD5kp_G__G8FCaOJAO7RICmmHZhk_6AFrchml_izXCtHF0WApUJbqZFmMsfFvzC&spc=wk1kZizF3hnPYsQajuQkAUEJIyYI6PAw1ohwAM4cYXie-9j4Ji-ViRqo8cS_1oHxMvo&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=IPyaNQAouW1NCfRls9IysgAQ&rqh=1&gir=yes&clen=4142049&dur=243.001&lmt=1716767004960613&mt=1746067022&fvip=2&keepalive=yes&fexp=51466698&c=WEB_REMIX&sefc=1&txp=4532434&n=mRVMLJQoZKg750BkOL-_w8_FfRC_fP2jiAlj7hONz&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%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=ACuhMU0wRgIhAPleliWKvHGZ_qvvOQcRT1hqKpnUj0yCR-N9e8k40jdxAiEAoMaGZiulavynwYYkOhYWhfUOdCAkBChxqydHzv4nHzo%3D&pot=MnjoxzIgnJkM6Oj7YY70Q0mpz2_R_BngK1L1PLgqKrt7xrsnpz_Sk1-lHMbv867mFmfaFax5zvM3g0lXzjPTB7PVVJpUJBHaffB7gfelBTFe0R9gUBCw6ajOzvStRzXk4z_qPCFQbL8wWQeeGV5t5mK7eAZqgoPIcAk%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"}
Apr 30 22:42:38 main-stereo volumio[984]: verbose: CURRENT POSITION 0
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::syncState stateService stop
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::syncState currentStatus stop
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: No code
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreStateMachine::pushState
Apr 30 22:42:38 main-stereo volumio[984]: info: CoreCommandRouter::volumioPushState
Apr 30 22:42:38 main-stereo volumio[984]: info: ------------------------------ 69ms
Apr 30 22:42:38 main-stereo volumio[984]: info: ------------------------------ 63ms
Apr 30 22:42:50 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 30 22:42:50 main-stereo volumio[984]: info: Preload queue cleared
Apr 30 22:42:50 main-stereo volumio[984]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 30 22:42:50 main-stereo volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 22:42:50 main-stereo volumio[984]: TypeError: Cannot read property 'length' of undefined
Apr 30 22:42:50 main-stereo volumio[984]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Apr 30 22:42:50 main-stereo volumio[984]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Apr 30 22:42:50 main-stereo volumio[984]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Apr 30 22:42:50 main-stereo volumio[984]: at Parser.emit (events.js:315:20)
Apr 30 22:42:50 main-stereo volumio[984]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Apr 30 22:42:50 main-stereo volumio[984]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Apr 30 22:42:50 main-stereo volumio[984]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Apr 30 22:42:50 main-stereo volumio[984]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Apr 30 22:42:50 main-stereo volumio[984]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Apr 30 22:42:50 main-stereo volumio[984]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Apr 30 22:42:50 main-stereo volumio[984]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Apr 30 22:42:50 main-stereo volumio[984]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Apr 30 22:42:50 main-stereo volumio[984]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Apr 30 22:42:50 main-stereo volumio[984]: at IncomingMessage.emit (events.js:327:22)
Apr 30 22:42:50 main-stereo volumio[984]: at endReadableNT (internal/streams/readable.js:1327:12)
Apr 30 22:42:50 main-stereo volumio[984]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 30 22:42:50 main-stereo volumio[984]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 30 22:42:51 main-stereo sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-30 22:41
Apr 30 22:42:51 main-stereo sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET"
VOLUMIO_VERSION="3.795"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"