-- Logs begin at Sun 2025-06-22 20:19:06 CEST, end at Fri 2025-06-27 14:51:55 CEST. -- Jun 27 14:50:06 volumio go-librespot[1168]: time="2025-06-27T14:50:06+02:00" level=trace msg="sent dealer ping" Jun 27 14:50:07 volumio go-librespot[1168]: time="2025-06-27T14:50:07+02:00" level=trace msg="received dealer pong" Jun 27 14:50:34 volumio vtcs[1368]: [close:85] Entering Jun 27 14:50:34 volumio volumio[880]: info: Pushing metadata Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 27 14:50:34 volumio volumio[880]: info: CoreStateMachine::pushState Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 27 14:50:34 volumio vtcs[1368]: [feedThread:276] Exiting Jun 27 14:50:34 volumio vtcs[1368]: [close:100] Exiting Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: SPOTIFY VOLUME 76 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: VOLUMIO VOLUME 75 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 14:50:34 volumio vtcs[1368]: [close:85] Entering Jun 27 14:50:34 volumio vtcs[1368]: [close:100] Exiting Jun 27 14:50:34 volumio vtcs[1368]: [open:50] Opening with sample rate: 44100, type: 0 Jun 27 14:50:34 volumio vtcs[1368]: [start:106] Entering Jun 27 14:50:34 volumio vtcs[1368]: [start:113] Reopening PCM device... Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 27 14:50:34 volumio volumio[880]: info: CoreStateMachine::pushState Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: SPOTIFY VOLUME 76 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: VOLUMIO VOLUME 75 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 14:50:34 volumio vtcs[1368]: [start:115] PCM opened successfully. Jun 27 14:50:34 volumio vtcs[1368]: [start:120] Exiting Jun 27 14:50:34 volumio vtcs[1368]: [feedThread:223] Entering Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 27 14:50:34 volumio volumio[880]: info: CoreStateMachine::pushState Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 14:50:34 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: SPOTIFY VOLUME 76 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: VOLUMIO VOLUME 75 Jun 27 14:50:34 volumio volumio[880]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 14:50:36 volumio go-librespot[1168]: time="2025-06-27T14:50:36+02:00" level=trace msg="sent dealer ping" Jun 27 14:50:37 volumio go-librespot[1168]: time="2025-06-27T14:50:37+02:00" level=trace msg="received dealer pong" Jun 27 14:50:56 volumio go-librespot[1168]: time="2025-06-27T14:50:56+02:00" level=trace msg="received accesspoint ping" Jun 27 14:50:56 volumio go-librespot[1168]: time="2025-06-27T14:50:56+02:00" level=trace msg="received accesspoint pong ack" Jun 27 14:51:06 volumio go-librespot[1168]: time="2025-06-27T14:51:06+02:00" level=trace msg="sent dealer ping" Jun 27 14:51:07 volumio go-librespot[1168]: time="2025-06-27T14:51:07+02:00" level=trace msg="received dealer pong" Jun 27 14:51:34 volumio vtcs[1368]: [2025-06-27 14:51:34.487] [tisoc] [error] [QueueWindowLoader.cpp:272] status code:401 Jun 27 14:51:34 volumio vtcs[1368]: [2025-06-27 14:51:34.517] [tisoc] [error] [HttpClient.cpp:469] curl_easy_perform failed. reason:URL using bad/illegal format or missing URL further detail: Jun 27 14:51:34 volumio vtcs[1368]: [2025-06-27 14:51:34.517] [tisoc] [error] [QueueWindowLoader.cpp:272] status code:99 Jun 27 14:51:35 volumio vtcs[1368]: [close:85] Entering Jun 27 14:51:35 volumio vtcs[1368]: [feedThread:276] Exiting Jun 27 14:51:35 volumio vtcs[1368]: [close:100] Exiting Jun 27 14:51:35 volumio volumio[880]: info: CoreCommandRouter::servicePushState Jun 27 14:51:35 volumio volumio[880]: info: CoreStateMachine::pushState Jun 27 14:51:35 volumio volumio[880]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 14:51:35 volumio volumio[880]: info: CoreCommandRouter::volumioPushState Jun 27 14:51:35 volumio volumio[880]: SPOTIFY: RECEIVED VOLUMIO VOLUME 75 Jun 27 14:51:35 volumio volumio[880]: SPOTIFY: SPOTIFY VOLUME 76 Jun 27 14:51:35 volumio volumio[880]: SPOTIFY: VOLUMIO VOLUME 75 Jun 27 14:51:35 volumio volumio[880]: SPOTIFY: DELTA VOLUME ENOUGH: false Jun 27 14:51:36 volumio go-librespot[1168]: time="2025-06-27T14:51:36+02:00" level=trace msg="sent dealer ping" Jun 27 14:51:37 volumio go-librespot[1168]: time="2025-06-27T14:51:37+02:00" level=trace msg="received dealer pong" Jun 27 14:51:40 volumio vtcs[1368]: [2025-06-27 14:51:40.184] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Jun 27 14:51:46 volumio volumio[880]: info: CoreCommandRouter::volumioNext Jun 27 14:51:46 volumio volumio[880]: info: CoreStateMachine::next Jun 27 14:51:46 volumio volumio[880]: info: Received next Jun 27 14:51:52 volumio volumio[880]: info: CoreCommandRouter::volumioGetQueue Jun 27 14:51:52 volumio volumio[880]: info: CoreStateMachine::getQueue Jun 27 14:51:52 volumio volumio[880]: info: CorePlayQueue::getQueue Jun 27 14:51:54 volumio volumio[880]: info: CoreCommandRouter::volumioRemoveQueueItem Jun 27 14:51:54 volumio volumio[880]: info: CoreStateMachine::removeQueueItem Jun 27 14:51:54 volumio volumio[880]: info: CoreStateMachine::stop Jun 27 14:51:54 volumio volumio[880]: info: CoreStateMachine::serviceStop Jun 27 14:51:54 volumio volumio[880]: info: CoreCommandRouter::serviceStop Jun 27 14:51:54 volumio vtcs[1368]: [close:85] Entering Jun 27 14:51:54 volumio volumio[880]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 14:51:54 volumio vtcs[1368]: [close:100] Exiting Jun 27 14:51:54 volumio vtcs[1368]: [2025-06-27 14:51:54.754] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:0 Jun 27 14:51:54 volumio volumio[880]: TypeError: Cannot read property 'then' of undefined Jun 27 14:51:54 volumio volumio[880]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1368:7) Jun 27 14:51:54 volumio volumio[880]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Jun 27 14:51:54 volumio volumio[880]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Jun 27 14:51:54 volumio volumio[880]: at Socket.emit (events.js:315:20) Jun 27 14:51:54 volumio volumio[880]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 27 14:51:54 volumio volumio[880]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 27 14:51:54 volumio volumio[880]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 14:51:54 volumio vtcs[1368]: [2025-06-27 14:51:54] [info] asio async_shutdown error: asio.misc:2 (End of file) Jun 27 14:51:55 volumio sudo[10606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 14:50 Jun 27 14:51:55 volumio sudo[10606]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"