-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-10-24 16:23:24 CEST. -- Oct 24 16:22:25 volumio59 volumio[1266]: info: Tunnel connection is inactive, restarting it Oct 24 16:22:25 volumio59 volumio[1266]: info: Starting Tunnel 1 Oct 24 16:22:25 volumio59 volumio[1266]: info: Starting Tunnel Connection Checker Oct 24 16:22:26 volumio59 sudo[8323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 24 16:22:26 volumio59 sudo[8323]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 16:22:26 volumio59 autossh[8170]: received signal to exit (15) Oct 24 16:22:26 volumio59 systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 24 16:22:26 volumio59 systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 24 16:22:26 volumio59 systemd[1]: sshtunnel.service: Succeeded. Oct 24 16:22:26 volumio59 systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 24 16:22:26 volumio59 volumio[1266]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 24 16:22:26 volumio59 volumio[1266]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 24 16:22:26 volumio59 systemd[1]: Started MyVolumio SSH Tunnel. Oct 24 16:22:26 volumio59 sudo[8323]: pam_unix(sudo:session): session closed for user root Oct 24 16:22:26 volumio59 volumio[1266]: info: Remote SSH Started Oct 24 16:22:26 volumio59 autossh[8326]: port set to 0, monitoring disabled Oct 24 16:22:26 volumio59 autossh[8326]: starting ssh (count 1) Oct 24 16:22:26 volumio59 autossh[8326]: ssh child pid is 8329 Oct 24 16:22:26 volumio59 volumio[1266]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 24 16:22:26 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 16:22:47 volumio59 volumio[1266]: info: Discovery: Getting this device information Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 16:22:47 volumio59 volumio[1266]: verbose: New Socket.io Connection to 192.168.68.80:3000 from 192.168.68.71 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 24 16:22:47 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 24 16:22:51 volumio59 sudo[8374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 16:22:51 volumio59 sudo[8374]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 16:22:51 volumio59 sudo[8374]: pam_unix(sudo:session): session closed for user root Oct 24 16:22:51 volumio59 sudo[8377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 16:22:51 volumio59 sudo[8377]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 16:22:51 volumio59 sudo[8377]: pam_unix(sudo:session): session closed for user root Oct 24 16:22:51 volumio59 volumio[1266]: verbose: New Socket.io Connection to 192.168.68.75 from 192.168.68.71 UA: Mozilla/5.0 (Linux; Android 14; SM-X916B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.110 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 24 16:22:51 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 16:22:51 volumio59 sudo[8380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 16:22:51 volumio59 sudo[8380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 16:22:51 volumio59 sudo[8380]: pam_unix(sudo:session): session closed for user root Oct 24 16:22:51 volumio59 sudo[8383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 16:22:51 volumio59 sudo[8383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 16:22:51 volumio59 sudo[8383]: pam_unix(sudo:session): session closed for user root Oct 24 16:22:52 volumio59 volumio[1266]: verbose: New Socket.io Connection to 192.168.68.75 from 192.168.68.71 UA: Mozilla/5.0 (Linux; Android 14; SM-X916B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/126.0.6478.110 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 16:22:52 volumio59 volumio[1266]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 24 16:22:52 volumio59 volumio[1266]: info: Received Get System Info Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 16:22:52 volumio59 volumio[1266]: info: Discovery: Getting this device information Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:52 volumio59 volumio[1266]: info: Listing playlists Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 16:22:52 volumio59 volumio[1266]: info: Discovery: Getting this device information Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:52 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 16:22:53 volumio59 volumio[1266]: info: Received Get System Info Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 16:22:53 volumio59 volumio[1266]: info: Discovery: Getting this device information Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:22:53 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreCommandRouter::volumioPlay Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::play index undefined Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:22:54 volumio59 volumio[1266]: info: CorePlayQueue::getTrack 0 Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::startPlaybackTimer Oct 24 16:22:54 volumio59 volumio[1266]: info: CorePlayQueue::getTrack 0 Oct 24 16:22:54 volumio59 volumio[1266]: info: [1729779774417] ControllerTidal::resume Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 16:22:54 volumio59 volumio[1266]: info: ControllerMpd::resume Oct 24 16:22:54 volumio59 volumio[1266]: verbose: ControllerMpd::sendMpdCommand play Oct 24 16:22:54 volumio59 volumio[1266]: info: sendMpdCommand play took 4 milliseconds Oct 24 16:22:54 volumio59 volumio[1266]: info: Oct 24 16:22:54 volumio59 volumio[1266]: ---------------------------- MPD announces state update: player Oct 24 16:22:54 volumio59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 24 16:22:54 volumio59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 24 16:22:54 volumio59 volumio[1266]: info: ControllerMpd::getState Oct 24 16:22:54 volumio59 volumio[1266]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:22:54 volumio59 volumio[1266]: info: sendMpdCommand status took 1 milliseconds Oct 24 16:22:54 volumio59 volumio[1266]: verbose: ControllerMpd::parseState Oct 24 16:22:54 volumio59 volumio[1266]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:22:54 volumio59 volumio[1266]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 24 16:22:54 volumio59 volumio[1266]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:22:54 volumio59 volumio[1266]: info: ControllerMpd::pushState Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreCommandRouter::servicePushState Oct 24 16:22:54 volumio59 volumio[1266]: info: CorePlayQueue::getTrack 0 Oct 24 16:22:54 volumio59 volumio[1266]: verbose: STATE SERVICE {"status":"play","position":0,"seek":6818,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"738 Kbps","isStreaming":false,"title":"14403642&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/14403642&soundQuality=HI_RES","trackType":"tidal"} Oct 24 16:22:54 volumio59 volumio[1266]: verbose: CURRENT POSITION 0 Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::syncState stateService play Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::syncState currentStatus pause Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreStateMachine::pushState Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:22:54 volumio59 volumio[1266]: info: CoreCommandRouter::volumioPushState Oct 24 16:22:54 volumio59 volumio[1266]: info: MRS: Pushing multiroomSync output update for this device Oct 24 16:22:54 volumio59 volumio[1266]: info: MRS: Pushing multiroomSync output Oct 24 16:22:54 volumio59 volumio[1266]: info: ------------------------------ 22ms Oct 24 16:22:55 volumio59 volumio[1266]: info: Executing endpoint metavolumio Oct 24 16:22:55 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 16:22:55 volumio59 volumio[1266]: info: Executing endpoint metavolumio Oct 24 16:22:55 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 16:22:55 volumio59 volumio[1266]: info: Executing endpoint metavolumio Oct 24 16:22:55 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 24 16:23:02 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreCommandRouter::volumioPause Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::pause Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::stPlaybackTimer Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::servicePause Oct 24 16:23:03 volumio59 volumio[1266]: info: CorePlayQueue::getTrack 0 Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreCommandRouter::servicePause Oct 24 16:23:03 volumio59 volumio[1266]: info: [1729779783019] ControllerTidal::pause Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 16:23:03 volumio59 volumio[1266]: info: ControllerMpd::pause Oct 24 16:23:03 volumio59 volumio[1266]: verbose: ControllerMpd::sendMpdCommand pause Oct 24 16:23:03 volumio59 volumio[1266]: info: Oct 24 16:23:03 volumio59 volumio[1266]: ---------------------------- MPD announces state update: player Oct 24 16:23:03 volumio59 kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Oct 24 16:23:03 volumio59 volumio[1266]: info: sendMpdCommand pause took 3 milliseconds Oct 24 16:23:03 volumio59 volumio[1266]: info: ControllerMpd::getState Oct 24 16:23:03 volumio59 volumio[1266]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:23:03 volumio59 volumio[1266]: info: sendMpdCommand status took 1 milliseconds Oct 24 16:23:03 volumio59 volumio[1266]: verbose: ControllerMpd::parseState Oct 24 16:23:03 volumio59 volumio[1266]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:23:03 volumio59 volumio[1266]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 16:23:03 volumio59 volumio[1266]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:23:03 volumio59 volumio[1266]: info: ControllerMpd::pushState Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreCommandRouter::servicePushState Oct 24 16:23:03 volumio59 volumio[1266]: info: CorePlayQueue::getTrack 0 Oct 24 16:23:03 volumio59 volumio[1266]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":15809,"duration":192,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"766 Kbps","isStreaming":false,"title":"14403642&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/14403642&soundQuality=HI_RES","trackType":"tidal"} Oct 24 16:23:03 volumio59 volumio[1266]: verbose: CURRENT POSITION 0 Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::syncState stateService pause Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::syncState currentStatus pause Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::pushState Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreCommandRouter::volumioPushState Oct 24 16:23:03 volumio59 volumio[1266]: info: MRS: Pushing multiroomSync output update for this device Oct 24 16:23:03 volumio59 volumio[1266]: info: MRS: Pushing multiroomSync output Oct 24 16:23:03 volumio59 volumio[1266]: info: CoreStateMachine::stPlaybackTimer Oct 24 16:23:03 volumio59 volumio[1266]: info: ------------------------------ 14ms Oct 24 16:23:03 volumio59 volumio[1266]: info: touch_display: Setting screensaver timeout to 0 seconds. Oct 24 16:23:09 volumio59 volumio[1266]: error: Failed request for metavolumio API Oct 24 16:23:09 volumio59 volumio[1266]: error: Failed request for metavolumio API Oct 24 16:23:09 volumio59 volumio[1266]: error: Failed request for metavolumio API Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 16:23:11 volumio59 volumio[1266]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 24 16:23:11 volumio59 volumio[1266]: info: Received Get System Version Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 16:23:11 volumio59 volumio[1266]: info: Received Get System Info Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 16:23:11 volumio59 volumio[1266]: info: Discovery: Getting this device information Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::volumioGetState Oct 24 16:23:11 volumio59 volumio[1266]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 16:23:24 volumio59 volumio[1266]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 16:23:24 volumio59 volumio[1266]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] { Oct 24 16:23:24 volumio59 volumio[1266]: code: 'auth/network-request-failed', Oct 24 16:23:24 volumio59 volumio[1266]: a: null Oct 24 16:23:24 volumio59 volumio[1266]: } Oct 24 16:23:24 volumio59 volumio[1266]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 16:23:24 volumio59 sudo[8400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 16:22 Oct 24 16:23:24 volumio59 sudo[8400]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"