-- Logs begin at Thu 2019-02-14 17:11:58 +07, end at Sat 2025-08-30 09:55:54 +07. -- Aug 30 09:55:47 volumio systemd-timedated[1793]: Changed local time to Sat Aug 30 09:55:47 2025 Aug 30 09:55:47 volumio systemd[1]: Starting Daily apt download activities... Aug 30 09:55:47 volumio sudo[1791]: pam_unix(sudo:session): session closed for user root Aug 30 09:55:47 volumio volumio-time-update[685]: volumio-time-update-util: System time updated successfully. Aug 30 09:55:47 volumio systemd[1]: Started Volumio Time Update Utility. Aug 30 09:55:47 volumio systemd[1]: Reached target Multi-User System. Aug 30 09:55:47 volumio systemd[1]: Reached target Graphical Interface. Aug 30 09:55:47 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 30 09:55:47 volumio volumio[1177]: info: Discovery: A device disappeared from network Aug 30 09:55:47 volumio volumio[1177]: info: Discovery: Device volumio disappeared from network Aug 30 09:55:47 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 30 09:55:47 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 30 09:55:47 volumio volumio[1177]: info: Discovery: adding 2fb789cf-9433-4f2e-8b77-89a5ba124c20 Aug 30 09:55:47 volumio volumio[1177]: info: Discovery: Found device Volumio Aug 30 09:55:47 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Aug 30 09:55:47 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Aug 30 09:55:47 volumio systemd[1]: apt-daily.service: Succeeded. Aug 30 09:55:47 volumio systemd[1]: Started Daily apt download activities. Aug 30 09:55:47 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Aug 30 09:55:48 volumio wpa_supplicant[1036]: RRM: Ignoring radio measurement request: Not RRM network Aug 30 09:55:48 volumio volumio[1177]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 30 09:55:48 volumio volumio[1177]: info: CoreStateMachine::getcurrentVolume Aug 30 09:55:48 volumio volumio[1177]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 09:55:48 volumio volumio[1177]: info: VolumeController:: Volume=20 Mute =false Aug 30 09:55:48 volumio volumio[1177]: info: CoreStateMachine::pushState Aug 30 09:55:48 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Aug 30 09:55:48 volumio volumio[1177]: info: CoreCommandRouter::volumioPushState Aug 30 09:55:48 volumio volumio[1177]: info: CoreStateMachine::updateTrackBlock Aug 30 09:55:48 volumio volumio[1177]: info: CorePlayQueue::getTrackBlock Aug 30 09:55:48 volumio volumio[1177]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 09:55:48 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 30 09:55:48 volumio systemd[1]: Started Daily apt upgrade and clean activities. Aug 30 09:55:48 volumio volumio[1177]: info: VolumeController:: Volume=20 Mute =false Aug 30 09:55:48 volumio volumio[1177]: info: CoreStateMachine::pushState Aug 30 09:55:48 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Aug 30 09:55:48 volumio volumio[1177]: info: CoreCommandRouter::volumioPushState Aug 30 09:55:48 volumio systemd[1]: Startup finished in 11.540s (kernel) + 38.939s (userspace) = 50.480s. Aug 30 09:55:48 volumio dhcpcd[1048]: eth0: offered 192.168.1.170 from 192.168.1.1 Aug 30 09:55:48 volumio dhcpcd[1048]: eth0: probing address 192.168.1.170/24 Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin bluetooth to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin multiroom to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin metavolumio to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin cd_controller to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 30 09:55:48 volumio volumio[1177]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 30 09:55:49 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:55:49 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:55:49 volumio volumio[1177]: info: Starting MyVolumio Remote Streaming Endpoints Aug 30 09:55:49 volumio volumio[1177]: info: MyVolumio login type: Token Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 30 09:55:49 volumio volumio[1177]: info: Starting Streaming Service Transparent Proxy Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 30 09:55:49 volumio volumio[1177]: info: Streaming services startup Aug 30 09:55:49 volumio volumio[1177]: info: Starting Streaming Daemon Aug 30 09:55:49 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 09:55:49 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:55:49 volumio volumio[1177]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 30 09:55:49 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Aug 30 09:55:49 volumio volumio[1177]: error: Cannot start Volumio Streaming Daemon Aug 30 09:55:49 volumio volumio[1177]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 09:55:49 volumio volumio[1177]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 09:55:49 volumio volumio[1177]: STREAMING PROXY: Starting server on port 3245 Aug 30 09:55:49 volumio volumio[1177]: Node JS runtime: 14 Aug 30 09:55:49 volumio volumio[1177]: error: MyVolumio Custom Token format not valid, refreshing it Aug 30 09:55:49 volumio volumio[1177]: info: Getting Spotify volume Aug 30 09:55:49 volumio volumio[1177]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 30 09:55:49 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Aug 30 09:55:49 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Aug 30 09:55:49 volumio volumio[1177]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Aug 30 09:55:49 volumio volumio[1177]: SPOTIFY: SPOTIFY VOLUME undefined Aug 30 09:55:49 volumio volumio[1177]: SPOTIFY: VOLUMIO VOLUME 20 Aug 30 09:55:49 volumio volumio[1177]: info: Aligning Spotify Volume to Volumio Volume Aug 30 09:55:49 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Aug 30 09:55:49 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Aug 30 09:55:49 volumio volumio[1177]: info: Setting Spotify Volume from Volumio: 20 Aug 30 09:55:50 volumio volumio[1177]: info: MyVolumio login type: Token Aug 30 09:55:50 volumio volumio[1601]: .....................................................................................................................................................++++ Aug 30 09:55:50 volumio volumio[1601]: e is 65537 (0x010001) Aug 30 09:55:50 volumio volumio[1601]: writing RSA key Aug 30 09:55:51 volumio volumio[1177]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 30 09:55:51 volumio volumio[1177]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Aug 30 09:55:51 volumio volumio[1177]: info: Sending Spotify command with payload to local API: /player/volume Aug 30 09:55:51 volumio volumio[1177]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.832&uuid=1cff0d6b2c49fc7a5d87e75d4004fadb" http://updates.volumio.org/downloader-v1/track-device Aug 30 09:55:51 volumio volumio[1177]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 30 09:55:51 volumio volumio[1177]: Dload Upload Total Spent Left Speed Aug 30 09:55:51 volumio volumio[1177]: [132B blob data] Aug 30 09:55:51 volumio volumio[1177]: retrying in 5 seconds, trial 1 Aug 30 09:55:51 volumio volumio[1177]: info: Volumio Calling Home Aug 30 09:55:51 volumio volumio[1177]: info: MyVolumio token set successfully Aug 30 09:55:51 volumio volumio[1177]: info: MYVOLUMIO: Adding device Aug 30 09:55:51 volumio volumio[1177]: info: MYVOLUMIO: Evaluating Server Aug 30 09:55:52 volumio volumio[1177]: info: MyVolumio Plan changed: premium Aug 30 09:55:52 volumio volumio[1177]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Aug 30 09:55:52 volumio volumio[1177]: info: Removing browser output: myVolumio user plan is not superstar Aug 30 09:55:52 volumio volumio[1177]: info: Removing audio output: Aug 30 09:55:52 volumio volumio[1177]: info: MYVOLUMIO: Adding device Aug 30 09:55:52 volumio volumio[1177]: info: MYVOLUMIO: Evaluating Server Aug 30 09:55:52 volumio volumio[1177]: info: Volumio called home Aug 30 09:55:52 volumio volumio[1177]: info: Remote config written successfully Aug 30 09:55:52 volumio volumio[1177]: info: Starting Tunnel 1 Aug 30 09:55:52 volumio volumio[1177]: info: Starting Tunnel Connection Checker Aug 30 09:55:52 volumio volumio[1177]: info: Completed starting MyVolumio Plugin Aug 30 09:55:52 volumio volumio[1177]: info: MYVolumio Device enabled Aug 30 09:55:52 volumio volumio[1177]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 30 09:55:52 volumio volumio[1177]: info: MyVolumio status changed Aug 30 09:55:52 volumio volumio[1177]: info: Streaming services startup Aug 30 09:55:52 volumio volumio[1177]: info: Starting Streaming Daemon Aug 30 09:55:52 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 30 09:55:52 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 09:55:52 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 30 09:55:52 volumio volumio[1177]: error: [MyVolumio PluginManager] Cache data is invalid! Aug 30 09:55:52 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Aug 30 09:55:52 volumio volumio[1177]: error: Cannot start Volumio Streaming Daemon Aug 30 09:55:52 volumio volumio[1177]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 30 09:55:52 volumio volumio[1177]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 30 09:55:52 volumio dhcpcd[1048]: wlan0: carrier lost Aug 30 09:55:52 volumio wpa_supplicant[1036]: wlan0: Associated with ca:9f:1a:10:5e:a0 Aug 30 09:55:52 volumio wpa_supplicant[1036]: wlan0: CTRL-EVENT-CONNECTED - Connection to ca:9f:1a:10:5e:a0 completed [id=0 id_str=] Aug 30 09:55:52 volumio wpa_supplicant[1036]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 30 09:55:52 volumio avahi-daemon[679]: Withdrawing address record for 192.168.1.171 on wlan0. Aug 30 09:55:52 volumio avahi-daemon[679]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.171. Aug 30 09:55:52 volumio avahi-daemon[679]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 30 09:55:52 volumio dhcpcd[1048]: wlan0: deleting route to 192.168.1.0/24 Aug 30 09:55:52 volumio dhcpcd[1048]: wlan0: deleting default route via 192.168.1.1 Aug 30 09:55:52 volumio volumio[1177]: info: Discovery: A device disappeared from network Aug 30 09:55:52 volumio volumio[1177]: info: Discovery: Device volumio disappeared from network Aug 30 09:55:52 volumio dhcpcd[1048]: wlan0: carrier acquired Aug 30 09:55:52 volumio dhcpcd[1048]: wlan0: IAID 67:64:0b:b4 Aug 30 09:55:53 volumio dhcpcd[1048]: wlan0: soliciting an IPv6 router Aug 30 09:55:53 volumio dhcpcd[1048]: wlan0: soliciting a DHCP lease Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu10.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu4.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint us3.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: info: Setting Geolocation for MyVolumio to as1 Aug 30 09:55:53 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:55:53 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:55:53 volumio volumio[1177]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu3.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu6.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu5.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to add MyVolumio device: undefined Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu7.myvolumio.org : unknown error Aug 30 09:55:53 volumio volumio[1177]: error: Failed to ping endpoint eu8.myvolumio.org : unknown error Aug 30 09:55:53 volumio dhcpcd[1048]: eth0: leased 192.168.1.170 for 86400 seconds Aug 30 09:55:53 volumio dhcpcd[1048]: eth0: adding route to 192.168.1.0/24 Aug 30 09:55:53 volumio avahi-daemon[679]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.170. Aug 30 09:55:53 volumio dhcpcd[1048]: eth0: adding default route via 192.168.1.1 Aug 30 09:55:53 volumio avahi-daemon[679]: New relevant interface eth0.IPv4 for mDNS. Aug 30 09:55:53 volumio avahi-daemon[679]: Registering new address record for 192.168.1.170 on eth0.IPv4. Aug 30 09:55:54 volumio volumio[1177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 09:55:54 volumio volumio[1177]: Error: Unable to resolve or reject the same promise twice Aug 30 09:55:54 volumio volumio[1177]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 30 09:55:54 volumio volumio[1177]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32119) Aug 30 09:55:54 volumio volumio[1177]: at Socket.emit (events.js:327:22) Aug 30 09:55:54 volumio volumio[1177]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 30 09:55:54 volumio volumio[1177]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 30 09:55:54 volumio volumio[1177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 09:55:54 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 09:54 Aug 30 09:55:54 volumio sudo[2047]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"