-- Logs begin at Fri 2025-02-21 07:02:00 NZDT, end at Fri 2025-02-21 21:26:27 NZDT. -- Feb 21 21:25:05 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:13 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:14 media-ground volumio[934]: info: CoreCommandRouter::volumioPlay Feb 21 21:25:14 media-ground volumio[934]: info: CoreStateMachine::play index undefined Feb 21 21:25:14 media-ground volumio[934]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:25:14 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:14 media-ground volumio[934]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:25:14 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:14 media-ground volumio[934]: info: [1740126314081] ControllerSpotify::clearAddPlayTrack Feb 21 21:25:14 media-ground volumio[934]: info: Sending Spotify command with payload to local API: /player/play Feb 21 21:25:15 media-ground volumio[934]: info: CoreCommandRouter::volumioPlay Feb 21 21:25:15 media-ground volumio[934]: info: CoreStateMachine::play index undefined Feb 21 21:25:15 media-ground volumio[934]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:25:15 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:15 media-ground volumio[934]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:25:15 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:15 media-ground volumio[934]: info: [1740126315847] ControllerSpotify::clearAddPlayTrack Feb 21 21:25:15 media-ground volumio[934]: info: Sending Spotify command with payload to local API: /player/play Feb 21 21:25:16 media-ground go-librespot[24361]: time="2025-02-21T21:25:16+13:00" level=error msg="did not receive last pong from dealer, 36015s passed" Feb 21 21:25:17 media-ground volumio[934]: info: CoreCommandRouter::volumioPlay Feb 21 21:25:17 media-ground volumio[934]: info: CoreStateMachine::play index undefined Feb 21 21:25:17 media-ground volumio[934]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:25:17 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:17 media-ground volumio[934]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:25:17 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:17 media-ground volumio[934]: info: [1740126317002] ControllerSpotify::clearAddPlayTrack Feb 21 21:25:17 media-ground volumio[934]: info: Sending Spotify command with payload to local API: /player/play Feb 21 21:25:17 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:17 media-ground volumio[934]: info: CoreCommandRouter::volumioRandom Feb 21 21:25:17 media-ground volumio[934]: info: CoreCommandRouter::writePlayerControls Feb 21 21:25:17 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:17 media-ground volumio[934]: info: CoreStateMachine::setRepeat true single true Feb 21 21:25:17 media-ground volumio[934]: info: CoreStateMachine::pushState Feb 21 21:25:17 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:17 media-ground volumio[934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:25:17 media-ground volumio[934]: info: CoreCommandRouter::volumioPushState Feb 21 21:25:17 media-ground volumio[934]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 21 21:25:17 media-ground volumio[934]: SPOTIFY: SPOTIFY VOLUME 66 Feb 21 21:25:17 media-ground volumio[934]: SPOTIFY: VOLUMIO VOLUME 65 Feb 21 21:25:17 media-ground volumio[934]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 21 21:25:19 media-ground volumio[934]: info: CoreCommandRouter::volumioRandom Feb 21 21:25:19 media-ground volumio[934]: info: CoreCommandRouter::writePlayerControls Feb 21 21:25:19 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:19 media-ground volumio[934]: info: CoreStateMachine::setRepeat false single false Feb 21 21:25:19 media-ground volumio[934]: info: CoreStateMachine::pushState Feb 21 21:25:19 media-ground volumio[934]: info: CorePlayQueue::getTrack 18 Feb 21 21:25:19 media-ground volumio[934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:25:19 media-ground volumio[934]: info: CoreCommandRouter::volumioPushState Feb 21 21:25:19 media-ground volumio[934]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Feb 21 21:25:19 media-ground volumio[934]: SPOTIFY: SPOTIFY VOLUME 66 Feb 21 21:25:19 media-ground volumio[934]: SPOTIFY: VOLUMIO VOLUME 65 Feb 21 21:25:19 media-ground volumio[934]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 21 21:25:20 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:21 media-ground volumio[934]: info: CoreCommandRouter::volumioPrevious Feb 21 21:25:21 media-ground volumio[934]: info: CoreStateMachine::previous Feb 21 21:25:21 media-ground volumio[934]: info: CoreStateMachine::updateTrackBlock Feb 21 21:25:21 media-ground volumio[934]: info: CorePlayQueue::getTrackBlock Feb 21 21:25:21 media-ground volumio[934]: info: CoreStateMachine::serviceClearAddPlay Feb 21 21:25:21 media-ground volumio[934]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 21 21:25:21 media-ground volumio[934]: error: WARNING: No clearAddPlayTracks method for service spop Feb 21 21:25:21 media-ground volumio[934]: info: CoreCommandRouter::volumioPrevious Feb 21 21:25:21 media-ground volumio[934]: info: CoreStateMachine::previous Feb 21 21:25:21 media-ground volumio[934]: info: CoreStateMachine::updateTrackBlock Feb 21 21:25:21 media-ground volumio[934]: info: CorePlayQueue::getTrackBlock Feb 21 21:25:21 media-ground volumio[934]: info: CoreStateMachine::serviceClearAddPlay Feb 21 21:25:21 media-ground volumio[934]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 21 21:25:21 media-ground volumio[934]: error: WARNING: No clearAddPlayTracks method for service spop Feb 21 21:25:23 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:26 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:32 media-ground volumio[934]: info: VolumeController::SetAlsaVolume69 Feb 21 21:25:32 media-ground volumio[934]: info: CoreStateMachine::pushState Feb 21 21:25:32 media-ground volumio[934]: info: CorePlayQueue::getTrack 408 Feb 21 21:25:32 media-ground volumio[934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:25:32 media-ground volumio[934]: info: CoreCommandRouter::volumioPushState Feb 21 21:25:32 media-ground volumio[934]: SPOTIFY: RECEIVED VOLUMIO VOLUME 69 Feb 21 21:25:32 media-ground volumio[934]: SPOTIFY: SPOTIFY VOLUME 66 Feb 21 21:25:32 media-ground volumio[934]: SPOTIFY: VOLUMIO VOLUME 69 Feb 21 21:25:32 media-ground volumio[934]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 21 21:25:32 media-ground volumio[934]: info: Setting Spotify Volume from Volumio: 69 Feb 21 21:25:33 media-ground volumio[934]: info: VolumeController::SetAlsaVolume51 Feb 21 21:25:33 media-ground volumio[934]: info: CoreStateMachine::pushState Feb 21 21:25:33 media-ground volumio[934]: info: CorePlayQueue::getTrack 408 Feb 21 21:25:33 media-ground volumio[934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:25:33 media-ground volumio[934]: info: CoreCommandRouter::volumioPushState Feb 21 21:25:33 media-ground volumio[934]: SPOTIFY: RECEIVED VOLUMIO VOLUME 51 Feb 21 21:25:33 media-ground volumio[934]: SPOTIFY: SPOTIFY VOLUME 69 Feb 21 21:25:33 media-ground volumio[934]: SPOTIFY: VOLUMIO VOLUME 51 Feb 21 21:25:33 media-ground volumio[934]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 21 21:25:33 media-ground volumio[934]: info: Setting Spotify Volume from Volumio: 51 Feb 21 21:25:34 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:34 media-ground volumio[934]: SPOTIFY: SETTING SPOTIFY VOLUME 51 Feb 21 21:25:34 media-ground volumio[934]: info: Sending Spotify command with payload to local API: /player/volume Feb 21 21:25:35 media-ground volumio[934]: info: VolumeController::SetAlsaVolume48 Feb 21 21:25:35 media-ground volumio[934]: info: CoreStateMachine::pushState Feb 21 21:25:35 media-ground volumio[934]: info: CorePlayQueue::getTrack 408 Feb 21 21:25:35 media-ground volumio[934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:25:35 media-ground volumio[934]: info: CoreCommandRouter::volumioPushState Feb 21 21:25:35 media-ground volumio[934]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Feb 21 21:25:35 media-ground volumio[934]: SPOTIFY: SPOTIFY VOLUME 51 Feb 21 21:25:35 media-ground volumio[934]: SPOTIFY: VOLUMIO VOLUME 48 Feb 21 21:25:35 media-ground volumio[934]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 21 21:25:35 media-ground volumio[934]: info: Setting Spotify Volume from Volumio: 48 Feb 21 21:25:36 media-ground volumio[934]: info: VolumeController::SetAlsaVolume55 Feb 21 21:25:36 media-ground volumio[934]: info: CoreStateMachine::pushState Feb 21 21:25:36 media-ground volumio[934]: info: CorePlayQueue::getTrack 408 Feb 21 21:25:36 media-ground volumio[934]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:25:36 media-ground volumio[934]: info: CoreCommandRouter::volumioPushState Feb 21 21:25:36 media-ground volumio[934]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Feb 21 21:25:36 media-ground volumio[934]: SPOTIFY: SPOTIFY VOLUME 48 Feb 21 21:25:36 media-ground volumio[934]: SPOTIFY: VOLUMIO VOLUME 55 Feb 21 21:25:36 media-ground volumio[934]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 21 21:25:36 media-ground volumio[934]: info: Setting Spotify Volume from Volumio: 55 Feb 21 21:25:37 media-ground volumio[934]: SPOTIFY: SETTING SPOTIFY VOLUME 55 Feb 21 21:25:37 media-ground volumio[934]: info: Sending Spotify command with payload to local API: /player/volume Feb 21 21:25:37 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:40 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:46 media-ground go-librespot[24361]: time="2025-02-21T21:25:46+13:00" level=error msg="did not receive last pong from dealer, 36045s passed" Feb 21 21:25:49 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:52 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:25:55 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:26:03 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:26:06 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:26:14 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:26:16 media-ground go-librespot[24361]: time="2025-02-21T21:26:16+13:00" level=error msg="did not receive last pong from dealer, 36075s passed" Feb 21 21:26:17 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:26:20 media-ground volumio[934]: info: Discovery: Remote connection error: 10.10.50.14 Feb 21 21:26:27 media-ground volumio[934]: info: CorePlayQueue::getTrack 408 Feb 21 21:26:27 media-ground volumio[934]: info: CorePlayQueue::getTrack 376 Feb 21 21:26:27 media-ground volumio[934]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 21:26:27 media-ground volumio[934]: TypeError: Cannot read property 'service' of undefined Feb 21 21:26:27 media-ground volumio[934]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:454:107) Feb 21 21:26:27 media-ground volumio[934]: at listOnTimeout (internal/timers.js:554:17) Feb 21 21:26:27 media-ground volumio[934]: at processTimers (internal/timers.js:497:7) Feb 21 21:26:27 media-ground volumio[934]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 21:26:27 media-ground sudo[18759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-21 21:25 Feb 21 21:26:27 media-ground sudo[18759]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"