-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2024-05-02 08:15:41 UTC. -- May 02 08:14:04 volumio volumio[938]: info: CoreCommandRouter::volumioToggle May 02 08:14:04 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:04 volumio volumio[938]: info: CoreStateMachine::play index undefined May 02 08:14:04 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:14:04 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:04 volumio volumio[938]: info: CoreStateMachine::startPlaybackTimer May 02 08:14:04 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:04 volumio volumio[938]: info: [1714637644463] ControllerTidal::clearAddPlayTrack May 02 08:14:26 volumio volumio[938]: info: CoreCommandRouter::volumioToggle May 02 08:14:26 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:26 volumio volumio[938]: info: CoreStateMachine::play index undefined May 02 08:14:26 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:14:26 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:26 volumio volumio[938]: info: CoreStateMachine::startPlaybackTimer May 02 08:14:26 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:26 volumio volumio[938]: info: [1714637666297] ControllerTidal::clearAddPlayTrack May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:14:42 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:42 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:14:42 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.8.157 from 192.168.8.145 UA: okhttp/4.9.2 Total Clients: 8 May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:42 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 02 08:14:42 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 02 08:14:46 volumio sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 02 08:14:46 volumio sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:14:46 volumio sudo[2080]: pam_unix(sudo:session): session closed for user root May 02 08:14:46 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 02 08:14:46 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 02 08:14:46 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root May 02 08:14:46 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.8.157 from 192.168.8.145 UA: okhttp/4.9.2 Total Clients: 9 May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::volumioGetVisibleSources May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:46 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 02 08:14:46 volumio volumio[938]: info: Received Get System Info May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:14:46 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:46 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:46 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:46 volumio volumio[938]: info: Listing playlists May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 02 08:14:46 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:14:48 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 08:14:48 volumio volumio[938]: info: Received Get System Info May 02 08:14:48 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:14:48 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:14:48 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:14:48 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:48 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:48 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:14:48 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 02 08:14:50 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 08:14:50 volumio volumio[938]: info: Received Get System Info May 02 08:14:50 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:14:50 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:14:50 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:14:50 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:50 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:50 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:14:51 volumio volumio[938]: info: CoreCommandRouter::volumioPlay May 02 08:14:51 volumio volumio[938]: info: CoreStateMachine::play index undefined May 02 08:14:51 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:14:51 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:51 volumio volumio[938]: info: CoreStateMachine::startPlaybackTimer May 02 08:14:51 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:51 volumio volumio[938]: info: [1714637691773] ControllerTidal::clearAddPlayTrack May 02 08:14:51 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 08:14:51 volumio volumio[938]: info: Received Get System Info May 02 08:14:51 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:14:51 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:14:51 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:14:51 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:51 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:51 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:14:52 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 02 08:14:52 volumio volumio[938]: info: Received Get System Info May 02 08:14:52 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:14:52 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:14:52 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:14:52 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:14:52 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:14:52 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:14:58 volumio wpa_supplicant[777]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="WLAN1-JJ29ND" May 02 08:14:58 volumio wpa_supplicant[777]: wlan0: Trying to associate with SSID 'WLAN1-JJ29ND' May 02 08:15:01 volumio volumio[938]: info: VolumeController::SetAlsaVolume50 May 02 08:15:01 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:01 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:01 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:15:01 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:01 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:15:01 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:01 volumio volumio[938]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 02 08:15:01 volumio volumio[938]: info: touch_display: Setting screensaver timeout to 120 seconds. May 02 08:15:01 volumio wpa_supplicant[777]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 02 08:15:01 volumio wpa_supplicant[777]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="WLAN1-JJ29ND" auth_failures=5 duration=75 reason=CONN_FAILED May 02 08:15:02 volumio volumio[938]: info: CoreCommandRouter::getUIConfigOnPlugin May 02 08:15:02 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 02 08:15:02 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:15:02 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 02 08:15:02 volumio volumio[938]: info: Getting Alsa Cards List without I2S DAC May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 02 08:15:03 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 02 08:15:04 volumio volumio[938]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 02 08:15:04 volumio volumio[938]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio May 02 08:15:04 volumio volumio[938]: info: Received Get System Version May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 02 08:15:04 volumio volumio[938]: info: Received Get System Info May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 02 08:15:04 volumio volumio[938]: info: Discovery: Getting this device information May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:15:04 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:04 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 02 08:15:09 volumio volumio[938]: info: CoreCommandRouter::volumioGetQueue May 02 08:15:09 volumio volumio[938]: info: CoreStateMachine::getQueue May 02 08:15:09 volumio volumio[938]: info: CorePlayQueue::getQueue May 02 08:15:22 volumio volumio[938]: verbose: New Socket.io Connection to 192.168.8.157 from 192.168.8.145 UA: okhttp/4.9.2 Total Clients: 9 May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:15:39 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:15:39 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:39 volumio volumio[938]: info: Currently active: tidal May 02 08:15:39 volumio volumio[938]: info: Stopping currently active service May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::volumioStop May 02 08:15:39 volumio volumio[938]: info: CoreStateMachine::stop May 02 08:15:39 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:15:39 volumio volumio[938]: info: TidalConnect Active May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::volumioGetState May 02 08:15:39 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:39 volumio volumio[938]: info: Currently active: tidal May 02 08:15:39 volumio volumio[938]: info: Setting Volatile state to tidalconnect May 02 08:15:39 volumio volumio[938]: info: CoreStateMachine::setConsumeUpdateService undefined May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:39 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:39 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:39 volumio volumio[938]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:39 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:39 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:39 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:39 volumio volumio[938]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 02 08:15:39 volumio volumio[938]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Lh3MK01wsbSOyKWENS5jD3Qthzc2.2e12d6fa00d7f4321a2f7604501b990b.state.status' May 02 08:15:40 volumio vtcs[1745]: [2024-05-02 08:15:40.067] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:1 May 02 08:15:40 volumio volumio[938]: info: Pushing metadata May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:40 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:40 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:40 volumio volumio[938]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:40 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:40 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:40 volumio volumio[938]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:40 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:40 volumio volumio[938]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Lh3MK01wsbSOyKWENS5jD3Qthzc2.2e12d6fa00d7f4321a2f7604501b990b.state.status' May 02 08:15:40 volumio volumio[938]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Lh3MK01wsbSOyKWENS5jD3Qthzc2.2e12d6fa00d7f4321a2f7604501b990b.state.status' May 02 08:15:40 volumio vtcs[1745]: [2024-05-02 08:15:40.793] [tisoc] [warning] [PlaybackEngineTidal.cpp:142] url fetch error: 1, 401, {"status":401,"subStatus":4005,"userMessage":"Asset is not ready for playback"} May 02 08:15:40 volumio volumio[938]: info: Pushing metadata May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:40 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::servicePushState May 02 08:15:40 volumio volumio[938]: info: CoreStateMachine::pushState May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 02 08:15:40 volumio volumio[938]: info: CoreCommandRouter::volumioPushState May 02 08:15:40 volumio volumio[938]: info: CorePlayQueue::getTrack 0 May 02 08:15:40 volumio volumio[938]: info: CorePlayQueue::getTrack 19 May 02 08:15:40 volumio volumio[938]: info: Prefetching next song May 02 08:15:40 volumio volumio[938]: info: [1714637740908] ControllerTidal::prefetch May 02 08:15:40 volumio volumio[938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 08:15:40 volumio volumio[938]: TypeError: Cannot read property 'highestSoundQuality' of undefined May 02 08:15:40 volumio volumio[938]: at ControllerTidal.getProperSoundQuality (/myvolumio/plugins/music_service/tidal/tidal_real:1:17751) May 02 08:15:40 volumio volumio[938]: at ControllerTidal.prefetch (/myvolumio/plugins/music_service/tidal/tidal_real:1:18455) May 02 08:15:40 volumio volumio[938]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:437:18) May 02 08:15:40 volumio volumio[938]: at listOnTimeout (internal/timers.js:554:17) May 02 08:15:40 volumio volumio[938]: at processTimers (internal/timers.js:497:7) May 02 08:15:40 volumio volumio[938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 02 08:15:41 volumio sudo[2133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-02 08:14 May 02 08:15:41 volumio sudo[2133]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"