-- Logs begin at Thu 2024-04-11 13:26:44 CEST, end at Thu 2024-04-11 14:08:18 CEST. -- Apr 11 14:08:03 volumio shairport-sync[10656]: {"time":1712837180227,"response":"startAirplayPlayback Success"}Very large sync error: 2023934 frames, with delay: 2112134, td_in_frames: 2176240, rt: 5520958879, nt: 5521022985, current_delay: 0, seqno: 35827, given timestamp: 1226055689. Apr 11 14:08:03 volumio shairport-sync[10656]: Very large sync error: 2023707 frames, with delay: 2111907, td_in_frames: 2176365, rt: 5520958879, nt: 5521023337, current_delay: 0, seqno: 35828, given timestamp: 1226056041. Apr 11 14:08:03 volumio shairport-sync[10656]: Very large sync error: 2023360 frames, with delay: 2111560, td_in_frames: 2176370, rt: 5520958879, nt: 5521023689, current_delay: 0, seqno: 35829, given timestamp: 1226056393. Apr 11 14:08:03 volumio shairport-sync[10656]: Very large sync error: 2023010 frames, with delay: 2111210, td_in_frames: 2176372, rt: 5520958879, nt: 5521024041, current_delay: 0, seqno: 35830, given timestamp: 1226056745. Apr 11 14:08:03 volumio shairport-sync[10656]: Shome mhistake shurely: very large number of frames to drop: 2023010 -- setting it to 132300. Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio shairport-sync[10656]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 11 14:08:09 volumio shairport-sync[10656]: Dload Upload Total Spent Left Speed Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::servicePushState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:09 volumio volumio[888]: info: camilladsp spawned new process with pid 11105, instance 1, run: true Apr 11 14:08:09 volumio volumio[888]: info: Apr 11 14:08:09 volumio volumio[888]: ---------------------------- Client requests Stop Airplay Playback Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Apr 11 14:08:09 volumio volumio[888]: info: Airplay playback stop Apr 11 14:08:09 volumio volumio[888]: info: Airplay Playback Stopped, clearing state Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::resetVolumioState Apr 11 14:08:09 volumio volumio[888]: info: CoreStateMachine::getcurrentVolume Apr 11 14:08:09 volumio volumio[888]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 14:08:10 volumio shairport-sync[10656]: [158B blob data] Apr 11 14:08:10 volumio volumio[888]: info: VolumeController:: Volume=51 Mute =false Apr 11 14:08:10 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:10 volumio volumio[888]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 14:08:10 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:10 volumio volumio[888]: info: CoreStateMachine::updateTrackBlock Apr 11 14:08:10 volumio volumio[888]: info: CorePlayQueue::getTrackBlock Apr 11 14:08:10 volumio volumio[888]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 14:08:10 volumio volumio[888]: info: VolumeController:: Volume=51 Mute =false Apr 11 14:08:10 volumio volumio[888]: info: CoreStateMachine::pushState Apr 11 14:08:10 volumio volumio[888]: info: CoreCommandRouter::volumioPushState Apr 11 14:08:18 volumio volumio[888]: info: [1712837298084] [80s80s] Pushing the next song state The Verve Pipe - Photograph and getting next track. Apr 11 14:08:18 volumio volumio[888]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 14:08:18 volumio volumio[888]: TypeError: Cannot set property 'name' of undefined Apr 11 14:08:18 volumio volumio[888]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Apr 11 14:08:18 volumio volumio[888]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Apr 11 14:08:18 volumio volumio[888]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Apr 11 14:08:18 volumio volumio[888]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Apr 11 14:08:18 volumio volumio[888]: at processImmediate (internal/timers.js:464:21) Apr 11 14:08:18 volumio volumio[888]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 14:08:18 volumio sudo[11126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 14:07 Apr 11 14:08:18 volumio sudo[11126]: 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="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"