-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-01-11 12:37:48 CET. -- Jan 11 12:36:11 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 11 12:36:11 volumiomaren volumio[1013]: info: CoreStateMachine::getQueue Jan 11 12:36:11 volumiomaren volumio[1013]: info: CorePlayQueue::getQueue Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioClearQueue Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::ClearQueue Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::stop Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::updateTrackBlock Jan 11 12:36:13 volumiomaren volumio[1013]: info: CorePlayQueue::getTrackBlock Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::stPlaybackTimer Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::pushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: CorePlayQueue::getTrack 1 Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::serviceStop Jan 11 12:36:13 volumiomaren volumio[1013]: info: CorePlayQueue::getTrack 1 Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::serviceStop Jan 11 12:36:13 volumiomaren volumio[1013]: info: [1736595373496] ControllerQobuz::stop Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 12:36:13 volumiomaren volumio[1013]: info: ControllerMpd::stop Jan 11 12:36:13 volumiomaren volumio[1013]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 12:36:13 volumiomaren volumio[1013]: info: CorePlayQueue::clearPlayQueue Jan 11 12:36:13 volumiomaren volumio[1013]: info: CorePlayQueue::saveQueue Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioPushQueue Jan 11 12:36:13 volumiomaren volumio[1013]: info: Jan 11 12:36:13 volumiomaren volumio[1013]: ---------------------------- MPD announces state update: player Jan 11 12:36:13 volumiomaren volumio[1013]: info: sendMpdCommand stop took 51 milliseconds Jan 11 12:36:13 volumiomaren volumio[1013]: info: ControllerMpd::getState Jan 11 12:36:13 volumiomaren volumio[1013]: verbose: ControllerMpd::sendMpdCommand status Jan 11 12:36:13 volumiomaren volumio[1013]: info: sendMpdCommand status took 3 milliseconds Jan 11 12:36:13 volumiomaren volumio[1013]: verbose: ControllerMpd::parseState Jan 11 12:36:13 volumiomaren volumio[1013]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 12:36:13 volumiomaren volumio[1013]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 11 12:36:13 volumiomaren volumio[1013]: verbose: ControllerMpd::parseTrackInfo Jan 11 12:36:13 volumiomaren volumio[1013]: info: ControllerMpd::pushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::servicePushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::pushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output Jan 11 12:36:13 volumiomaren volumio[1013]: info: CorePlayQueue::getTrack 1 Jan 11 12:36:13 volumiomaren volumio[1013]: 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":"155345607","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/155345607","trackType":"qobuz"} Jan 11 12:36:13 volumiomaren volumio[1013]: verbose: CURRENT POSITION 1 Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::syncState stateService stop Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::syncState currentStatus stop Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::pushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output Jan 11 12:36:13 volumiomaren volumio[1013]: info: No code Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreStateMachine::pushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioPushState Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output update for this device Jan 11 12:36:13 volumiomaren volumio[1013]: info: MRS: Pushing multiroomSync output Jan 11 12:36:13 volumiomaren volumio[1013]: info: ------------------------------ 68ms Jan 11 12:36:17 volumiomaren wpa_supplicant[891]: RRM: Ignoring radio measurement request: Not RRM network Jan 11 12:36:19 volumiomaren wpa_supplicant[891]: RRM: Ignoring radio measurement request: Not RRM network Jan 11 12:36:19 volumiomaren volumio[1013]: info: Executing endpoint metavolumio Jan 11 12:36:19 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 12:36:19 volumiomaren volumio[1013]: info: Executing endpoint metavolumio Jan 11 12:36:19 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 12:36:21 volumiomaren wpa_supplicant[891]: wlan0: Associated with 7c:ff:4d:ee:5b:66 Jan 11 12:36:21 volumiomaren dhcpcd[903]: wlan0: carrier lost Jan 11 12:36:21 volumiomaren wpa_supplicant[891]: wlan0: CTRL-EVENT-CONNECTED - Connection to 7c:ff:4d:ee:5b:66 completed [id=0 id_str=] Jan 11 12:36:21 volumiomaren wpa_supplicant[891]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jan 11 12:36:21 volumiomaren avahi-daemon[644]: Withdrawing address record for 192.168.178.44 on wlan0. Jan 11 12:36:21 volumiomaren avahi-daemon[644]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.44. Jan 11 12:36:21 volumiomaren dhcpcd[903]: wlan0: deleting route to 192.168.178.0/24 Jan 11 12:36:21 volumiomaren dhcpcd[903]: wlan0: deleting default route via 192.168.178.1 Jan 11 12:36:21 volumiomaren avahi-daemon[644]: Interface wlan0.IPv4 no longer relevant for mDNS. Jan 11 12:36:21 volumiomaren volumio[1013]: info: Discovery: A device disappeared from network Jan 11 12:36:21 volumiomaren volumio[1013]: info: Discovery: Device volumiomaren disappeared from network Jan 11 12:36:21 volumiomaren dhcpcd[903]: wlan0: carrier acquired Jan 11 12:36:21 volumiomaren dhcpcd[903]: wlan0: IAID 32:dd:f7:99 Jan 11 12:36:22 volumiomaren dhcpcd[903]: wlan0: rebinding lease of 192.168.178.44 Jan 11 12:36:22 volumiomaren dhcpcd[903]: wlan0: probing address 192.168.178.44/24 Jan 11 12:36:22 volumiomaren dhcpcd[903]: wlan0: soliciting an IPv6 router Jan 11 12:36:23 volumiomaren ntpd[783]: Deleting interface #4 wlan0, 192.168.178.44#123, interface stats: received=38, sent=39, dropped=0, active_time=45 secs Jan 11 12:36:23 volumiomaren ntpd[783]: 46.235.112.25 local addr 192.168.178.44 -> Jan 11 12:36:23 volumiomaren ntpd[783]: 78.47.93.191 local addr 192.168.178.44 -> Jan 11 12:36:23 volumiomaren ntpd[783]: 109.91.184.21 local addr 192.168.178.44 -> Jan 11 12:36:23 volumiomaren ntpd[783]: 178.63.52.31 local addr 192.168.178.44 -> Jan 11 12:36:23 volumiomaren ntpd[783]: 78.31.67.99 local addr 192.168.178.44 -> Jan 11 12:36:23 volumiomaren ntpd[783]: 194.50.19.204 local addr 192.168.178.44 -> Jan 11 12:36:27 volumiomaren volumio[1013]: error: Failed request for metavolumio API Jan 11 12:36:27 volumiomaren volumio[1013]: error: Failed request for metavolumio API Jan 11 12:36:27 volumiomaren dhcpcd[903]: wlan0: leased 192.168.178.44 for 864000 seconds Jan 11 12:36:27 volumiomaren avahi-daemon[644]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.44. Jan 11 12:36:27 volumiomaren avahi-daemon[644]: New relevant interface wlan0.IPv4 for mDNS. Jan 11 12:36:27 volumiomaren dhcpcd[903]: wlan0: adding route to 192.168.178.0/24 Jan 11 12:36:27 volumiomaren dhcpcd[903]: wlan0: adding default route via 192.168.178.1 Jan 11 12:36:27 volumiomaren avahi-daemon[644]: Registering new address record for 192.168.178.44 on wlan0.IPv4. Jan 11 12:36:28 volumiomaren volumio[1013]: info: Discovery: adding f8c0c04f-ea58-4a56-97d4-80004b0a40b7 Jan 11 12:36:28 volumiomaren volumio[1013]: info: Discovery: Found device VolumioMaren Jan 11 12:36:28 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:36:28 volumiomaren ntpd[783]: Listen normally on 5 wlan0 192.168.178.44:123 Jan 11 12:36:28 volumiomaren ntpd[783]: new interface(s) found: waking up resolver Jan 11 12:36:41 volumiomaren ntpd[783]: Soliciting pool server 136.243.177.133 Jan 11 12:36:42 volumiomaren ntpd[783]: Soliciting pool server 185.41.106.152 Jan 11 12:36:43 volumiomaren ntpd[783]: Soliciting pool server 88.198.200.96 Jan 11 12:36:43 volumiomaren ntpd[783]: Soliciting pool server 185.252.140.125 Jan 11 12:36:44 volumiomaren ntpd[783]: Soliciting pool server 89.58.43.2 Jan 11 12:36:44 volumiomaren ntpd[783]: Soliciting pool server 195.201.20.16 Jan 11 12:36:45 volumiomaren ntpd[783]: Soliciting pool server 85.215.189.120 Jan 11 12:36:46 volumiomaren ntpd[783]: Soliciting pool server 131.188.3.220 Jan 11 12:36:49 volumiomaren ntpd[783]: Soliciting pool server 62.108.36.235 Jan 11 12:36:54 volumiomaren volumio[1013]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jan 11 12:36:54 volumiomaren volumio[1013]: info: Received Get System Info Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 12:36:54 volumiomaren volumio[1013]: info: Discovery: Getting this device information Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 12:36:54 volumiomaren volumio[1013]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 11 12:36:54 volumiomaren volumio[1013]: info: Received Get System Info Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 12:36:54 volumiomaren volumio[1013]: info: Discovery: Getting this device information Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:36:54 volumiomaren volumio[1013]: info: Listing playlists Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetQueue Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreStateMachine::getQueue Jan 11 12:36:54 volumiomaren volumio[1013]: info: CorePlayQueue::getQueue Jan 11 12:36:54 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 12:37:04 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 12:37:05 volumiomaren volumio[1013]: info: Executing endpoint metavolumio Jan 11 12:37:05 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 12:37:05 volumiomaren volumio[1013]: info: Executing endpoint metavolumio Jan 11 12:37:05 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 12:37:11 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 11 12:37:11 volumiomaren volumio[1013]: info: handleBrowseUri took 107 milliseconds Jan 11 12:37:11 volumiomaren volumio[1013]: info: Preload queue cleared Jan 11 12:37:14 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 11 12:37:15 volumiomaren volumio[1013]: info: handleBrowseUri took 941 milliseconds Jan 11 12:37:15 volumiomaren volumio[1013]: info: Preload queue cleared Jan 11 12:37:17 volumiomaren volumio[1013]: info: Tunnel connection is inactive, restarting it Jan 11 12:37:17 volumiomaren volumio[1013]: info: Starting Tunnel 1 Jan 11 12:37:17 volumiomaren volumio[1013]: info: Starting Tunnel Connection Checker Jan 11 12:37:17 volumiomaren sudo[2470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 11 12:37:17 volumiomaren sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 12:37:17 volumiomaren autossh[1954]: received signal to exit (15) Jan 11 12:37:17 volumiomaren systemd[1]: Stopping MyVolumio SSH Tunnel... Jan 11 12:37:17 volumiomaren systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Jan 11 12:37:17 volumiomaren systemd[1]: sshtunnel.service: Succeeded. Jan 11 12:37:17 volumiomaren systemd[1]: Stopped MyVolumio SSH Tunnel. Jan 11 12:37:17 volumiomaren volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 11 12:37:17 volumiomaren volumio[1013]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 11 12:37:17 volumiomaren systemd[1]: Started MyVolumio SSH Tunnel. Jan 11 12:37:17 volumiomaren sudo[2470]: pam_unix(sudo:session): session closed for user root Jan 11 12:37:17 volumiomaren volumio[1013]: info: Remote SSH Started Jan 11 12:37:17 volumiomaren autossh[2473]: port set to 0, monitoring disabled Jan 11 12:37:17 volumiomaren autossh[2473]: starting ssh (count 1) Jan 11 12:37:17 volumiomaren autossh[2473]: ssh child pid is 2476 Jan 11 12:37:17 volumiomaren volumio[1013]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 11 12:37:17 volumiomaren volumio[1013]: info: CoreCommandRouter::volumioGetState Jan 11 12:37:19 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 11 12:37:20 volumiomaren volumio[1013]: info: handleBrowseUri took 985 milliseconds Jan 11 12:37:20 volumiomaren volumio[1013]: info: Preload queue cleared Jan 11 12:37:25 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 11 12:37:25 volumiomaren volumio[1013]: info: handleBrowseUri took 613 milliseconds Jan 11 12:37:25 volumiomaren volumio[1013]: info: Preload queue cleared Jan 11 12:37:26 volumiomaren volumio[1013]: info: Executing endpoint metavolumio Jan 11 12:37:26 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 12:37:26 volumiomaren volumio[1013]: info: Executing endpoint getSimilarArtists Jan 11 12:37:26 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 11 12:37:38 volumiomaren volumio[1013]: info: Executing endpoint metavolumio Jan 11 12:37:38 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 12:37:47 volumiomaren volumio[1013]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 12:37:47 volumiomaren volumio[1013]: info: CURURI: playlists Jan 11 12:37:47 volumiomaren volumio[1013]: info: Listing playlists Jan 11 12:37:47 volumiomaren volumio[1013]: info: Preload queue cleared Jan 11 12:37:47 volumiomaren volumio[1013]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 12:37:47 volumiomaren volumio[1013]: Error: read ECONNRESET Jan 11 12:37:47 volumiomaren volumio[1013]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Jan 11 12:37:47 volumiomaren volumio[1013]: errno: -104, Jan 11 12:37:47 volumiomaren volumio[1013]: code: 'ECONNRESET', Jan 11 12:37:47 volumiomaren volumio[1013]: syscall: 'read' Jan 11 12:37:47 volumiomaren volumio[1013]: } Jan 11 12:37:47 volumiomaren volumio[1013]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 12:37:48 volumiomaren sudo[2579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 12:36 Jan 11 12:37:48 volumiomaren sudo[2579]: 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"