-- Logs begin at Sat 2024-11-30 02:38:41 CET, end at Mon 2024-12-02 12:30:52 CET. -- Dec 02 12:29:10 volumio volumio[1073]: error: error Dec 02 12:29:59 volumio volumio[1073]: error: error Dec 02 12:30:26 volumio volumio[1073]: info: Tunnel connection is inactive, restarting it Dec 02 12:30:26 volumio volumio[1073]: info: Starting Tunnel 1 Dec 02 12:30:26 volumio volumio[1073]: info: Starting Tunnel Connection Checker Dec 02 12:30:27 volumio sudo[27397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 02 12:30:27 volumio sudo[27397]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 02 12:30:27 volumio autossh[26947]: received signal to exit (15) Dec 02 12:30:27 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Dec 02 12:30:27 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Dec 02 12:30:27 volumio systemd[1]: sshtunnel.service: Succeeded. Dec 02 12:30:27 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Dec 02 12:30:27 volumio volumio[1073]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 02 12:30:27 volumio volumio[1073]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 02 12:30:27 volumio systemd[1]: Started MyVolumio SSH Tunnel. Dec 02 12:30:27 volumio sudo[27397]: pam_unix(sudo:session): session closed for user root Dec 02 12:30:27 volumio volumio[1073]: info: Remote SSH Started Dec 02 12:30:27 volumio autossh[27403]: port set to 0, monitoring disabled Dec 02 12:30:27 volumio autossh[27403]: starting ssh (count 1) Dec 02 12:30:27 volumio autossh[27403]: ssh child pid is 27406 Dec 02 12:30:27 volumio volumio[1073]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 02 12:30:27 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 02 12:30:27 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 02 12:30:33 volumio volumio[1073]: info: Clearing queue after UPNP request Dec 02 12:30:34 volumio volumio[1073]: info: CoreStateMachine::ClearQueue Dec 02 12:30:34 volumio volumio[1073]: info: CoreStateMachine::stop Dec 02 12:30:34 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 02 12:30:34 volumio volumio[1073]: info: CorePlayQueue::clearPlayQueue Dec 02 12:30:34 volumio volumio[1073]: info: CorePlayQueue::saveQueue Dec 02 12:30:34 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Dec 02 12:30:34 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Dec 02 12:30:34 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Dec 02 12:30:34 volumio volumio[1073]: info: CoreCommandRouter::volumioPushQueue Dec 02 12:30:34 volumio volumio[1073]: error: Upnp client error: Error: This socket has been ended by the other party Dec 02 12:30:35 volumio volumio[1073]: info: Dec 02 12:30:35 volumio volumio[1073]: ---------------------------- MPD announces state update: options Dec 02 12:30:35 volumio volumio[1073]: info: ControllerMpd::getState Dec 02 12:30:35 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Dec 02 12:30:35 volumio volumio[1073]: info: Starting UPNP Playback Dec 02 12:30:35 volumio volumio[1073]: info: Preparing playback through UPNP Dec 02 12:30:35 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 02 12:30:35 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 02 12:30:35 volumio volumio[1073]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 02 12:30:35 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Dec 02 12:30:35 volumio volumio[1073]: verbose: ControllerMpd::parseState Dec 02 12:30:35 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 12:30:35 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 02 12:30:35 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Dec 02 12:30:35 volumio volumio[1073]: info: ControllerMpd::pushState Dec 02 12:30:35 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Dec 02 12:30:35 volumio volumio[1073]: info: CoreStateMachine::pushState Dec 02 12:30:35 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 02 12:30:35 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Dec 02 12:30:35 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Dec 02 12:30:35 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Dec 02 12:30:35 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 02 12:30:35 volumio volumio[1073]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusique-midfi.mp3","artist":"francemusique-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusique-midfi.mp3","trackType":"mp3"} Dec 02 12:30:35 volumio volumio[1073]: verbose: CURRENT POSITION 0 Dec 02 12:30:35 volumio volumio[1073]: info: CoreStateMachine::syncState stateService stop Dec 02 12:30:35 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Dec 02 12:30:35 volumio volumio[1073]: info: CoreStateMachine::pushState Dec 02 12:30:35 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Dec 02 12:30:35 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Dec 02 12:30:35 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Dec 02 12:30:35 volumio volumio[1073]: info: No code Dec 02 12:30:35 volumio volumio[1073]: info: CoreStateMachine::pushState Dec 02 12:30:35 volumio volumio[1073]: info: CoreCommandRouter::volumioPushState Dec 02 12:30:35 volumio volumio[1073]: info: MRS: Pushing multiroomSync output update for this device Dec 02 12:30:35 volumio volumio[1073]: info: MRS: Pushing multiroomSync output Dec 02 12:30:35 volumio volumio[1073]: info: ------------------------------ 16ms Dec 02 12:30:36 volumio volumio[1073]: info: Dec 02 12:30:36 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:36 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:36 volumio volumio[1073]: info: Dec 02 12:30:36 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:36 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:36 volumio volumio[1073]: info: ------------------------------ 2ms Dec 02 12:30:36 volumio volumio[1073]: info: ------------------------------ 1ms Dec 02 12:30:36 volumio volumio[1073]: info: Dec 02 12:30:36 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:36 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:36 volumio volumio[1073]: info: Dec 02 12:30:36 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:36 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:36 volumio volumio[1073]: info: ------------------------------ 1ms Dec 02 12:30:36 volumio volumio[1073]: info: ------------------------------ 1ms Dec 02 12:30:36 volumio volumio[1073]: info: Dec 02 12:30:36 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:36 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:36 volumio volumio[1073]: info: Dec 02 12:30:36 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:36 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:36 volumio volumio[1073]: info: ------------------------------ 2ms Dec 02 12:30:36 volumio volumio[1073]: info: ------------------------------ 1ms Dec 02 12:30:38 volumio volumio[1073]: info: Dec 02 12:30:38 volumio volumio[1073]: ---------------------------- MPD announces state update: player Dec 02 12:30:38 volumio volumio[1073]: info: ControllerMpd::getState Dec 02 12:30:38 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Dec 02 12:30:38 volumio volumio[1073]: info: Executing endpoint metavolumio Dec 02 12:30:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 02 12:30:38 volumio volumio[1073]: info: Executing endpoint metavolumio Dec 02 12:30:38 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 02 12:30:39 volumio volumio[1073]: info: Dec 02 12:30:39 volumio volumio[1073]: ---------------------------- MPD announces system playlist update Dec 02 12:30:39 volumio volumio[1073]: info: Ignoring MPD Status Update Dec 02 12:30:39 volumio volumio[1073]: info: Dec 02 12:30:39 volumio volumio[1073]: ---------------------------- MPD announces state update: player Dec 02 12:30:39 volumio volumio[1073]: info: sendMpdCommand status took 403 milliseconds Dec 02 12:30:39 volumio volumio[1073]: info: ControllerMpd::getState Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::parseState Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 12:30:39 volumio volumio[1073]: info: ------------------------------ 3ms Dec 02 12:30:39 volumio volumio[1073]: info: sendMpdCommand status took 2 milliseconds Dec 02 12:30:39 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::parseState Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Dec 02 12:30:39 volumio volumio[1073]: info: ControllerMpd::pushState Dec 02 12:30:39 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Dec 02 12:30:39 volumio volumio[1073]: verbose: In UPNP mode Dec 02 12:30:39 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusique-midfi.mp3","artist":"francemusique-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusique-midfi.mp3","trackType":"mp3"} Dec 02 12:30:39 volumio volumio[1073]: verbose: CURRENT POSITION 0 Dec 02 12:30:39 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Dec 02 12:30:39 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus stop Dec 02 12:30:39 volumio volumio[1073]: info: ------------------------------ 408ms Dec 02 12:30:39 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 02 12:30:39 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Dec 02 12:30:39 volumio volumio[1073]: info: ControllerMpd::pushState Dec 02 12:30:39 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Dec 02 12:30:39 volumio volumio[1073]: verbose: In UPNP mode Dec 02 12:30:39 volumio volumio[1073]: verbose: STATE SERVICE {"status":"play","position":1,"seek":1238,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusique-midfi.mp3","artist":"francemusique-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusique-midfi.mp3","trackType":"mp3"} Dec 02 12:30:39 volumio volumio[1073]: verbose: CURRENT POSITION 0 Dec 02 12:30:39 volumio volumio[1073]: info: CoreStateMachine::syncState stateService play Dec 02 12:30:39 volumio volumio[1073]: info: CoreStateMachine::syncState currentStatus play Dec 02 12:30:39 volumio volumio[1073]: info: Received an update from plugin. extracting info from payload Dec 02 12:30:39 volumio volumio[1073]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 02 12:30:39 volumio volumio[1073]: info: CoreStateMachine::pushState Dec 02 12:30:39 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 02 12:30:39 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 02 12:30:39 volumio volumio[1073]: info: ------------------------------ 7ms Dec 02 12:30:47 volumio volumio[1073]: info: Dec 02 12:30:47 volumio volumio[1073]: ---------------------------- MPD announces state update: player Dec 02 12:30:47 volumio volumio[1073]: info: ControllerMpd::getState Dec 02 12:30:47 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand status Dec 02 12:30:47 volumio volumio[1073]: info: sendMpdCommand status took 0 milliseconds Dec 02 12:30:47 volumio volumio[1073]: verbose: ControllerMpd::parseState Dec 02 12:30:47 volumio volumio[1073]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 02 12:30:47 volumio volumio[1073]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 02 12:30:47 volumio volumio[1073]: verbose: ControllerMpd::parseTrackInfo Dec 02 12:30:47 volumio volumio[1073]: info: ControllerMpd::pushState Dec 02 12:30:47 volumio volumio[1073]: info: CoreCommandRouter::servicePushState Dec 02 12:30:47 volumio volumio[1073]: info: CoreStateMachine::pushState Dec 02 12:30:47 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 02 12:30:47 volumio volumio[1073]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Dec 02 12:30:47 volumio volumio[1073]: info: ------------------------------ 4ms Dec 02 12:30:49 volumio volumio[1073]: error: error Dec 02 12:30:51 volumio volumio[1073]: info: Starting UPNP Playback Dec 02 12:30:51 volumio volumio[1073]: info: Preparing playback through UPNP Dec 02 12:30:51 volumio volumio[1073]: info: Consume mode Dec 02 12:30:51 volumio volumio[1073]: info: CoreCommandRouter::volumioGetState Dec 02 12:30:51 volumio volumio[1073]: info: CorePlayQueue::getTrack 0 Dec 02 12:30:51 volumio volumio[1073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 12:30:51 volumio volumio[1073]: TypeError: Cannot read property 'name' of undefined Dec 02 12:30:51 volumio volumio[1073]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Dec 02 12:30:51 volumio volumio[1073]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Dec 02 12:30:51 volumio volumio[1073]: at UpnpInterface.prepareUpnpPlayback (/volumio/app/plugins/audio_interface/upnp/index.js:257:34) Dec 02 12:30:51 volumio volumio[1073]: at Socket. (/volumio/app/plugins/audio_interface/upnp/index.js:44:14) Dec 02 12:30:51 volumio volumio[1073]: at Socket.emit (events.js:315:20) Dec 02 12:30:51 volumio volumio[1073]: at addChunk (internal/streams/readable.js:309:12) Dec 02 12:30:51 volumio volumio[1073]: at readableAddChunk (internal/streams/readable.js:280:11) Dec 02 12:30:51 volumio volumio[1073]: at Socket.Readable.push (internal/streams/readable.js:223:10) Dec 02 12:30:51 volumio volumio[1073]: at TCP.onStreamRead (internal/stream_base_commons.js:188:23) Dec 02 12:30:51 volumio volumio[1073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 02 12:30:52 volumio sudo[27552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-02 12:29 Dec 02 12:30:52 volumio sudo[27552]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"