-- Logs begin at Thu 2019-02-14 17:11:59 WIB, end at Sun 2026-01-11 13:26:27 WIB. -- Jan 11 13:26:16 volumio ntpd[781]: receive: Unexpected origin timestamp 0xed0630ed.1e4a7191 does not match aorg 0000000000.00000000 from server@202.162.32.12 xmt 0xed0dc108.b6ed2c90 Jan 11 13:26:16 volumio ntpd[781]: receive: Unexpected origin timestamp 0xed0630ed.1e41ec2c does not match aorg 0000000000.00000000 from server@165.154.228.19 xmt 0xed0dc108.b15acef4 Jan 11 13:26:16 volumio ntpd[781]: receive: Unexpected origin timestamp 0xed0630ed.1e478531 does not match aorg 0000000000.00000000 from server@103.134.77.146 xmt 0xed0dc108.b3489723 Jan 11 13:26:16 volumio ntpd[781]: receive: Unexpected origin timestamp 0xed0630ed.1e4cd21f does not match aorg 0000000000.00000000 from server@203.89.31.13 xmt 0xed0dc108.b42e8622 Jan 11 13:26:16 volumio systemd[1]: Starting Daily man-db regeneration... Jan 11 13:26:16 volumio systemd[1]: Starting Daily apt download activities... Jan 11 13:26:16 volumio volumio[977]: info: Discovery: A device disappeared from network Jan 11 13:26:16 volumio volumio[977]: info: Discovery: Device volumio disappeared from network Jan 11 13:26:17 volumio volumio[977]: info: Discovery: adding c6bd13b9-1d80-4d32-b700-9021285df1b7 Jan 11 13:26:17 volumio volumio[977]: info: Discovery: Found device Volumio Jan 11 13:26:17 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Jan 11 13:26:17 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jan 11 13:26:17 volumio systemd[1]: man-db.service: Succeeded. Jan 11 13:26:17 volumio systemd[1]: Started Daily man-db regeneration. Jan 11 13:26:20 volumio systemd[1]: apt-daily.service: Succeeded. Jan 11 13:26:20 volumio systemd[1]: Started Daily apt download activities. Jan 11 13:26:20 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin bluetooth to MyMusic Plugins Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin multiroom to MyMusic Plugins Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin metavolumio to MyMusic Plugins Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin cd_controller to MyMusic Plugins Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 11 13:26:20 volumio volumio[977]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4JAvHpjipBk" Jan 11 13:26:20 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWWvoJqVv7uOD" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX2L0iB23Enbq" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXcRXFNfZr7Tp" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXcBWIGoYBM5M" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX3rxVfibe1L0" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX0s5kDXi1oC5" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4WYpdgoIcn6" Jan 11 13:26:20 volumio go-librespot[1293]: time="2026-01-11T13:26:20+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Jan 11 13:26:21 volumio go-librespot[1293]: time="2026-01-11T13:26:21+07:00" level=debug msg="handling transfer player command from a1e59d4f80597b182e321c605f6c231a0e00826c" Jan 11 13:26:22 volumio volumio[977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 11 13:26:22 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 11 13:26:22 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:26:22 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 13:26:22 volumio volumio[977]: info: Starting MyVolumio Remote Streaming Endpoints Jan 11 13:26:22 volumio volumio[977]: info: MyVolumio login type: Token Jan 11 13:26:22 volumio volumio[977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 11 13:26:22 volumio volumio[977]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 11 13:26:22 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 11 13:26:22 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jan 11 13:26:22 volumio go-librespot[1293]: time="2026-01-11T13:26:22+07:00" level=debug msg="resolved context of track" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:22 volumio go-librespot[1293]: time="2026-01-11T13:26:22+07:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:23 volumio go-librespot[1293]: time="2026-01-11T13:26:23+07:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:23 volumio go-librespot[1293]: time="2026-01-11T13:26:23+07:00" level=trace msg="fetched new page 1 with 50 items (list: 100)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:23 volumio go-librespot[1293]: time="2026-01-11T13:26:23+07:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:23 volumio go-librespot[1293]: time="2026-01-11T13:26:23+07:00" level=trace msg="fetched new page 2 with 50 items (list: 150)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:23 volumio volumio[977]: info: Starting Streaming Service Transparent Proxy Jan 11 13:26:23 volumio volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 11 13:26:23 volumio volumio[977]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 11 13:26:23 volumio volumio[977]: info: Streaming services startup Jan 11 13:26:23 volumio volumio[977]: info: Starting Streaming Daemon Jan 11 13:26:24 volumio volumio[977]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 11 13:26:24 volumio sudo[2005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 13:26:24 volumio sudo[2005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:26:24 volumio sudo[2005]: pam_unix(sudo:session): session closed for user root Jan 11 13:26:24 volumio volumio[977]: error: Cannot start Volumio Streaming Daemon Jan 11 13:26:24 volumio volumio[977]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 11 13:26:24 volumio volumio[977]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 11 13:26:24 volumio go-librespot[1293]: time="2026-01-11T13:26:24+07:00" level=trace msg="fetched new page from (has next: true)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:24 volumio go-librespot[1293]: time="2026-01-11T13:26:24+07:00" level=trace msg="fetched new page 3 with 50 items (list: 200)" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:24 volumio volumio[977]: error: MyVolumio Custom Token format not valid, refreshing it Jan 11 13:26:24 volumio go-librespot[1293]: time="2026-01-11T13:26:24+07:00" level=warning msg="failed seeking to track in context spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" error="failed fetching tracks for seek: failed moving to next index 200 (page 4): failed fetching next page: invalid status code from page at radio-apollo/v5/tracks/spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP?salt=603285&autoplay=false&count=50&isVideo=false&prev_tracks=5Ds0VGkTSQ1jf4KzLUpZPb,19aYH2zhbnTNx4plfenrIk,1NCuYqMc8hKMb4cpNTcJbD,3RMeOetCdXttthQK0clPuz,3cfnGXJ9bmiWvFqEO6ff8B,7wWw7hBmErNg9u5w6Xx0vy,6kvoHl80mfCVTv7XnZkjQn,1JLn8RhQzHz3qDqsChcmBl,10I09MLZCY4pK37XxAfkw0,0qbhFj4bVrguIZViOJOs36,0dqRtewfRKQM4SEzWEpzDU,6zC0mpGYwbNTpk9SKwh08f,2TQaMcc3BEAF5Srn7FCipl,7gh2v4IHnxdiwSgA6xluhe,6Ycf7Ch2VlEKlORbz7yfpJ,2OgVsp77En2nju8pnCieVU,2vUoP3ppYxD1F5ovbnmM46,5ljGmNLjvQZm2hw8cDJXNJ,63CHa6rmamv9OsehkRD8oz,2374M0fQpWi3dLnB54qaLX,3X7uFMzJrEE0sxn62qd8Ch,6mIY6O7uNGgVqOoX70UAYh,5fnOrhQ4KgT5irGCjIccGH,1Df1rxgMob1qQudexDEKuu,3pHpV3pa96s4vuBP7sQq0V,2Ho6HCaHwmRLAGnH91165S,5GHY1DFWKz3Prg2V0Iodqo,2lFFiNm0XtgJ6wkdncTB4k,66rdGSb6FMm0neFXxmJdPQ,0KMGxYKeUzK9wc5DZCt3HT,41sGGCCoHI2GLV9qadX80A,5EdlwzcgIAYs2HGvOnczPN,4F2t297QK40XFcIHqkmLHs,2GpBrAoCwt48fxjgjlzMd4,7N0iCGxTzN2gJPxnTdIRiT,1WeoeHh0TSzsApyJ6Q8OOK,3Wo1NNRahoesVY5db0cEwl,6l46wjsV1bY2qpwuHj7zV5,7IX2e7pEShera9T1QIMvi7,36lkJSDyMEZoWTqtRj8Q8q,1JSTJqkT5qHq8MDJnJbRE1,0yfNXxlyXdmP0ue1iJijx1,6CKoWCWAqEVWVjpeoJXyNH,1kgdslQYmeTR4thk9whoRw,2yBVeksU2EtrPJbTu4ZslK,5ac3D4hNLW7NFhYFCThXgh,0Yp603BgzIKDDcSUBrYYba,6VnpKLtfNH4Dk09YSGPSyR,3yUcJwYu7fXAfqMj9krY6l,4P0uyibZjcXZLVeNOHBd8Q&minimal=true?salt=763900&autoplay=false&count=50&isVideo=false&prev_tracks=08onVqQ8YicJ98Ycm1qoLf,3TLtCHUaiMHcQcJVGVW2p2,3RMeOetCdXttthQK0clPuz,0UOxp1BpnD8uPQMKU4wKjz,3cfnGXJ9bmiWvFqEO6ff8B,10I09MLZCY4pK37XxAfkw0,19aYH2zhbnTNx4plfenrIk,1NCuYqMc8hKMb4cpNTcJbD,6kvoHl80mfCVTv7XnZkjQn,6zC0mpGYwbNTpk9SKwh08f,0dqRtewfRKQM4SEzWEpzDU,6Ycf7Ch2VlEKlORbz7yfpJ,1JLn8RhQzHz3qDqsChcmBl,02HtMmMgd4SYJx76Alv71n,3X7uFMzJrEE0sxn62qd8Ch,6mIY6O7uNGgVqOoX70UAYh,2vUoP3ppYxD1F5ovbnmM46,63CHa6rmamv9OsehkRD8oz,0qbhFj4bVrguIZViOJOs36,2OgVsp77En2nju8pnCieVU,5ljGmNLjvQZm2hw8cDJXNJ,66rdGSb6FMm0neFXxmJdPQ,1R9J6Lscr2ijqarEAAVNqx,1WeoeHh0TSzsApyJ6Q8OOK,0HIfQL1DHt1OiJzba86rej,0zxj7dVhYZq5ACyaDx5XIo,5EdlwzcgIAYs2HGvOnczPN,3pHpV3pa96s4vuBP7sQq0V,36lkJSDyMEZoWTqtRj8Q8q,62YP3NEB4FVPJ90XcgJQKS,6ZSZQoRMIgetNOV3VK53o8,41sGGCCoHI2GLV9qadX80A,4lsEHglVsLzILTDQ3CNESV,1kgdslQYmeTR4thk9whoRw,0KMGxYKeUzK9wc5DZCt3HT,3yUcJwYu7fXAfqMj9krY6l,2374M0fQpWi3dLnB54qaLX,5fnOrhQ4KgT5irGCjIccGH,6OPi0rUWmRWJhe1BjeOlJb,7ktxXsACTciz3gAtuzd1uV,2MaBAGBIttgv86bYytdx1f,6VnpKLtfNH4Dk09YSGPSyR,2r008pcfVYc0zgQvSRqUJE,1JSTJqkT5qHq8MDJnJbRE1,65bvH7egzl7h9f7uJmqGV4,1UdoFJDUHWu4oWH5JlwmDP,5ac3D4hNLW7NFhYFCThXgh,0ofHAoxe9vBkTCp2UQIavz,2yBVeksU2EtrPJbTu4ZslK,73GR50SgGV5hybgWDSFWtD?salt=892721&autoplay=false&count=50&isVideo=false&prev_tracks=08onVqQ8YicJ98Ycm1qoLf,3TLtCHUaiMHcQcJVGVW2p2,3RMeOetCdXttthQK0clPuz,0UOxp1BpnD8uPQMKU4wKjz,3cfnGXJ9bmiWvFqEO6ff8B,10I09MLZCY4pK37XxAfkw0,19aYH2zhbnTNx4plfenrIk,1NCuYqMc8hKMb4cpNTcJbD,6kvoHl80mfCVTv7XnZkjQn,6zC0mpGYwbNTpk9SKwh08f,0dqRtewfRKQM4SEzWEpzDU,6Ycf7Ch2VlEKlORbz7yfpJ,1JLn8RhQzHz3qDqsChcmBl,02HtMmMgd4SYJx76Alv71n,3X7uFMzJrEE0sxn62qd8Ch,6mIY6O7uNGgVqOoX70UAYh,2vUoP3ppYxD1F5ovbnmM46,63CHa6rmamv9OsehkRD8oz,0qbhFj4bVrguIZViOJOs36,2OgVsp77En2nju8pnCieVU,5ljGmNLjvQZm2hw8cDJXNJ,66rdGSb6FMm0neFXxmJdPQ,1R9J6Lscr2ijqarEAAVNqx,1WeoeHh0TSzsApyJ6Q8OOK,0HIfQL1DHt1OiJzba86rej,0zxj7dVhYZq5ACyaDx5XIo,5EdlwzcgIAYs2HGvOnczPN,3pHpV3pa96s4vuBP7sQq0V,36lkJSDyMEZoWTqtRj8Q8q,62YP3NEB4FVPJ90XcgJQKS,6ZSZQoRMIgetNOV3VK53o8,41sGGCCoHI2GLV9qadX80A,4lsEHglVsLzILTDQ3CNESV,1kgdslQYmeTR4thk9whoRw,0KMGxYKeUzK9wc5DZCt3HT,3yUcJwYu7fXAfqMj9krY6l,2374M0fQpWi3dLnB54qaLX,5fnOrhQ4KgT5irGCjIccGH,6OPi0rUWmRWJhe1BjeOlJb,7ktxXsACTciz3gAtuzd1uV,2MaBAGBIttgv86bYytdx1f,6VnpKLtfNH4Dk09YSGPSyR,2r008pcfVYc0zgQvSRqUJE,1JSTJqkT5qHq8MDJnJbRE1,65bvH7egzl7h9f7uJmqGV4,1UdoFJDUHWu4oWH5JlwmDP,5ac3D4hNLW7NFhYFCThXgh,0ofHAoxe9vBkTCp2UQIavz,2yBVeksU2EtrPJbTu4ZslK,73GR50SgGV5hybgWDSFWtD?salt=258146&autoplay=false&count=50&isVideo=false&prev_tracks=08onVqQ8YicJ98Ycm1qoLf,3TLtCHUaiMHcQcJVGVW2p2,3RMeOetCdXttthQK0clPuz,0UOxp1BpnD8uPQMKU4wKjz,3cfnGXJ9bmiWvFqEO6ff8B,10I09MLZCY4pK37XxAfkw0,19aYH2zhbnTNx4plfenrIk,1NCuYqMc8hKMb4cpNTcJbD,6kvoHl80mfCVTv7XnZkjQn,6zC0mpGYwbNTpk9SKwh08f,0dqRtewfRKQM4SEzWEpzDU,6Ycf7Ch2VlEKlORbz7yfpJ,1JLn8RhQzHz3qDqsChcmBl,02HtMmMgd4SYJx76Alv71n,3X7uFMzJrEE0sxn62qd8Ch,6mIY6O7uNGgVqOoX70UAYh,2vUoP3ppYxD1F5ovbnmM46,63CHa6rmamv9OsehkRD8oz,0qbhFj4bVrguIZViOJOs36,2OgVsp77En2nju8pnCieVU,5ljGmNLjvQZm2hw8cDJXNJ,66rdGSb6FMm0neFXxmJdPQ,1R9J6Lscr2ijqarEAAVNqx,1WeoeHh0TSzsApyJ6Q8OOK,0HIfQL1DHt1OiJzba86rej,0zxj7dVhYZq5ACyaDx5XIo,5EdlwzcgIAYs2HGvOnczPN,3pHpV3pa96s4vuBP7sQq0V,36lkJSDyMEZoWTqtRj8Q8q,62YP3NEB4FVPJ90XcgJQKS,6ZSZQoRMIgetNOV3VK53o8,41sGGCCoHI2GLV9qadX80A,4lsEHglVsLzILTDQ3CNESV,1kgdslQYmeTR4thk9whoRw,0KMGxYKeUzK9wc5DZCt3HT,3yUcJwYu7fXAfqMj9krY6l,2374M0fQpWi3dLnB54qaLX,5fnOrhQ4KgT5irGCjIccGH,6OPi0rUWmRWJhe1BjeOlJb,7ktxXsACTciz3gAtuzd1uV,2MaBAGBIttgv86bYytdx1f,6VnpKLtfNH4Dk09YSGPSyR,2r008pcfVYc0zgQvSRqUJE,1JSTJqkT5qHq8MDJnJbRE1,65bvH7egzl7h9f7uJmqGV4,1UdoFJDUHWu4oWH5JlwmDP,5ac3D4hNLW7NFhYFCThXgh,0ofHAoxe9vBkTCp2UQIavz,2yBVeksU2EtrPJbTu4ZslK,73GR50SgGV5hybgWDSFWtD: 414" uri="spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP" Jan 11 13:26:24 volumio go-librespot[1293]: time="2026-01-11T13:26:24+07:00" level=debug msg="loading track (paused: true, position: 8150ms)" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=trace msg="emitting websocket event: will_play" Jan 11 13:26:25 volumio volumio[977]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:station:playlist:5EbbcmE1vP1tGIGaXncvRP","uri":"spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb","play_origin":""}} Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1382" Jan 11 13:26:25 volumio volumio[977]: STREAMING PROXY: Starting server on port 3245 Jan 11 13:26:25 volumio volumio[977]: Node JS runtime: 14 Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=debug msg="selected format OGG_VORBIS_320 (549ae7aebd944b69bce275c417a6567717a28ebd)" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=debug msg="requested aes key for file 549ae7aebd944b69bce275c417a6567717a28ebd, gid: 5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:25 volumio go-librespot[1293]: time="2026-01-11T13:26:25+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1135" Jan 11 13:26:25 volumio volumio[977]: info: MyVolumio login type: Token Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:26 volumio volumio[977]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=debug msg="fetched first chunk of 21, total size is 10524840 bytes" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=trace msg="seek to 8150ms (diff: 31ms, samples: 359415, bytes: 319591)" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=debug msg="created new output device" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=info msg="loaded track \"Change the World\" (paused: true, position: 8150ms, duration: 234866ms, prefetched: false)" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=debug msg="fetched chunk 2/20, size: 524288" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 170" Jan 11 13:26:26 volumio go-librespot[1293]: time="2026-01-11T13:26:26+07:00" level=debug msg="fetched chunk 1/20, size: 524288" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=debug msg="fetched chunk 3/20, size: 524288" uri="spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb" Jan 11 13:26:27 volumio volumio[977]: info: MyVolumio token set successfully Jan 11 13:26:27 volumio volumio[977]: info: MYVOLUMIO: Adding device Jan 11 13:26:27 volumio volumio[977]: info: MYVOLUMIO: Evaluating Server Jan 11 13:26:27 volumio volumio-time-update[611]: volumio-time-update-util: Setting system time to: 2026-01-11 13:26:27 Jan 11 13:26:27 volumio sudo[2015]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-01-11 13:26:27 Jan 11 13:26:27 volumio sudo[2015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:26:27 volumio dbus-daemon[613]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.20' (uid=0 pid=2016 comm="timedatectl set-time 2026-01-11 13:26:27 ") Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=trace msg="emitting websocket event: metadata" Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=trace msg="emitting websocket event: active" Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=debug msg="sending successful reply for dealer request" Jan 11 13:26:27 volumio systemd[1]: Starting Time & Date Service... Jan 11 13:26:27 volumio dbus-daemon[613]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 11 13:26:27 volumio systemd[1]: Started Time & Date Service. Jan 11 13:26:27 volumio systemd-timedated[2019]: Changed local time to Sun Jan 11 13:26:27 2026 Jan 11 13:26:27 volumio sudo[2015]: pam_unix(sudo:session): session closed for user root Jan 11 13:26:27 volumio volumio-time-update[611]: volumio-time-update-util: System time updated successfully. Jan 11 13:26:27 volumio systemd[1]: Started Volumio Time Update Utility. Jan 11 13:26:27 volumio systemd[1]: Reached target Multi-User System. Jan 11 13:26:27 volumio systemd[1]: Reached target Graphical Interface. Jan 11 13:26:27 volumio volumio[977]: info: MyVolumio status changed Jan 11 13:26:27 volumio volumio[977]: info: Streaming services startup Jan 11 13:26:27 volumio volumio[977]: info: Starting Streaming Daemon Jan 11 13:26:27 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 11 13:26:27 volumio volumio[977]: info: Removing browser output: myVolumio user plan is not superstar Jan 11 13:26:27 volumio volumio[977]: info: Removing audio output: Jan 11 13:26:27 volumio volumio[977]: info: Stoppping Tunnel 1 Jan 11 13:26:27 volumio volumio[977]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5Ds0VGkTSQ1jf4KzLUpZPb","name":"Change the World","artist_names":["Eric Clapton"],"album_name":"Clapton Chronicles: The Best of Eric Clapton","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0284edd1376faafd5e350bd7a9","position":8150,"duration":234866,"release_date":"year:1999 month:10 day:12","track_number":2,"disc_number":1}} Jan 11 13:26:27 volumio sudo[2039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 11 13:26:27 volumio sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:26:27 volumio volumio[977]: SPOTIFY: received: {"type":"active","data":null} Jan 11 13:26:27 volumio volumio[977]: info: Aligning Spotify Volume to Volumio Volume Jan 11 13:26:27 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Jan 11 13:26:27 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Jan 11 13:26:27 volumio volumio[977]: info: Setting Spotify Volume from Volumio: 75 Jan 11 13:26:27 volumio sudo[2041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 11 13:26:27 volumio sudo[2041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 13:26:27 volumio volumio[977]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Jan 11 13:26:27 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:26:27 volumio volumio[977]: Error: Unable to resolve or reject the same promise twice Jan 11 13:26:27 volumio volumio[977]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 11 13:26:27 volumio volumio[977]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Jan 11 13:26:27 volumio volumio[977]: at Socket.emit (events.js:315:20) Jan 11 13:26:27 volumio volumio[977]: at addChunk (internal/streams/readable.js:309:12) Jan 11 13:26:27 volumio volumio[977]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 11 13:26:27 volumio volumio[977]: at Socket.Readable.push (internal/streams/readable.js:223:10) Jan 11 13:26:27 volumio volumio[977]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jan 11 13:26:27 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 13:26:27 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 11 13:26:27 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 11 13:26:27 volumio sudo[2039]: pam_unix(sudo:session): session closed for user root Jan 11 13:26:27 volumio sudo[2041]: pam_unix(sudo:session): session closed for user root Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 11 13:26:27 volumio go-librespot[1293]: time="2026-01-11T13:26:27+07:00" level=trace msg="emitting websocket event: paused" Jan 11 13:26:27 volumio sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 13:25 Jan 11 13:26:27 volumio sudo[2053]: 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="5d50cd80bebce60dddccd7f5e813d656e28ca4cd" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="7840f283c310c38bfe939a4c485727a6ff6a0ce1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 21 Oct 2025 03:46:47 PM CEST" VOLUMIO_VERSION="3.866" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e2f3a8aca3147ba51b5da8167dd6ff8"