-- Logs begin at Thu 2025-06-12 06:28:51 CEST, end at Thu 2025-06-12 06:43:03 CEST. -- Jun 12 06:42:10 volumio ntpd[726]: Soliciting pool server 193.123.38.172 Jun 12 06:42:11 volumio ntpd[726]: Soliciting pool server 195.123.221.21 Jun 12 06:42:15 volumio ntpd[726]: Soliciting pool server 178.239.19.62 Jun 12 06:42:18 volumio ntpd[726]: Soliciting pool server 174.138.107.7 Jun 12 06:42:23 volumio volumio[7451]: info: CoreCommandRouter::volumioPause Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::pause Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::stPlaybackTimer Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::servicePause Jun 12 06:42:23 volumio volumio[7451]: info: CorePlayQueue::getTrack 2 Jun 12 06:42:23 volumio volumio[7451]: info: CoreCommandRouter::servicePause Jun 12 06:42:23 volumio volumio[7451]: info: [1749703343034] ControllerUPNPBrowser::pause Jun 12 06:42:23 volumio volumio[7451]: verbose: ControllerMpd::sendMpdCommand pause Jun 12 06:42:23 volumio volumio[7451]: info: sendMpdCommand pause took 2 milliseconds Jun 12 06:42:23 volumio volumio[7451]: info: Jun 12 06:42:23 volumio volumio[7451]: ---------------------------- MPD announces state update: player Jun 12 06:42:23 volumio volumio[7451]: info: ControllerMpd::getState Jun 12 06:42:23 volumio volumio[7451]: verbose: ControllerMpd::sendMpdCommand status Jun 12 06:42:23 volumio volumio[7451]: info: sendMpdCommand status took 0 milliseconds Jun 12 06:42:23 volumio volumio[7451]: verbose: ControllerMpd::parseState Jun 12 06:42:23 volumio volumio[7451]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 06:42:23 volumio volumio[7451]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 12 06:42:23 volumio volumio[7451]: verbose: ControllerMpd::parseTrackInfo Jun 12 06:42:23 volumio volumio[7451]: info: ControllerMpd::pushState Jun 12 06:42:23 volumio volumio[7451]: info: CoreCommandRouter::servicePushState Jun 12 06:42:23 volumio volumio[7451]: info: CorePlayQueue::getTrack 2 Jun 12 06:42:23 volumio volumio[7451]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":99206,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0_MTRfN2QwZWQ3NjUtOTAzMw","artist":null,"album":null,"uri":"http://192.168.178.52:10243/WMPNSSv4/3278521999/0_MTRfN2QwZWQ3NjUtOTAzMw","trackType":""} Jun 12 06:42:23 volumio volumio[7451]: verbose: CURRENT POSITION 2 Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::syncState stateService pause Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::syncState currentStatus pause Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::pushState Jun 12 06:42:23 volumio volumio[7451]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 06:42:23 volumio volumio[7451]: info: CoreCommandRouter::volumioPushState Jun 12 06:42:23 volumio volumio[7451]: info: MRS: Pushing multiroomSync output update for this device Jun 12 06:42:23 volumio volumio[7451]: info: MRS: Pushing multiroomSync output Jun 12 06:42:23 volumio volumio[7451]: info: CoreCommandRouter::volumioGetState Jun 12 06:42:23 volumio volumio[7451]: info: CoreStateMachine::stPlaybackTimer Jun 12 06:42:23 volumio volumio[7451]: info: ------------------------------ 34ms Jun 12 06:42:26 volumio volumio[7451]: info: MRS: STARTING volumioStreaming Jun 12 06:42:26 volumio sudo[8888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/stream.m3u8 /tmp/hls/stream0.ts /tmp/hls/stream1.ts /tmp/hls/stream10.ts /tmp/hls/stream11.ts /tmp/hls/stream12.ts /tmp/hls/stream13.ts /tmp/hls/stream14.ts /tmp/hls/stream15.ts /tmp/hls/stream16.ts /tmp/hls/stream17.ts /tmp/hls/stream18.ts /tmp/hls/stream19.ts /tmp/hls/stream2.ts /tmp/hls/stream20.ts /tmp/hls/stream21.ts /tmp/hls/stream22.ts /tmp/hls/stream23.ts /tmp/hls/stream24.ts /tmp/hls/stream25.ts /tmp/hls/stream26.ts /tmp/hls/stream27.ts /tmp/hls/stream28.ts /tmp/hls/stream29.ts /tmp/hls/stream3.ts /tmp/hls/stream30.ts /tmp/hls/stream31.ts /tmp/hls/stream32.ts /tmp/hls/stream33.ts /tmp/hls/stream34.ts /tmp/hls/stream35.ts /tmp/hls/stream36.ts /tmp/hls/stream37.ts /tmp/hls/stream38.ts /tmp/hls/stream39.ts /tmp/hls/stream4.ts /tmp/hls/stream40.ts /tmp/hls/stream41.ts /tmp/hls/stream42.ts /tmp/hls/stream43.ts /tmp/hls/stream44.ts /tmp/hls/stream45.ts Jun 12 06:42:26 volumio sudo[8888]: volumio : (command continued) /tmp/hls/stream46.ts /tmp/hls/stream47.ts /tmp/hls/stream48.ts /tmp/hls/stream49.ts /tmp/hls/stream5.ts /tmp/hls/stream50.ts /tmp/hls/stream51.ts /tmp/hls/stream52.ts /tmp/hls/stream53.ts /tmp/hls/stream54.ts /tmp/hls/stream55.ts /tmp/hls/stream56.ts /tmp/hls/stream57.ts /tmp/hls/stream58.ts /tmp/hls/stream59.ts /tmp/hls/stream6.ts /tmp/hls/stream60.ts /tmp/hls/stream61.ts /tmp/hls/stream62.ts /tmp/hls/stream63.ts /tmp/hls/stream64.ts /tmp/hls/stream65.ts /tmp/hls/stream66.ts /tmp/hls/stream67.ts /tmp/hls/stream68.ts /tmp/hls/stream69.ts /tmp/hls/stream7.ts /tmp/hls/stream70.ts /tmp/hls/stream71.ts /tmp/hls/stream72.ts /tmp/hls/stream73.ts /tmp/hls/stream74.ts /tmp/hls/stream75.ts /tmp/hls/stream76.ts /tmp/hls/stream77.ts /tmp/hls/stream78.ts /tmp/hls/stream79.ts /tmp/hls/stream8.ts /tmp/hls/stream80.ts /tmp/hls/stream81.ts /tmp/hls/stream9.ts Jun 12 06:42:26 volumio sudo[8888]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 06:42:26 volumio sudo[8888]: pam_unix(sudo:session): session closed for user root Jun 12 06:42:26 volumio sudo[8891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioStreaming Jun 12 06:42:26 volumio sudo[8891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 06:42:26 volumio systemd[1]: Stopping VolumioStreamingService... Jun 12 06:42:26 volumio systemd[1]: volumioStreaming.service: Main process exited, code=killed, status=15/TERM Jun 12 06:42:26 volumio systemd[1]: volumioStreaming.service: Succeeded. Jun 12 06:42:26 volumio systemd[1]: Stopped VolumioStreamingService. Jun 12 06:42:26 volumio volumio[7451]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 12 06:42:26 volumio volumio[7451]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 12 06:42:26 volumio systemd[1]: Started VolumioStreamingService. Jun 12 06:42:26 volumio sudo[8891]: pam_unix(sudo:session): session closed for user root Jun 12 06:42:26 volumio sudo[8895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 12 06:42:26 volumio sudo[8895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 06:42:26 volumio volumio[7451]: info: MRS: volumioStreaming STARTED Jun 12 06:42:26 volumio sudo[8895]: pam_unix(sudo:session): session closed for user root Jun 12 06:42:49 volumio volumio[7451]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 06:42:49 volumio volumio[7451]: info: Preload queue cleared Jun 12 06:42:55 volumio volumio[7451]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 06:42:55 volumio volumio[7451]: info: Preload queue cleared Jun 12 06:42:58 volumio volumio[7451]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 06:42:58 volumio volumio[7451]: info: Preload queue cleared Jun 12 06:43:03 volumio volumio[7451]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 12 06:43:03 volumio volumio[7451]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 06:43:03 volumio volumio[7451]: TypeError: Cannot read property 'length' of undefined Jun 12 06:43:03 volumio volumio[7451]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 12 06:43:03 volumio volumio[7451]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 12 06:43:03 volumio volumio[7451]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 12 06:43:03 volumio volumio[7451]: at Parser.emit (events.js:315:20) Jun 12 06:43:03 volumio volumio[7451]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 12 06:43:03 volumio volumio[7451]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 12 06:43:03 volumio volumio[7451]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 12 06:43:03 volumio volumio[7451]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 12 06:43:03 volumio volumio[7451]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 12 06:43:03 volumio volumio[7451]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 12 06:43:03 volumio volumio[7451]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 12 06:43:03 volumio volumio[7451]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 12 06:43:03 volumio volumio[7451]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 12 06:43:03 volumio volumio[7451]: at IncomingMessage.emit (events.js:327:22) Jun 12 06:43:03 volumio volumio[7451]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 12 06:43:03 volumio volumio[7451]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 12 06:43:03 volumio volumio[7451]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 06:43:03 volumio sudo[8962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 06:42 Jun 12 06:43:03 volumio sudo[8962]: 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"