-- Logs begin at Mon 2024-12-16 09:35:29 CET, end at Wed 2025-02-26 14:46:36 CET. -- Feb 26 14:45:21 argon go-librespot[29479]: time="2025-02-26T14:45:21+01:00" level=trace msg="sent dealer ping" Feb 26 14:45:22 argon go-librespot[29479]: time="2025-02-26T14:45:22+01:00" level=trace msg="received dealer pong" Feb 26 14:45:51 argon go-librespot[29479]: time="2025-02-26T14:45:51+01:00" level=trace msg="received accesspoint ping" Feb 26 14:45:51 argon go-librespot[29479]: time="2025-02-26T14:45:51+01:00" level=trace msg="received accesspoint pong ack" Feb 26 14:45:51 argon go-librespot[29479]: time="2025-02-26T14:45:51+01:00" level=trace msg="sent dealer ping" Feb 26 14:45:51 argon go-librespot[29479]: time="2025-02-26T14:45:51+01:00" level=trace msg="received dealer pong" Feb 26 14:46:15 argon volumio[938]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 26 14:46:15 argon volumio[938]: info: Preload queue cleared Feb 26 14:46:19 argon volumio[938]: info: Tunnel connection is inactive, restarting it Feb 26 14:46:19 argon volumio[938]: info: Starting Tunnel 1 Feb 26 14:46:19 argon volumio[938]: info: Starting Tunnel Connection Checker Feb 26 14:46:19 argon sudo[29975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 26 14:46:19 argon sudo[29975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 14:46:19 argon autossh[29364]: received signal to exit (15) Feb 26 14:46:19 argon systemd[1]: Stopping MyVolumio SSH Tunnel... Feb 26 14:46:19 argon systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Feb 26 14:46:19 argon systemd[1]: sshtunnel.service: Succeeded. Feb 26 14:46:19 argon volumio[938]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 26 14:46:19 argon systemd[1]: Stopped MyVolumio SSH Tunnel. Feb 26 14:46:19 argon volumio[938]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 26 14:46:19 argon systemd[1]: Started MyVolumio SSH Tunnel. Feb 26 14:46:19 argon sudo[29975]: pam_unix(sudo:session): session closed for user root Feb 26 14:46:19 argon volumio[938]: info: Remote SSH Started Feb 26 14:46:19 argon autossh[29978]: port set to 0, monitoring disabled Feb 26 14:46:19 argon autossh[29978]: starting ssh (count 1) Feb 26 14:46:19 argon autossh[29978]: ssh child pid is 29981 Feb 26 14:46:19 argon volumio[938]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 26 14:46:19 argon volumio[938]: info: CoreCommandRouter::volumioGetState Feb 26 14:46:19 argon volumio[938]: info: CorePlayQueue::getTrack 46 Feb 26 14:46:21 argon go-librespot[29479]: time="2025-02-26T14:46:21+01:00" level=trace msg="sent dealer ping" Feb 26 14:46:21 argon go-librespot[29479]: time="2025-02-26T14:46:21+01:00" level=trace msg="received dealer pong" Feb 26 14:46:23 argon volumio[938]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 26 14:46:25 argon volumio[938]: info: Preload queue cleared Feb 26 14:46:27 argon volumio[938]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 26 14:46:27 argon volumio[938]: info: Preload queue cleared Feb 26 14:46:29 argon volumio[938]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 26 14:46:29 argon volumio[938]: info: Preload queue cleared Feb 26 14:46:30 argon volumio[938]: info: Preload queue cleared Feb 26 14:46:30 argon volumio[938]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::ClearQueue Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::stop Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::stPlaybackTimer Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::updateTrackBlock Feb 26 14:46:30 argon volumio[938]: info: CorePlayQueue::getTrackBlock Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::pushState Feb 26 14:46:30 argon volumio[938]: info: CorePlayQueue::getTrack 46 Feb 26 14:46:30 argon volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 14:46:30 argon volumio[938]: info: CoreCommandRouter::volumioPushState Feb 26 14:46:30 argon volumio[938]: info: MRS: Pushing multiroomSync output update for this device Feb 26 14:46:30 argon volumio[938]: info: MRS: Pushing multiroomSync output Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::serviceStop Feb 26 14:46:30 argon volumio[938]: info: CorePlayQueue::getTrack 46 Feb 26 14:46:30 argon volumio[938]: info: CoreCommandRouter::serviceStop Feb 26 14:46:30 argon volumio[938]: info: Spotify Stop Feb 26 14:46:30 argon volumio[938]: SPOTIFY: SPOTIFY STOP Feb 26 14:46:30 argon volumio[938]: SPOTIFY: {"status":"play","position":46,"title":"Rame (Beloved) (feat. Rukmani)","artist":"SNAP!","album":"Welcome to Tomorrow","albumart":"https://i.scdn.co/image/ab67616d0000b27392215b3ed86d652474241c68","uri":"spotify:track:0nAnAwlbFnXX71wtA996n6","trackType":"spotify","codec":"ogg","seek":8836,"duration":237,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":86,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Feb 26 14:46:30 argon volumio[938]: info: Sending Spotify command to local API: /player/pause Feb 26 14:46:30 argon volumio[938]: info: CorePlayQueue::clearPlayQueue Feb 26 14:46:30 argon volumio[938]: info: CorePlayQueue::saveQueue Feb 26 14:46:30 argon volumio[938]: info: CoreCommandRouter::volumioPushQueue Feb 26 14:46:30 argon volumio[938]: info: CoreStateMachine::addQueueItems Feb 26 14:46:30 argon volumio[938]: info: CorePlayQueue::addQueueItems Feb 26 14:46:30 argon volumio[938]: info: Preload queue cleared Feb 26 14:46:30 argon volumio[938]: info: Adding Item to queue: upnp/folder/http://192.168.1.1:49000/MediaServer/ContentDirectory/Control@4:cont2:110:0:0: Feb 26 14:46:30 argon volumio[938]: info: Exploding uri upnp/folder/http://192.168.1.1:49000/MediaServer/ContentDirectory/Control@4:cont2:110:0:0: in service upnp_browser Feb 26 14:46:30 argon volumio[938]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 26 14:46:31 argon volumio[938]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 26 14:46:31 argon sudo[30002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 26 14:46:31 argon sudo[30002]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 14:46:31 argon systemd[1]: Stopping peppymeterbasic Daemon... Feb 26 14:46:31 argon systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Feb 26 14:46:31 argon volumio[938]: SPOTIFY: RECEIVED VOLUMIO VOLUME 86 Feb 26 14:46:31 argon sudo[30005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 26 14:46:31 argon sudo[30005]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 14:46:31 argon volumio[938]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 26 14:46:31 argon volumio[938]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 26 14:46:31 argon volumio[938]: info: CoreCommandRouter::volumioPushQueue Feb 26 14:46:31 argon volumio[938]: info: CorePlayQueue::saveQueue Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::updateTrackBlock Feb 26 14:46:31 argon volumio[938]: info: CorePlayQueue::getTrackBlock Feb 26 14:46:31 argon volumio[938]: info: CoreCommandRouter::volumioPlay Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::play index 0 Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::stop Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:46:31 argon systemd[1]: peppymeterbasic.service: Succeeded. Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::play index undefined Feb 26 14:46:31 argon volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 26 14:46:31 argon systemd[1]: Stopped peppymeterbasic Daemon. Feb 26 14:46:31 argon volumio[938]: info: CorePlayQueue::getTrack 0 Feb 26 14:46:31 argon volumio[938]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 26 14:46:31 argon sudo[30005]: pam_unix(sudo:session): session closed for user root Feb 26 14:46:31 argon volumio[938]: info: peppymeterbasic Daemon Stop Feb 26 14:46:31 argon sudo[30002]: pam_unix(sudo:session): session closed for user root Feb 26 14:46:31 argon volumio[938]: info: peppymeterbasic Daemon Stop Feb 26 14:46:35 argon volumio[938]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 26 14:46:35 argon volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 14:46:35 argon volumio[938]: TypeError: Cannot read property 'length' of undefined Feb 26 14:46:35 argon volumio[938]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Feb 26 14:46:35 argon volumio[938]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Feb 26 14:46:35 argon volumio[938]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Feb 26 14:46:35 argon volumio[938]: at Parser.emit (events.js:315:20) Feb 26 14:46:35 argon volumio[938]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Feb 26 14:46:35 argon volumio[938]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Feb 26 14:46:35 argon volumio[938]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Feb 26 14:46:35 argon volumio[938]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Feb 26 14:46:35 argon volumio[938]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Feb 26 14:46:35 argon volumio[938]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Feb 26 14:46:35 argon volumio[938]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Feb 26 14:46:35 argon volumio[938]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Feb 26 14:46:35 argon volumio[938]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Feb 26 14:46:35 argon volumio[938]: at IncomingMessage.emit (events.js:327:22) Feb 26 14:46:35 argon volumio[938]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 26 14:46:35 argon volumio[938]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 26 14:46:35 argon volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 14:46:36 argon sudo[30022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-26 14:45 Feb 26 14:46:36 argon sudo[30022]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"