Jan 10 03:09:10 volumio go-librespot[12967]: time="2026-01-10T03:09:10Z" level=debug msg="handling pause player command from 5e06495da75d8c46c3146d82daa4a520237ee50d" Jan 10 03:09:10 volumio go-librespot[12967]: time="2026-01-10T03:09:10Z" level=debug msg="pause track at 65221ms" Jan 10 03:09:10 volumio go-librespot[12967]: time="2026-01-10T03:09:10Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 03:09:10 volumio go-librespot[12967]: time="2026-01-10T03:09:10Z" level=debug msg="sending successful reply for dealer request" Jan 10 03:09:10 volumio go-librespot[12967]: time="2026-01-10T03:09:10Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 10 03:09:10 volumio go-librespot[12967]: time="2026-01-10T03:09:10Z" level=trace msg="emitting websocket event: paused" Jan 10 03:09:10 volumio volumio[1383]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:track:0nKu6ysQjldXxABnxMu7kL","uri":"spotify:track:0nKu6ysQjldXxABnxMu7kL","play_origin":"go-librespot"}} Jan 10 03:09:10 volumio volumio[1383]: SPOTIFY: PUSH STATE SPOTIFY Jan 10 03:09:10 volumio volumio[1383]: SPOTIFY: {"status":"pause","service":"spop","title":"Bailando Bachata","artist":"Chayanne","album":"Bailando Bachata","albumart":"https://i.scdn.co/image/ab67616d00001e02d1a9a24d35da68a7188cfd91","uri":"spotify:track:0nKu6ysQjldXxABnxMu7kL","trackType":"spotify","seek":64000,"duration":170,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 03:09:10 volumio volumio[1383]: info: CoreCommandRouter::servicePushState Jan 10 03:09:10 volumio volumio[1383]: info: CorePlayQueue::getTrack 33 Jan 10 03:09:10 volumio volumio[1383]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Bailando Bachata","artist":"Chayanne","album":"Bailando Bachata","albumart":"https://i.scdn.co/image/ab67616d00001e02d1a9a24d35da68a7188cfd91","uri":"spotify:track:0nKu6ysQjldXxABnxMu7kL","trackType":"spotify","seek":64000,"duration":170,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jan 10 03:09:10 volumio volumio[1383]: verbose: CURRENT POSITION 33 Jan 10 03:09:10 volumio volumio[1383]: info: CoreStateMachine::syncState stateService pause Jan 10 03:09:10 volumio volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Jan 10 03:09:10 volumio volumio[1383]: info: CoreStateMachine::pushState Jan 10 03:09:10 volumio volumio[1383]: info: CorePlayQueue::getTrack 33 Jan 10 03:09:10 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 03:09:10 volumio volumio[1383]: info: CoreCommandRouter::volumioPushState Jan 10 03:09:10 volumio volumio[1383]: info: CoreStateMachine::stPlaybackTimer Jan 10 03:09:10 volumio volumio[1383]: SPOTIFY: RECEIVED VOLUMIO VOLUME 24 Jan 10 03:09:25 volumio go-librespot[12967]: time="2026-01-10T03:09:25Z" level=trace msg="received accesspoint ping" Jan 10 03:09:25 volumio go-librespot[12967]: time="2026-01-10T03:09:25Z" level=trace msg="received accesspoint pong ack" Jan 10 03:09:25 volumio go-librespot[12967]: time="2026-01-10T03:09:25Z" level=trace msg="sent dealer ping" Jan 10 03:09:25 volumio go-librespot[12967]: time="2026-01-10T03:09:25Z" level=trace msg="received dealer pong" Jan 10 03:09:47 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:47 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:47 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:09:48 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:48 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:48 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:09:49 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:49 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:50 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:09:51 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:51 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:51 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:09:51 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:51 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:52 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:09:54 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:54 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:54 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:09:55 volumio go-librespot[12967]: time="2026-01-10T03:09:55Z" level=trace msg="sent dealer ping" Jan 10 03:09:55 volumio go-librespot[12967]: time="2026-01-10T03:09:55Z" level=trace msg="received dealer pong" Jan 10 03:09:57 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:09:57 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:09:58 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:10:00 volumio volumio[1383]: Searching plugin music_service/spop Jan 10 03:10:00 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , search Jan 10 03:10:01 volumio volumio[1383]: info: All search sources collected, pushing search results Jan 10 03:10:03 volumio volumio[1383]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 10 03:10:03 volumio volumio[1383]: info: In handleBrowseUri, curUri=spotify:artist:4D8DfTYN0q1HYyz64Lmptw Jan 10 03:10:04 volumio volumio[1383]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 03:10:04 volumio volumio[1383]: TypeError: Cannot read properties of undefined (reading 'url') Jan 10 03:10:04 volumio volumio[1383]: at /data/plugins/music_service/spop/index.js:2446:60 Jan 10 03:10:04 volumio volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 10 03:10:04 volumio volumio[1383]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 03:10:04 volumio sudo[19568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 03:09' Jan 10 03:10:04 volumio sudo[19568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"