-- Logs begin at Thu 2024-10-03 14:17:23 UTC, end at Fri 2024-10-04 12:22:46 UTC. -- Oct 04 12:21:33 volumio vtcs[2225]: [2024-10-04 12:21:33.971] [tisoc] [warning] [PlaybackControllerImpl.cpp:913] Not send NotifyMediaChanged(media_info is null) Oct 04 12:22:02 volumio vtcs[2225]: [2024-10-04 12:22:02] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 12:22:20 volumio volumio[1204]: info: Discovery: Getting this device information Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 12:22:20 volumio volumio[1204]: verbose: New Socket.io Connection to 192.168.0.9:3000 from 192.168.0.20 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 04 12:22:20 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 04 12:22:21 volumio sudo[15875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 12:22:21 volumio sudo[15877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 12:22:21 volumio sudo[15875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 12:22:21 volumio sudo[15877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 12:22:21 volumio sudo[15875]: pam_unix(sudo:session): session closed for user root Oct 04 12:22:21 volumio sudo[15877]: pam_unix(sudo:session): session closed for user root Oct 04 12:22:21 volumio volumio[1204]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 12:22:21 volumio volumio[1204]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 04 12:22:21 volumio volumio[1204]: info: Received Get System Info Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 12:22:21 volumio volumio[1204]: info: Discovery: Getting this device information Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:21 volumio volumio[1204]: info: Listing playlists Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 04 12:22:21 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 04 12:22:22 volumio sudo[15896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 12:22:22 volumio sudo[15896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 12:22:22 volumio sudo[15899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 12:22:22 volumio sudo[15896]: pam_unix(sudo:session): session closed for user root Oct 04 12:22:22 volumio sudo[15899]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 12:22:22 volumio sudo[15899]: pam_unix(sudo:session): session closed for user root Oct 04 12:22:22 volumio volumio[1204]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.20 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 12:22:22 volumio volumio[1204]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 04 12:22:22 volumio volumio[1204]: info: Received Get System Info Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 12:22:22 volumio volumio[1204]: info: Discovery: Getting this device information Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:22 volumio volumio[1204]: info: Listing playlists Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 04 12:22:22 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 04 12:22:23 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 04 12:22:23 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 12:22:23 volumio volumio[1204]: info: Received Get System Info Oct 04 12:22:23 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 12:22:23 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 12:22:23 volumio volumio[1204]: info: Discovery: Getting this device information Oct 04 12:22:23 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:23 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 12:22:24 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 12:22:24 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 12:22:24 volumio volumio[1204]: info: Discovery: Getting this device information Oct 04 12:22:24 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:24 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 12:22:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 12:22:25 volumio volumio[1204]: info: Received Get System Info Oct 04 12:22:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 12:22:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 12:22:25 volumio volumio[1204]: info: Discovery: Getting this device information Oct 04 12:22:25 volumio volumio[1204]: info: CoreCommandRouter::volumioGetState Oct 04 12:22:25 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 12:22:26 volumio volumio[1204]: info: CoreCommandRouter::volumioGetQueue Oct 04 12:22:26 volumio volumio[1204]: info: CoreStateMachine::getQueue Oct 04 12:22:26 volumio volumio[1204]: info: CorePlayQueue::getQueue Oct 04 12:22:32 volumio volumio[1204]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 04 12:22:45 volumio volumio[2143]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Authenticated as "1120201994" ! Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Using alsa sink Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Country: "GB" Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Metadata pipe established Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Oct 04 12:22:46 volumio volumio[1204]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Event: SessionActive { became_active_at: 1728044566333 } Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : SessionActive! Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Fetching autoplay context uri Oct 04 12:22:46 volumio volumio[1204]: [SpotifyConnect] A connect session has begun Oct 04 12:22:46 volumio volumio[1204]: info: Acquiring new spotify session Oct 04 12:22:46 volumio volumio[1204]: info: CoreCommandRouter::volumioStop Oct 04 12:22:46 volumio volumio[1204]: info: CoreStateMachine::stop Oct 04 12:22:46 volumio volumio[1204]: info: CoreStateMachine::serviceStop Oct 04 12:22:46 volumio volumio[1204]: info: CoreCommandRouter::serviceStop Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 102523295126806058970156385441890355933, audio_type: Track } } Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 102523295126806058970156385441890355933, audio_type: Track } } Oct 04 12:22:46 volumio volumio[1204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 12:22:46 volumio vtcs[2225]: [2024-10-04 12:22:46.338] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Oct 04 12:22:46 volumio volumio[1204]: TypeError: Cannot read property 'then' of undefined Oct 04 12:22:46 volumio volumio[1204]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2132:31) Oct 04 12:22:46 volumio volumio[1204]: at SpotConnEvents.emit (events.js:400:28) Oct 04 12:22:46 volumio volumio[1204]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:92:16) Oct 04 12:22:46 volumio volumio[1204]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Oct 04 12:22:46 volumio volumio[1204]: at Socket.emit (events.js:400:28) Oct 04 12:22:46 volumio volumio[1204]: at UDP.onMessage [as onmessage] (dgram.js:931:8) Oct 04 12:22:46 volumio volumio[1204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:5xL4xX63iwalGypkSTSoAn"> Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Resolving uri "spotify:station:playlist:5xL4xX63iwalGypkSTSoAn" Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBrK6Q6XufG02OT2b8X61yleKVUnc7L-oWn4C4COX9XuODC_jyPLVQei7urR_P8zJ4IcypOni-PS_BDs6OENVfKszBbN1VsVDbyWdVJeIFVMY92lCTwGEQW6jqtxGWLxeH6cSee7ojxN4hee2uAbbYzDAe_XeJPS3BMBiJrTbvIteaQUyfTJHDBXpaYUlUF1ZPmrpBQcHjCzw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Oct 04 12:22:46 volumio volumio[2143]: [Vollibrespot] : Loading with Spotify URI Oct 04 12:22:46 volumio sudo[15932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 12:21 Oct 04 12:22:46 volumio sudo[15932]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:19:36 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="43b87fbef9c19ede18711bdf23c439c5"