-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Sun 2024-04-21 13:59:38 +07. -- Apr 21 13:58:28 volumio systemd[1]: Starting Daily apt download activities... Apr 21 13:58:28 volumio systemd[1]: Starting Daily man-db regeneration... Apr 21 13:58:28 volumio systemd[1]: man-db.service: Succeeded. Apr 21 13:58:28 volumio systemd[1]: Started Daily man-db regeneration. Apr 21 13:58:28 volumio volumio[1069]: info: Discovery: A device disappeared from network Apr 21 13:58:28 volumio volumio[1069]: info: Discovery: Device volumio disappeared from network Apr 21 13:58:28 volumio volumio[1069]: info: Discovery: adding 6afb9747-a036-42a5-946f-fe7bb88314dc Apr 21 13:58:28 volumio volumio[1069]: info: Discovery: Found device Volumio Apr 21 13:58:28 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:28 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:28 volumio systemd[1]: apt-daily.service: Succeeded. Apr 21 13:58:28 volumio systemd[1]: Started Daily apt download activities. Apr 21 13:58:28 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Apr 21 13:58:29 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Apr 21 13:58:29 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 21 13:58:29 volumio volumio-remote-updater[534]: [2024-04-21 13:58:29] [connect] Successful connection Apr 21 13:58:29 volumio volumio-remote-updater[534]: [2024-04-21 13:58:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1713682709 101 Apr 21 13:58:29 volumio volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 2 Apr 21 13:58:33 volumio volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 3 Apr 21 13:58:34 volumio sudo[1659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:58:34 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:58:34 volumio sudo[1659]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:58:34 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:58:34 volumio sudo[1659]: pam_unix(sudo:session): session closed for user root Apr 21 13:58:34 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Apr 21 13:58:34 volumio sudo[1680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 21 13:58:34 volumio sudo[1680]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:58:34 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 21 13:58:34 volumio sudo[1680]: pam_unix(sudo:session): session closed for user root Apr 21 13:58:35 volumio volumio[1069]: info: Upmpdcli Daemon Started Apr 21 13:58:35 volumio volumio[1686]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 21 13:58:35 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 21 13:58:36 volumio sh[447]: timed out Apr 21 13:58:36 volumio dhcpcd[497]: timed out Apr 21 13:58:36 volumio sh[447]: dhcpcd exited Apr 21 13:58:36 volumio dhcpcd[497]: dhcpcd exited Apr 21 13:58:36 volumio sh[447]: ifup: failed to bring up eth0 Apr 21 13:58:36 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 21 13:58:36 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 21 13:58:36 volumio ifplugd(eth0)[714]: client: sending commands to master dhcpcd process Apr 21 13:58:36 volumio dhcpcd[1728]: sending commands to master dhcpcd process Apr 21 13:58:36 volumio dhcpcd[1054]: control command: /sbin/dhcpcd eth0 Apr 21 13:58:36 volumio ifplugd(eth0)[714]: Program executed successfully. Apr 21 13:58:36 volumio ifplugd(eth0)[714]: Link beat lost. Apr 21 13:58:36 volumio ifplugd[664]: Network Interface Plugging Daemon...start eth0...done. Apr 21 13:58:36 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 21 13:58:36 volumio systemd[1]: Reached target Multi-User System. Apr 21 13:58:36 volumio systemd[1]: Reached target Graphical Interface. Apr 21 13:58:36 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 21 13:58:36 volumio volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 4 Apr 21 13:58:36 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 21 13:58:36 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 21 13:58:36 volumio systemd[1]: Startup finished in 16.388s (kernel) + 32.760s (userspace) = 49.149s. Apr 21 13:58:36 volumio volumio[1686]: ...................++++ Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 21 13:58:36 volumio volumio[1069]: info: Adding plugin bluetooth to MyMusic Plugins Apr 21 13:58:36 volumio volumio[1069]: info: Adding plugin multiroom to MyMusic Plugins Apr 21 13:58:36 volumio volumio[1069]: info: Adding plugin metavolumio to MyMusic Plugins Apr 21 13:58:36 volumio volumio[1069]: info: Adding plugin cd_controller to MyMusic Plugins Apr 21 13:58:36 volumio volumio[1069]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 21 13:58:36 volumio volumio[1069]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 21 13:58:36 volumio volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 21 13:58:37 volumio volumio[1686]: .................++++ Apr 21 13:58:37 volumio volumio[1686]: e is 65537 (0x010001) Apr 21 13:58:37 volumio volumio[1686]: writing RSA key Apr 21 13:58:37 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 21 13:58:37 volumio volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 21 13:58:37 volumio volumio[1069]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 21 13:58:37 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:37 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:37 volumio volumio[1069]: info: Starting MyVolumio Remote Streaming Endpoints Apr 21 13:58:38 volumio volumio[1069]: info: MyVolumio login type: Token Apr 21 13:58:38 volumio volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 21 13:58:38 volumio volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 21 13:58:39 volumio volumio[1069]: info: Starting Streaming Service Transparent Proxy Apr 21 13:58:39 volumio volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 21 13:58:39 volumio volumio[1069]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 21 13:58:39 volumio volumio[1069]: info: Streaming services startup Apr 21 13:58:39 volumio volumio[1069]: info: Starting Streaming Daemon Apr 21 13:58:39 volumio sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 21 13:58:39 volumio volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 21 13:58:39 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:58:39 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root Apr 21 13:58:39 volumio volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 5 Apr 21 13:58:39 volumio volumio[1069]: error: Cannot start Volumio Streaming Daemon Apr 21 13:58:39 volumio volumio[1069]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 21 13:58:39 volumio volumio[1069]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 21 13:58:39 volumio volumio[1069]: error: MyVolumio Custom Token format not valid, refreshing it Apr 21 13:58:39 volumio volumio[1069]: STREAMING PROXY: Starting server on port 3245 Apr 21 13:58:39 volumio volumio[1069]: Node JS runtime: 14 Apr 21 13:58:40 volumio volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 6 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 21 13:58:40 volumio volumio[1069]: info: MyVolumio login type: Token Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 13:58:40 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:40 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: Listing playlists Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:58:40 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:40 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 13:58:40 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:40 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: Listing playlists Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 13:58:40 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:40 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:40 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:40 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:41 volumio volumio[1069]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 21 13:58:41 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 21 13:58:41 volumio volumio[1069]: info: MyVolumio login type: Token Apr 21 13:58:41 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 21 13:58:41 volumio volumio[1069]: info: MyVolumio login type: Token Apr 21 13:58:42 volumio volumio[1069]: info: MyVolumio token set successfully Apr 21 13:58:42 volumio volumio[1069]: info: MYVOLUMIO: Adding device Apr 21 13:58:42 volumio volumio[1069]: info: MYVOLUMIO: Evaluating Server Apr 21 13:58:42 volumio volumio[1069]: info: MyVolumio status changed Apr 21 13:58:42 volumio volumio[1069]: info: Streaming services startup Apr 21 13:58:42 volumio volumio[1069]: info: Starting Streaming Daemon Apr 21 13:58:42 volumio volumio[1069]: info: Removing browser output: myVolumio user plan is not superstar Apr 21 13:58:42 volumio volumio[1069]: info: Removing audio output: Apr 21 13:58:42 volumio volumio[1069]: info: Stoppping Tunnel 1 Apr 21 13:58:43 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 21 13:58:43 volumio sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 21 13:58:43 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:58:43 volumio sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:58:43 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Apr 21 13:58:43 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root Apr 21 13:58:43 volumio volumio[1069]: error: Cannot start Volumio Streaming Daemon Apr 21 13:58:43 volumio volumio[1069]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 21 13:58:43 volumio volumio[1069]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 21 13:58:43 volumio volumio[1069]: info: Remote SSH Stopped Apr 21 13:58:44 volumio volumio[1069]: info: Setting Geolocation for MyVolumio to as1 Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:58:44 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:44 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:44 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:58:44 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:44 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:44 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:58:44 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:44 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:44 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:44 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:44 volumio volumio[1069]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 21 13:58:45 volumio volumio[1069]: info: Updating MyVolumio device info Apr 21 13:58:45 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:45 volumio volumio[1069]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 21 13:58:46 volumio ifplugd(eth0)[714]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Apr 21 13:58:46 volumio dhcpcd[1900]: sending commands to master dhcpcd process Apr 21 13:58:46 volumio ifplugd(eth0)[714]: client: sending commands to master dhcpcd process Apr 21 13:58:46 volumio dhcpcd[1054]: control command: /sbin/dhcpcd -k eth0 Apr 21 13:58:46 volumio dhcpcd[1054]: eth0: removing interface Apr 21 13:58:46 volumio ifplugd(eth0)[714]: Program executed successfully. Apr 21 13:58:48 volumio volumio[1069]: info: MYVOLUMIO: Adding device Apr 21 13:58:48 volumio volumio[1069]: info: MYVOLUMIO: Evaluating Server Apr 21 13:58:49 volumio volumio[1069]: info: Setting Geolocation for MyVolumio to as1 Apr 21 13:58:49 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:49 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:49 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:50 volumio volumio[1069]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 21 13:58:50 volumio volumio[1069]: info: Updating MyVolumio device info Apr 21 13:58:50 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:50 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:50 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:51 volumio volumio[1069]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 21 13:58:53 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 13:58:53 volumio volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 21 13:58:53 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:53 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 13:58:55 volumio volumio[1069]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 21 13:58:55 volumio volumio[1069]: info: Received Get System Version Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:58:55 volumio volumio[1069]: info: Received Get System Info Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:58:55 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:58:55 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:55 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 13:58:56 volumio volumio-remote-updater[534]: No test mode Apr 21 13:58:56 volumio volumio-remote-updater[534]: No alpha test mode Apr 21 13:58:56 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 21 13:58:56 volumio volumio[1069]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 21 13:58:56 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 13:58:58 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:58:59 volumio volumio[1069]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 21 13:58:59 volumio volumio[1069]: info: Completed starting MyVolumio Plugin Apr 21 13:58:59 volumio volumio[1069]: info: BOOT COMPLETED Apr 21 13:58:59 volumio volumio[1069]: [Metrics] CommandRouter: 37s 552.68ms Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::volumiosetStartupVolume Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 13:58:59 volumio volumio[1069]: info: VolumeController:: Setting startup Volume 45 Apr 21 13:58:59 volumio volumio[1069]: info: VolumeController::SetAlsaVolume45 Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::Close All Modals sent Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::Close All Modals sent Apr 21 13:58:59 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:58:59 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:58:59 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:00 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 13:59:00 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 13:59:00 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 13:59:03 volumio sudo[1950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:59:03 volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:59:03 volumio sudo[1950]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:59:03 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:59:03 volumio sudo[1950]: pam_unix(sudo:session): session closed for user root Apr 21 13:59:03 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Apr 21 13:59:03 volumio volumio[1069]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 8 Apr 21 13:59:03 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 21 13:59:03 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 21 13:59:03 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:59:03 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Apr 21 13:59:03 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 13:59:03 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root Apr 21 13:59:03 volumio volumio[1069]: verbose: New Socket.io Connection to 192.168.1.66 from 192.168.1.48 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 9 Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::volumioGetVisibleSources Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:03 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 21 13:59:03 volumio volumio[1069]: info: Received Get System Info Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:59:03 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:03 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:03 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:03 volumio volumio[1069]: info: Listing playlists Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 21 13:59:03 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 13:59:04 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 21 13:59:04 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:04 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:05 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:59:05 volumio volumio[1069]: info: Received Get System Info Apr 21 13:59:05 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:59:05 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:59:05 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:59:05 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:05 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:05 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:59:07 volumio volumio[1069]: info: VolumeController::SetAlsaVolume15 Apr 21 13:59:07 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:07 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 21 13:59:07 volumio volumio[1069]: info: Received Get System Info Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:59:07 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:07 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:07 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPlay Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::play index undefined Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::startPlaybackTimer Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::clearAddPlayTracks USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand stop took 2 milliseconds Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand clear Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand clear took 0 milliseconds Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand add "USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac" Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Apr 21 13:59:09 volumio volumio[1069]: info: Ignoring MPD Status Update Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Apr 21 13:59:09 volumio volumio[1069]: info: Ignoring MPD Status Update Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Apr 21 13:59:09 volumio volumio[1069]: info: Ignoring MPD Status Update Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Apr 21 13:59:09 volumio volumio[1069]: info: Ignoring MPD Status Update Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand add "USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac" took 5 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 6ms Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 4ms Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 3ms Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand play Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Apr 21 13:59:09 volumio volumio[1069]: info: Ignoring MPD Status Update Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces system playlist update Apr 21 13:59:09 volumio volumio[1069]: info: Ignoring MPD Status Update Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 9ms Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand play took 6 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 6ms Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 5ms Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::getState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::getState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::getState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand status took 20 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand status took 18 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand status took 18 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::getState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::getState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand status took 13 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 12 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 11 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand status took 3 milliseconds Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"122 Kbps","isStreaming":false,"title":"Moon River","artist":"Danny Williams","album":"The Very Best Easy Listening Music","uri":"USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac","trackType":"flac"} Apr 21 13:59:09 volumio volumio[1069]: verbose: CURRENT POSITION 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":155,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"727 Kbps","isStreaming":false,"title":"Moon River","artist":"Danny Williams","album":"The Very Best Easy Listening Music","uri":"USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac","trackType":"flac"} Apr 21 13:59:09 volumio volumio[1069]: verbose: CURRENT POSITION 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:59:09 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":155,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"728 Kbps","isStreaming":false,"title":"Moon River","artist":"Danny Williams","album":"The Very Best Easy Listening Music","uri":"USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac","trackType":"flac"} Apr 21 13:59:09 volumio volumio[1069]: verbose: CURRENT POSITION 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:59:09 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 63ms Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 131ms Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 131ms Apr 21 13:59:09 volumio volumio[1069]: info: Apr 21 13:59:09 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::getState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 102 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 105 milliseconds Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand status took 8 milliseconds Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseState Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":155,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"Moon River","artist":"Danny Williams","album":"The Very Best Easy Listening Music","uri":"USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac","trackType":"flac"} Apr 21 13:59:09 volumio volumio[1069]: verbose: CURRENT POSITION 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:59:09 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":155,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"Moon River","artist":"Danny Williams","album":"The Very Best Easy Listening Music","uri":"USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac","trackType":"flac"} Apr 21 13:59:09 volumio volumio[1069]: verbose: CURRENT POSITION 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:59:09 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 194ms Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 186ms Apr 21 13:59:09 volumio volumio[1069]: info: sendMpdCommand playlistinfo took 94 milliseconds Apr 21 13:59:09 volumio volumio[1069]: verbose: ControllerMpd::parseTrackInfo Apr 21 13:59:09 volumio volumio[1069]: info: ControllerMpd::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::servicePushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":155,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"Moon River","artist":"Danny Williams","album":"The Very Best Easy Listening Music","uri":"USB/SanDisk/International/The Very Best Easy Listening Music [2013] FLAC (16bit-44.1kHz)/01 - Moon River.flac","trackType":"flac"} Apr 21 13:59:09 volumio volumio[1069]: verbose: CURRENT POSITION 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState stateService play Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::syncState currentStatus play Apr 21 13:59:09 volumio volumio[1069]: info: Received an update from plugin. extracting info from payload Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:09 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:09 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:09 volumio volumio[1069]: info: ------------------------------ 123ms Apr 21 13:59:14 volumio volumio[1069]: info: CoreCommandRouter::volumioGetQueue Apr 21 13:59:14 volumio volumio[1069]: info: CoreStateMachine::getQueue Apr 21 13:59:14 volumio volumio[1069]: info: CorePlayQueue::getQueue Apr 21 13:59:18 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 21 13:59:18 volumio volumio[1069]: info: CURURI: music-library Apr 21 13:59:18 volumio volumio[1069]: info: Preload queue cleared Apr 21 13:59:19 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 21 13:59:19 volumio volumio[1069]: info: CURURI: music-library/USB Apr 21 13:59:19 volumio volumio[1069]: info: Preload queue cleared Apr 21 13:59:19 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 21 13:59:19 volumio volumio[1069]: info: CURURI: music-library/USB/SanDisk Apr 21 13:59:20 volumio volumio[1069]: info: Preload queue cleared Apr 21 13:59:22 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 21 13:59:22 volumio volumio[1069]: info: CURURI: music-library/USB/SanDisk/International Apr 21 13:59:22 volumio volumio[1069]: info: Preload queue cleared Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 13:59:23 volumio volumio[1069]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 21 13:59:23 volumio volumio[1069]: info: Received Get System Version Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 13:59:23 volumio volumio[1069]: info: Received Get System Info Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 21 13:59:23 volumio volumio[1069]: info: Discovery: Getting this device information Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::volumioGetState Apr 21 13:59:23 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:23 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 21 13:59:38 volumio volumio[1069]: info: Preload queue cleared Apr 21 13:59:38 volumio volumio[1069]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::ClearQueue Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::stop Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::stPlaybackTimer Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::updateTrackBlock Apr 21 13:59:38 volumio volumio[1069]: info: CorePlayQueue::getTrackBlock Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::pushState Apr 21 13:59:38 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:38 volumio volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 21 13:59:38 volumio volumio[1069]: info: CoreCommandRouter::volumioPushState Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::serviceStop Apr 21 13:59:38 volumio volumio[1069]: info: CorePlayQueue::getTrack 0 Apr 21 13:59:38 volumio volumio[1069]: info: CoreCommandRouter::serviceStop Apr 21 13:59:38 volumio volumio[1069]: info: ControllerMpd::stop Apr 21 13:59:38 volumio volumio[1069]: verbose: ControllerMpd::sendMpdCommand stop Apr 21 13:59:38 volumio volumio[1069]: info: CorePlayQueue::clearPlayQueue Apr 21 13:59:38 volumio volumio[1069]: info: CorePlayQueue::saveQueue Apr 21 13:59:38 volumio volumio[1069]: info: CoreCommandRouter::volumioPushQueue Apr 21 13:59:38 volumio volumio[1069]: info: CoreStateMachine::addQueueItems Apr 21 13:59:38 volumio volumio[1069]: info: CorePlayQueue::addQueueItems Apr 21 13:59:38 volumio volumio[1069]: info: Preload queue cleared Apr 21 13:59:38 volumio volumio[1069]: info: Adding Item to queue: music-library/USB/SanDisk/International/原音极品示范天碟Acoustic Solutions (STS) Apr 21 13:59:38 volumio volumio[1069]: info: Exploding uri music-library/USB/SanDisk/International/原音极品示范天碟Acoustic Solutions (STS) in service mpd Apr 21 13:59:38 volumio volumio[1069]: info: Apr 21 13:59:38 volumio volumio[1069]: ---------------------------- MPD announces state update: player Apr 21 13:59:38 volumio volumio[1069]: info: ALBUMART /albumart?cacheid=525&web=H.Arlen/Acoustic%20Solutions%20Ninth%20edition/extralarge&path=%2Fmnt%2FUSB%2FSanDisk%2FInternational%2F%E5%8E%9F%E9%9F%B3%E6%9E%81%E5%93%81%E7%A4%BA%E8%8C%83%E5%A4%A9%E7%A2%9FAcoustic%20Solutions%20(STS)%2FAcoustic%20Solutions%209.cue&metadata=false Apr 21 13:59:38 volumio volumio[1069]: info: URI /mnt/USB/SanDisk/International/原音极品示范天碟Acoustic Solutions (STS)/Acoustic Solutions 9.cue Apr 21 13:59:38 volumio volumio[1069]: info: ALBUMART /albumart?cacheid=525&web=A.Jobim/Acoustic%20Solutions%20Ninth%20edition/extralarge&path=%2Fmnt%2FUSB%2FSanDisk%2FInternational%2F%E5%8E%9F%E9%9F%B3%E6%9E%81%E5%93%81%E7%A4%BA%E8%8C%83%E5%A4%A9%E7%A2%9FAcoustic%20Solutions%20(STS)%2FAcoustic%20Solutions%209.cue&metadata=false Apr 21 13:59:38 volumio volumio[1069]: info: URI /mnt/USB/SanDisk/International/原音极品示范天碟Acoustic Solutions (STS)/Acoustic Solutions 9.cue Apr 21 13:59:38 volumio volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 13:59:38 volumio volumio[1069]: Error: Unable to resolve or reject the same promise twice Apr 21 13:59:38 volumio volumio[1069]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 21 13:59:38 volumio volumio[1069]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 21 13:59:38 volumio volumio[1069]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 21 13:59:38 volumio volumio[1069]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 21 13:59:38 volumio volumio[1069]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 21 13:59:38 volumio volumio[1069]: at Socket.emit (events.js:400:28) Apr 21 13:59:38 volumio volumio[1069]: at addChunk (internal/streams/readable.js:293:12) Apr 21 13:59:38 volumio volumio[1069]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 21 13:59:38 volumio volumio[1069]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 21 13:59:38 volumio volumio[1069]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 21 13:59:38 volumio volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 13:59:38 volumio sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-21 13:58 Apr 21 13:59:38 volumio sudo[2117]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:15:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="293a767fd1b71cabc5f5be4916f770a0"