-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Sat 2024-11-02 11:18:14 EDT. -- Nov 02 11:17:00 volumio volumio[1070]: 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: 13 Nov 02 11:17:02 volumio volumio[1070]: 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: 14 Nov 02 11:17:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 11:17:03 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 11:17:04 volumio volumio[1070]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 02 11:17:04 volumio volumio[1070]: info: Received Get System Info Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:17:04 volumio volumio[1070]: info: Discovery: Getting this device information Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 11:17:04 volumio volumio[1070]: info: Received Get System Info Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:17:04 volumio volumio[1070]: info: Discovery: Getting this device information Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 11:17:04 volumio volumio[1070]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 02 11:17:04 volumio volumio[1070]: info: Received Get System Info Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:17:04 volumio volumio[1070]: info: Discovery: Getting this device information Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:04 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:04 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 02 11:17:05 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:05 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 02 11:17:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 11:17:06 volumio volumio[1070]: info: Received Get System Info Nov 02 11:17:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:17:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:17:06 volumio volumio[1070]: info: Discovery: Getting this device information Nov 02 11:17:06 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:06 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:17:07 volumio volumio[1070]: info: Executing endpoint metavolumio Nov 02 11:17:07 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 02 11:17:07 volumio volumio[1070]: info: Executing endpoint metavolumio Nov 02 11:17:07 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 02 11:17:07 volumio volumio[1070]: info: Executing endpoint metavolumio Nov 02 11:17:07 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 02 11:17:10 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 02 11:17:14 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 11:17:14 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 02 11:17:15 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:16 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:16 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:16 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:16 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:16 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:20 volumio volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 11:17:22 volumio volumio[1070]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 02 11:17:22 volumio volumio[1070]: info: Received Get System Version Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 11:17:22 volumio volumio[1070]: info: Received Get System Info Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:17:22 volumio volumio[1070]: info: Discovery: Getting this device information Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:22 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:17:25 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:25 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:34 volumio go-librespot[2711]: time="2024-11-02T11:17:34-04:00" level=error msg="did not receive last pong from dealer, 30s passed" Nov 02 11:17:34 volumio go-librespot[2711]: time="2024-11-02T11:17:34-04:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\"" Nov 02 11:17:35 volumio go-librespot[2711]: time="2024-11-02T11:17:35-04:00" level=debug msg="re-established dealer connection" Nov 02 11:17:35 volumio go-librespot[2711]: time="2024-11-02T11:17:35-04:00" level=debug msg="received connection id: OTg0NTJkYzQtMDUxZC00Y2QwLTg3MDMtNjY3ZDE2ZTc2NDJjK2RlYWxlcit0Y3A6Ly8wYWNiZDhiYS5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArM0I3RkYyQzdDNjZCMTI0M0IzMUQyRjZDODE1MEQwMTMyNjgxQzAzNEQ2NUFCMEI3RUQzQzBFMTkwRkQzRUZERg==" Nov 02 11:17:35 volumio go-librespot[2711]: time="2024-11-02T11:17:35-04:00" level=debug msg="put connect state because NEW_DEVICE" Nov 02 11:17:35 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:35 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:35 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:35 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:35 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:35 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:37 volumio volumio[1070]: info: volroon::Roon Core Paired: 192.168.1.106:9330 with ID of: 9bbca036-b4ea-41b7-95d6-76c5ebbcf9d1 Nov 02 11:17:37 volumio volumio[1070]: info: volroon::getState Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::volumioClearQueue Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::ClearQueue Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::stop Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::updateTrackBlock Nov 02 11:17:37 volumio volumio[1070]: info: CorePlayQueue::getTrackBlock Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::stPlaybackTimer Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::pushState Nov 02 11:17:37 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Nov 02 11:17:37 volumio volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:17:37 volumio volumio[1070]: info: MRS: Pushing multiroomSync output Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::serviceStop Nov 02 11:17:37 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::serviceStop Nov 02 11:17:37 volumio volumio[1070]: info: [1730560657369] ControllerQobuz::stop Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 11:17:37 volumio volumio[1070]: info: ControllerMpd::stop Nov 02 11:17:37 volumio volumio[1070]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 11:17:37 volumio volumio[1070]: info: CorePlayQueue::clearPlayQueue Nov 02 11:17:37 volumio volumio[1070]: info: CorePlayQueue::saveQueue Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Nov 02 11:17:37 volumio volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:17:37 volumio volumio[1070]: info: MRS: Pushing multiroomSync output Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::volumioPushQueue Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::volumioStop Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::stop Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:17:37 volumio volumio[1070]: verbose: volroon::State snapshot: {"status":"play","service":"volroon","title":"Girl in a Beret","artist":"Grant McLennan","album":"Horsebreaker Star","albumart":"http://192.168.1.106:9330/api/image/36dfef42a13152f8c67c19ef1ed2679f","uri":"","trackType":"roon","seek":3000,"duration":271,"samplerate":"","bitdepth":"","bitrate":"","channels":2,"stream":false,"random":false,"repeat":false,"repeatSingle":false,"disableUiControls":false} Nov 02 11:17:37 volumio volumio[1070]: info: volroon::pushState Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Nov 02 11:17:37 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Nov 02 11:17:37 volumio volumio[1070]: verbose: STATE SERVICE {"status":"play","service":"volroon","title":"Girl in a Beret","artist":"Grant McLennan","album":"Horsebreaker Star","albumart":"http://192.168.1.106:9330/api/image/36dfef42a13152f8c67c19ef1ed2679f","uri":"","trackType":"roon","seek":3000,"duration":271,"samplerate":"","bitdepth":"","bitrate":"","channels":2,"stream":false,"random":false,"repeat":false,"repeatSingle":false,"disableUiControls":false} Nov 02 11:17:37 volumio volumio[1070]: verbose: CURRENT POSITION 0 Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::syncState stateService play Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::syncState currentStatus stop Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::resetVolumioState Nov 02 11:17:37 volumio volumio[1070]: info: CoreStateMachine::getcurrentVolume Nov 02 11:17:37 volumio volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 11:17:37 volumio volumio[1070]: info: sendMpdCommand stop took 129 milliseconds Nov 02 11:17:37 volumio volumio[1070]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:17:37 volumio volumio[1070]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:17:38 volumio volumio[1070]: info: VolumeController:: Volume=100 Mute =false Nov 02 11:17:38 volumio volumio[1070]: info: [squeezelite_mc] Setting Squeezelite volume to 100 Nov 02 11:17:38 volumio volumio[1070]: info: CoreStateMachine::pushState Nov 02 11:17:38 volumio volumio[1070]: info: CorePlayQueue::getTrack 0 Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Nov 02 11:17:38 volumio volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:17:38 volumio volumio[1070]: info: MRS: Pushing multiroomSync output Nov 02 11:17:38 volumio volumio[1070]: info: CoreStateMachine::updateTrackBlock Nov 02 11:17:38 volumio volumio[1070]: info: CorePlayQueue::getTrackBlock Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 11:17:38 volumio volumio[1070]: info: volroon::Setting volatile state to volroon Nov 02 11:17:38 volumio volumio[1070]: info: volroon::pushState Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::servicePushState Nov 02 11:17:38 volumio volumio[1070]: info: CoreStateMachine::pushState Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Nov 02 11:17:38 volumio volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:17:38 volumio volumio[1070]: info: MRS: Pushing multiroomSync output Nov 02 11:17:38 volumio volumio[1070]: info: VolumeController:: Volume=100 Mute =false Nov 02 11:17:38 volumio volumio[1070]: info: [squeezelite_mc] Setting Squeezelite volume to 100 Nov 02 11:17:38 volumio volumio[1070]: info: CoreStateMachine::pushState Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::volumioPushState Nov 02 11:17:38 volumio volumio[1070]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:17:38 volumio volumio[1070]: info: MRS: Pushing multiroomSync output Nov 02 11:17:38 volumio volumio[1070]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:17:38 volumio volumio[1070]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:17:38 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:39 volumio volumio[1070]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:17:39 volumio volumio[1070]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 02 11:17:46 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:46 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 11:17:55 volumio volumio[1070]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 02 11:17:56 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:56 volumio volumio[1070]: info: CoreCommandRouter::volumioGetState Nov 02 11:17:56 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:56 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:56 volumio volumio[1070]: info: Listing playlists Nov 02 11:17:56 volumio volumio[1070]: info: Listing playlists Nov 02 11:18:02 volumio volumio[1070]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 11:18:11 volumio volumio[1070]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 11:18:11 volumio volumio[1070]: TypeError: Cannot read property 'ping' of undefined Nov 02 11:18:11 volumio volumio[1070]: at Timeout._onTimeout (/data/plugins/music_service/volroon/node_modules/node-roon-api/transport-websocket.js:26:21) Nov 02 11:18:11 volumio volumio[1070]: at listOnTimeout (internal/timers.js:554:17) Nov 02 11:18:11 volumio volumio[1070]: at processTimers (internal/timers.js:497:7) Nov 02 11:18:11 volumio volumio[1070]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 11:18:14 volumio sudo[4145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 11:17 Nov 02 11:18:14 volumio sudo[4145]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"