-- Logs begin at Wed 2025-10-22 10:13:20 CEST, end at Thu 2025-10-23 18:18:41 CEST. -- Oct 23 18:17:05 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:05 konyha volumio[838]: info: Listing playlists Oct 23 18:17:05 konyha volumio[838]: info: Listing playlists Oct 23 18:17:06 konyha go-librespot[1509]: time="2025-10-23T18:17:06+02:00" level=trace msg="received accesspoint ping" Oct 23 18:17:06 konyha go-librespot[1509]: time="2025-10-23T18:17:06+02:00" level=trace msg="received accesspoint pong ack" Oct 23 18:17:06 konyha go-librespot[1509]: time="2025-10-23T18:17:06+02:00" level=trace msg="sent dealer ping" Oct 23 18:17:06 konyha go-librespot[1509]: time="2025-10-23T18:17:06+02:00" level=trace msg="received dealer pong" Oct 23 18:17:15 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:21 konyha shairport-sync[1140]: no RTSP header received Oct 23 18:17:25 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:25 konyha volumio[838]: info: Listing playlists Oct 23 18:17:25 konyha volumio[838]: info: Listing playlists Oct 23 18:17:35 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:36 konyha go-librespot[1509]: time="2025-10-23T18:17:36+02:00" level=trace msg="sent dealer ping" Oct 23 18:17:36 konyha go-librespot[1509]: time="2025-10-23T18:17:36+02:00" level=trace msg="received dealer pong" Oct 23 18:17:42 konyha volumio[838]: error: error Oct 23 18:17:45 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:45 konyha volumio[838]: info: Listing playlists Oct 23 18:17:45 konyha volumio[838]: info: Listing playlists Oct 23 18:17:48 konyha sudo[31718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 18:17:48 konyha sudo[31718]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 18:17:48 konyha sudo[31718]: pam_unix(sudo:session): session closed for user root Oct 23 18:17:48 konyha sudo[31721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 18:17:48 konyha sudo[31721]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 18:17:48 konyha sudo[31721]: pam_unix(sudo:session): session closed for user root Oct 23 18:17:48 konyha volumio[838]: verbose: New Socket.io Connection to 192.168.1.97 from 192.168.1.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 23 18:17:48 konyha volumio[838]: info: Received Get System Info Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 18:17:48 konyha volumio[838]: info: Discovery: Getting this device information Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:48 konyha volumio[838]: info: Listing playlists Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 23 18:17:48 konyha sudo[31724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 23 18:17:48 konyha sudo[31724]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 18:17:48 konyha sudo[31724]: pam_unix(sudo:session): session closed for user root Oct 23 18:17:48 konyha sudo[31727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 23 18:17:48 konyha sudo[31727]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 23 18:17:48 konyha sudo[31727]: pam_unix(sudo:session): session closed for user root Oct 23 18:17:48 konyha volumio[838]: verbose: New Socket.io Connection to 192.168.1.97 from 192.168.1.50 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 23 18:17:48 konyha volumio[838]: info: Received Get System Info Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 18:17:48 konyha volumio[838]: info: Discovery: Getting this device information Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:48 konyha volumio[838]: info: Listing playlists Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 18:17:48 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 23 18:17:49 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:50 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 23 18:17:50 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 18:17:50 konyha volumio[838]: info: Received Get System Info Oct 23 18:17:50 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 18:17:50 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 18:17:50 konyha volumio[838]: info: Discovery: Getting this device information Oct 23 18:17:50 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:50 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 18:17:51 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 23 18:17:51 konyha volumio[838]: info: Received Get System Info Oct 23 18:17:51 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 18:17:51 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 18:17:51 konyha volumio[838]: info: Discovery: Getting this device information Oct 23 18:17:51 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:51 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 18:17:53 konyha volumio[838]: info: CoreCommandRouter::volumioVolatilePlay Oct 23 18:17:53 konyha volumio[838]: info: CoreStateMachine::volatilePlay Oct 23 18:17:53 konyha volumio[838]: info: WARNING: No play method for volatile plugin undefined Oct 23 18:17:55 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:17:58 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 23 18:18:01 konyha volumio[838]: info: CoreCommandRouter::volumioVolatilePlay Oct 23 18:18:01 konyha volumio[838]: info: CoreStateMachine::volatilePlay Oct 23 18:18:01 konyha volumio[838]: info: WARNING: No play method for volatile plugin undefined Oct 23 18:18:05 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:05 konyha volumio[838]: info: Listing playlists Oct 23 18:18:05 konyha volumio[838]: info: Listing playlists Oct 23 18:18:05 konyha volumio[838]: info: VolumeController::SetAlsaVolume46 Oct 23 18:18:05 konyha volumio[838]: info: CoreStateMachine::pushState Oct 23 18:18:05 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:18:05 konyha volumio[838]: info: CoreCommandRouter::volumioPushState Oct 23 18:18:05 konyha volumio[838]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Oct 23 18:18:05 konyha volumio[838]: SPOTIFY: SPOTIFY VOLUME 10 Oct 23 18:18:05 konyha volumio[838]: SPOTIFY: VOLUMIO VOLUME 46 Oct 23 18:18:05 konyha volumio[838]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 23 18:18:05 konyha volumio[838]: info: Setting Spotify Volume from Volumio: 46 Oct 23 18:18:06 konyha go-librespot[1509]: time="2025-10-23T18:18:06+02:00" level=trace msg="sent dealer ping" Oct 23 18:18:06 konyha go-librespot[1509]: time="2025-10-23T18:18:06+02:00" level=trace msg="received dealer pong" Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 23 18:18:06 konyha volumio[838]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 23 18:18:06 konyha volumio[838]: info: Received Get System Version Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 23 18:18:06 konyha volumio[838]: info: Received Get System Info Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 23 18:18:06 konyha volumio[838]: info: Discovery: Getting this device information Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:06 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 23 18:18:07 konyha volumio[838]: info: VolumeController::SetAlsaVolume38 Oct 23 18:18:07 konyha volumio[838]: info: CoreStateMachine::pushState Oct 23 18:18:07 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 23 18:18:07 konyha volumio[838]: info: CoreCommandRouter::volumioPushState Oct 23 18:18:07 konyha volumio[838]: SPOTIFY: RECEIVED VOLUMIO VOLUME 38 Oct 23 18:18:07 konyha volumio[838]: SPOTIFY: SPOTIFY VOLUME 46 Oct 23 18:18:07 konyha volumio[838]: SPOTIFY: VOLUMIO VOLUME 38 Oct 23 18:18:07 konyha volumio[838]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 23 18:18:07 konyha volumio[838]: info: Setting Spotify Volume from Volumio: 38 Oct 23 18:18:08 konyha volumio[838]: SPOTIFY: SETTING SPOTIFY VOLUME 38 Oct 23 18:18:08 konyha volumio[838]: info: Sending Spotify command with payload to local API: /player/volume Oct 23 18:18:08 konyha go-librespot[1509]: time="2025-10-23T18:18:08+02:00" level=debug msg="update volume requested to 24903/65535" Oct 23 18:18:08 konyha go-librespot[1509]: time="2025-10-23T18:18:08+02:00" level=debug msg="renewing login5 access token" Oct 23 18:18:08 konyha go-librespot[1509]: time="2025-10-23T18:18:08+02:00" level=info msg="authenticated Login5" username="ri***zi" Oct 23 18:18:09 konyha go-librespot[1509]: time="2025-10-23T18:18:09+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 23 18:18:09 konyha go-librespot[1509]: time="2025-10-23T18:18:09+02:00" level=trace msg="emitting websocket event: volume" Oct 23 18:18:09 konyha volumio[838]: SPOTIFY: received: {"type":"volume","data":{"value":38,"max":100}} Oct 23 18:18:09 konyha volumio[838]: SPOTIFY: RECEIVED SPOTIFY VOLUME 38 Oct 23 18:18:10 konyha volumio[838]: info: CoreCommandRouter::volumioGetQueue Oct 23 18:18:10 konyha volumio[838]: info: CoreStateMachine::getQueue Oct 23 18:18:10 konyha volumio[838]: info: CorePlayQueue::getQueue Oct 23 18:18:15 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:21 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:25 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:25 konyha volumio[838]: info: Listing playlists Oct 23 18:18:25 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:29 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 23 18:18:29 konyha volumio[838]: info: CURURI: albums:// Oct 23 18:18:29 konyha volumio[838]: info: listAlbums - loading Albums from cache Oct 23 18:18:29 konyha volumio[838]: info: Preload queue cleared Oct 23 18:18:32 konyha volumio[838]: error: error Oct 23 18:18:33 konyha nmbd[745]: [2025/10/23 18:18:33.502143, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 23 18:18:33 konyha nmbd[745]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.97 for name WORKGROUP<1d>. Oct 23 18:18:33 konyha nmbd[745]: This response was from IP 192.168.1.5, reporting an IP address of 192.168.1.5. Oct 23 18:18:34 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 18:18:34 konyha volumio[838]: info: Preload queue cleared Oct 23 18:18:35 konyha volumio[838]: info: CoreCommandRouter::volumioGetState Oct 23 18:18:36 konyha go-librespot[1509]: time="2025-10-23T18:18:36+02:00" level=trace msg="sent dealer ping" Oct 23 18:18:36 konyha go-librespot[1509]: time="2025-10-23T18:18:36+02:00" level=trace msg="received dealer pong" Oct 23 18:18:37 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 18:18:37 konyha volumio[838]: info: Preload queue cleared Oct 23 18:18:38 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 18:18:38 konyha volumio[838]: info: Preload queue cleared Oct 23 18:18:39 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 18:18:39 konyha volumio[838]: info: Preload queue cleared Oct 23 18:18:41 konyha volumio[838]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 23 18:18:41 konyha volumio[838]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 18:18:41 konyha volumio[838]: TypeError: Cannot read property 'length' of undefined Oct 23 18:18:41 konyha volumio[838]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 23 18:18:41 konyha volumio[838]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 23 18:18:41 konyha volumio[838]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 23 18:18:41 konyha volumio[838]: at Parser.emit (events.js:315:20) Oct 23 18:18:41 konyha volumio[838]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 23 18:18:41 konyha volumio[838]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 23 18:18:41 konyha volumio[838]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 23 18:18:41 konyha volumio[838]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 23 18:18:41 konyha volumio[838]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 23 18:18:41 konyha volumio[838]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 23 18:18:41 konyha volumio[838]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 23 18:18:41 konyha volumio[838]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 23 18:18:41 konyha volumio[838]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 23 18:18:41 konyha volumio[838]: at IncomingMessage.emit (events.js:327:22) Oct 23 18:18:41 konyha volumio[838]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 23 18:18:41 konyha volumio[838]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 23 18:18:41 konyha volumio[838]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 23 18:18:41 konyha sudo[31871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-23 18:17 Oct 23 18:18:41 konyha sudo[31871]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"