-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sun 2024-08-18 14:27:49 CST. -- Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:00 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:00 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (我们都被忘了) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:01 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:02 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:02 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (都被别人忘了) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:03 volumio go-librespot[5245]: time="2024-08-18T14:26:03+08:00" level=error msg="failed receiving packet" error="failed reading packet header: EOF" Aug 18 14:26:04 volumio go-librespot[5245]: time="2024-08-18T14:26:04+08:00" level=debug msg="completed keyexchange" Aug 18 14:26:04 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:04 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:04 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (爱情该用多少字来形容) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="completed challenge" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="authenticated as 313azrxmjfh6sie35zdbghlhywc4" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="re-established accesspoint connection" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Aug 18 14:26:05 volumio go-librespot[5245]: time="2024-08-18T14:26:05+08:00" level=debug msg="autoplay enabled: false" Aug 18 14:26:07 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:08 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:08 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (你讲的淡定轻松) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:10 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:10 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:11 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:11 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (我看着乌云飞走) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:13 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:13 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (因为所有你的话我都懂) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:13 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:14 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:17 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:17 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (爱常有始无终) and artist (谢安琪/我们都被忘了 (电视剧 《我爱你爱你爱我》片尾曲)) passed on explicitly Aug 18 14:26:19 volumio volumio[5006]: error: [LastFM] track info request failed with error: Track not found Aug 18 14:26:19 volumio ntpd[844]: receive: Unexpected origin timestamp 0xea6c128a.91b11db5 does not match aorg 0xea6c128b.91b108f1 from server@198.18.6.179 xmt 0xea6c128b.7458eb2c Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:44 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:44 volumio volumio[5006]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 18 14:26:44 volumio volumio[5006]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioGetState Aug 18 14:26:45 volumio volumio[5006]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 18 14:26:45 volumio volumio[5006]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:26:45 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:26:45 volumio volumio[5006]: info: [LastFM] Current track has sufficient metadata: title (Trouble Is a Friend (Nadastrom's Wishful Dub Remix)) and artist (Lenka) passed on explicitly Aug 18 14:26:45 volumio volumio[5006]: info: [LastFM] updating timer, previous duration is obsolete; adding 37500 milliseconds. Aug 18 14:26:45 volumio volumio[5006]: Currently remaining: 206250 Aug 18 14:26:45 volumio volumio[5006]: -------------------------------------------------// Adding 37500 milliseconds to a total of 243750. Aug 18 14:26:45 volumio volumio[5006]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Lenka/Trouble%20Is%20A%20Friend%20-%20The%20Remixes/d70a6464-2a5d-4d5f-a672-1872b1024496.jpg' Aug 18 14:26:46 volumio volumio[5006]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Trouble Is A Friend (Nadastrom's Wishful Dub Remix)","url":"https://www.last.fm/music/Lenka/_/Trouble+Is+A+Friend+(Nadastrom%27s+Wishful+Dub+Remix)","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"242","playcount":"620","artist":{"name":"Lenka","mbid":"ae9ed5e2-4caf-4b3d-9cb3-2ad626b91714","url":"https://www.last.fm/music/Lenka"},"userplaycount":"0","userloved":"0","toptags":""}} Aug 18 14:27:06 volumio go-librespot[5245]: time="2024-08-18T14:27:06+08:00" level=error msg="failed receiving packet" error="failed reading packet header: EOF" Aug 18 14:27:08 volumio go-librespot[5245]: time="2024-08-18T14:27:08+08:00" level=debug msg="completed keyexchange" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="completed challenge" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="authenticated as 313azrxmjfh6sie35zdbghlhywc4" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="re-established accesspoint connection" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="autoplay enabled: false" Aug 18 14:27:09 volumio go-librespot[5245]: time="2024-08-18T14:27:09+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:12 volumio shairport-sync[5244]: {"time":1723962191802,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 14:27:12 volumio shairport-sync[5244]: Dload Upload Total Spent Left Speed Aug 18 14:27:12 volumio volumio[5006]: info: Aug 18 14:27:12 volumio volumio[5006]: ---------------------------- Client requests Stop Airplay Playback Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Aug 18 14:27:12 volumio volumio[5006]: info: Airplay playback stop Aug 18 14:27:12 volumio volumio[5006]: info: Airplay Playback Stopped, clearing state Aug 18 14:27:12 volumio volumio[5006]: info: CoreStateMachine::resetVolumioState Aug 18 14:27:12 volumio volumio[5006]: info: CoreStateMachine::getcurrentVolume Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 14:27:12 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:12 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:12 volumio shairport-sync[5244]: [158B blob data] Aug 18 14:27:13 volumio volumio[5006]: info: Airplay started streaming, receiving metadatas Aug 18 14:27:13 volumio shairport-sync[5244]: {"time":1723962432269,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 14:27:13 volumio shairport-sync[5244]: Dload Upload Total Spent Left Speed Aug 18 14:27:13 volumio volumio[5006]: info: Aug 18 14:27:13 volumio volumio[5006]: ---------------------------- Client requests Start Airplay PlaybackRoutine Aug 18 14:27:13 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Aug 18 14:27:13 volumio volumio[5006]: info: Airplay playback start Aug 18 14:27:13 volumio shairport-sync[5244]: [158B blob data] Aug 18 14:27:13 volumio shairport-sync[5244]: {"time":1723962433294,"response":"startAirplayPlayback Success"}FIXME: unhandled prediction type for compressed case: 6 Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: Not enough space if the output buffer for audio frame - E2. Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: Not enough space if the output buffer for audio frame - E2. Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: unhandled prediction type for compressed case: 15 Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: unhandled prediction type on channel 1: 2 Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: unhandled prediction type on channel 2: 4 Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: Not enough space if the output buffer for audio frame - E3. Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: unhandled prediction type for compressed case: 11 Aug 18 14:27:13 volumio shairport-sync[5244]: FIXME: Not enough space if the output buffer for audio frame - E3. Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::servicePushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreStateMachine::pushState Aug 18 14:27:14 volumio volumio[5006]: info: CoreCommandRouter::volumioPushState Aug 18 14:27:14 volumio shairport-sync[5244]: dbus[5244]: Watch is invalid, it should have been removed Aug 18 14:27:14 volumio shairport-sync[5244]: D-Bus not built with -rdynamic so unable to print a backtrace Aug 18 14:27:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Aug 18 14:27:14 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 14:27:47 volumio volumio[5006]: info: Discovery: Getting this device information Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::volumioGetState Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 14:27:47 volumio volumio[5006]: info: Discovery: Getting this device information Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::volumioGetState Aug 18 14:27:47 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 14:27:48 volumio volumio[5006]: verbose: New Socket.io Connection to 10.0.0.240:3000 from 10.0.0.154 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 18 14:27:48 volumio volumio[5006]: verbose: New Socket.io Connection to 10.0.0.240 from 10.0.0.154 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::volumioGetState Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 14:27:48 volumio volumio[5006]: info: Received Get System Info Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 14:27:48 volumio volumio[5006]: info: Discovery: Getting this device information Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::volumioGetState Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 14:27:48 volumio volumio[5006]: info: CoreCommandRouter::volumioGetState Aug 18 14:27:48 volumio volumio[5006]: info: Listing playlists Aug 18 14:27:49 volumio volumio[5006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 14:27:49 volumio volumio[5006]: Error: connect ETIMEDOUT 198.18.6.213:80 Aug 18 14:27:49 volumio volumio[5006]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 18 14:27:49 volumio volumio[5006]: errno: -110, Aug 18 14:27:49 volumio volumio[5006]: code: 'ETIMEDOUT', Aug 18 14:27:49 volumio volumio[5006]: syscall: 'connect', Aug 18 14:27:49 volumio volumio[5006]: address: '198.18.6.213', Aug 18 14:27:49 volumio volumio[5006]: port: 80 Aug 18 14:27:49 volumio volumio[5006]: } Aug 18 14:27:49 volumio volumio[5006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 14:27:49 volumio sudo[5706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 14:26 Aug 18 14:27:49 volumio sudo[5706]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"