-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2026-10-13 09:43:38 CEST. -- Oct 13 09:42:05 salavrx volumio[1368]: info: CoreCommandRouter::volumioPlay Oct 13 09:42:05 salavrx volumio[1368]: info: CoreStateMachine::play index undefined Oct 13 09:42:05 salavrx volumio[1368]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 09:42:05 salavrx volumio[1368]: info: CorePlayQueue::getTrack 0 Oct 13 09:42:05 salavrx volumio[1368]: info: CoreStateMachine::startPlaybackTimer Oct 13 09:42:05 salavrx volumio[1368]: info: CorePlayQueue::getTrack 0 Oct 13 09:42:05 salavrx volumio[1368]: info: [1791877325482] ControllerTidal::resume Oct 13 09:42:05 salavrx volumio[1368]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 13 09:42:05 salavrx volumio[1368]: info: ControllerMpd::resume Oct 13 09:42:05 salavrx volumio[1368]: verbose: ControllerMpd::sendMpdCommand play Oct 13 09:42:05 salavrx volumio[1368]: info: sendMpdCommand play took 5 milliseconds Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 09:42:20 salavrx volumio[1368]: info: Discovery: Getting this device information Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::volumioGetState Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 09:42:20 salavrx volumio[1368]: verbose: New Socket.io Connection to 192.168.178.29:3000 from 192.168.178.85 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 09:42:20 salavrx volumio[1368]: verbose: New Socket.io Connection to 192.168.178.29 from 192.168.178.85 UA: Mozilla/5.0 (Linux; Android 16; SM-A556B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/153.0.8010.36 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 13 09:42:20 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::volumioGetState Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Oct 13 09:42:21 salavrx volumio[1368]: info: Received Get System Info Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 09:42:21 salavrx volumio[1368]: info: Discovery: Getting this device information Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::volumioGetState Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::volumioGetState Oct 13 09:42:21 salavrx volumio[1368]: info: Listing playlists Oct 13 09:42:21 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 09:42:23 salavrx go-librespot[1680]: time="2026-10-13T09:42:23+02:00" level=trace msg="sent dealer ping" Oct 13 09:42:23 salavrx go-librespot[1680]: time="2026-10-13T09:42:23+02:00" level=trace msg="received dealer pong" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=debug msg="handling resume player command from 78034bf1cab168d2452bf885b787157961c4f73f" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=trace msg="seek to 153015ms (diff: 124ms, samples: 6747961, bytes: 6215708)" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 23219 us, period size = 1024 frames, buffer time = 92879 us, buffer size = 4096 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=debug msg="resume track at 152891ms" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=trace msg="scheduling prefetch in 106s" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=debug msg="sending successful reply for dealer request" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 13 09:42:24 salavrx go-librespot[1680]: time="2026-10-13T09:42:24+02:00" level=trace msg="emitting websocket event: playing" Oct 13 09:42:24 salavrx volumio[1368]: info: CoreCommandRouter::servicePushState Oct 13 09:42:24 salavrx volumio[1368]: info: CorePlayQueue::getTrack 0 Oct 13 09:42:24 salavrx volumio[1368]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Oct 13 09:42:24 salavrx volumio[1368]: info: CoreCommandRouter::servicePushState Oct 13 09:42:24 salavrx volumio[1368]: info: CorePlayQueue::getTrack 0 Oct 13 09:42:24 salavrx volumio[1368]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received spop Oct 13 09:42:26 salavrx go-librespot[1680]: time="2026-10-13T09:42:26+02:00" level=debug msg="fetched chunk 15/22, size: 524288" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:42:34 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 09:42:34 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 09:42:34 salavrx volumio[1368]: info: Discovery: Getting this device information Oct 13 09:42:34 salavrx volumio[1368]: info: CoreCommandRouter::volumioGetState Oct 13 09:42:34 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 09:42:34 salavrx volumio[1368]: verbose: New Socket.io Connection to 192.168.178.29:3000 from 192.168.178.85 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 13 09:42:34 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 09:42:34 salavrx volumio[1368]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 09:42:39 salavrx go-librespot[1680]: time="2026-10-13T09:42:39+02:00" level=debug msg="fetched chunk 16/22, size: 524288" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:42:52 salavrx go-librespot[1680]: time="2026-10-13T09:42:52+02:00" level=debug msg="fetched chunk 17/22, size: 524288" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:42:53 salavrx go-librespot[1680]: time="2026-10-13T09:42:53+02:00" level=trace msg="sent dealer ping" Oct 13 09:42:53 salavrx go-librespot[1680]: time="2026-10-13T09:42:53+02:00" level=trace msg="received dealer pong" Oct 13 09:43:05 salavrx go-librespot[1680]: time="2026-10-13T09:43:05+02:00" level=debug msg="fetched chunk 18/22, size: 524288" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:43:18 salavrx go-librespot[1680]: time="2026-10-13T09:43:18+02:00" level=debug msg="fetched chunk 19/22, size: 524288" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:43:23 salavrx go-librespot[1680]: time="2026-10-13T09:43:23+02:00" level=trace msg="received accesspoint ping" Oct 13 09:43:23 salavrx go-librespot[1680]: time="2026-10-13T09:43:23+02:00" level=trace msg="received accesspoint pong ack" Oct 13 09:43:23 salavrx go-librespot[1680]: time="2026-10-13T09:43:23+02:00" level=trace msg="sent dealer ping" Oct 13 09:43:23 salavrx go-librespot[1680]: time="2026-10-13T09:43:23+02:00" level=trace msg="received dealer pong" Oct 13 09:43:31 salavrx go-librespot[1680]: time="2026-10-13T09:43:31+02:00" level=debug msg="fetched chunk 20/22, size: 524288" uri="spotify:track:7w5AOd6HrDIHewHfpABEss" Oct 13 09:43:37 salavrx volumio[1368]: info: CorePlayQueue::getTrack 0 Oct 13 09:43:37 salavrx volumio[1368]: info: CorePlayQueue::getTrack 1 Oct 13 09:43:37 salavrx volumio[1368]: info: Prefetching next song Oct 13 09:43:37 salavrx volumio[1368]: info: [1791877417746] ControllerTidal::prefetch Oct 13 09:43:37 salavrx volumio[1368]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 09:43:37 salavrx volumio[1368]: TypeError: Cannot read property 'highestSoundQuality' of undefined Oct 13 09:43:37 salavrx volumio[1368]: at ControllerTidal.getProperSoundQuality (/volumio/app/plugins/music_service/tidal/tidal_real:1:19583) Oct 13 09:43:37 salavrx volumio[1368]: at ControllerTidal.prefetch (/volumio/app/plugins/music_service/tidal/tidal_real:1:20287) Oct 13 09:43:37 salavrx volumio[1368]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:464:18) Oct 13 09:43:37 salavrx volumio[1368]: at listOnTimeout (internal/timers.js:557:17) Oct 13 09:43:37 salavrx volumio[1368]: at processTimers (internal/timers.js:500:7) Oct 13 09:43:37 salavrx volumio[1368]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 09:43:38 salavrx sudo[8289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-13 09:42 Oct 13 09:43:38 salavrx sudo[8289]: 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 11:08:05 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="2435172a9e07e22ce77410ca4817b0fa"