-- Logs begin at Wed 2024-04-10 21:41:07 CEST, end at Thu 2024-04-11 15:12:00 CEST. -- Apr 11 15:11:00 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.193:5353: type 47 is invalid Apr 11 15:11:03 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.160:5353: type 47 is invalid Apr 11 15:11:06 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.191:5353: type 47 is invalid Apr 11 15:11:10 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.159:5353: type 47 is invalid Apr 11 15:11:26 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.192:5353: type 47 is invalid Apr 11 15:11:29 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.193:5353: type 47 is invalid Apr 11 15:11:31 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.160:5353: type 47 is invalid Apr 11 15:11:34 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.191:5353: type 47 is invalid Apr 11 15:11:39 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.159:5353: type 47 is invalid Apr 11 15:11:47 volumiosmart volumio[777]: info: Apr 11 15:11:47 volumiosmart volumio[777]: ---------------------------- MPD announces system playlist update Apr 11 15:11:47 volumiosmart volumio[777]: info: Ignoring MPD Status Update Apr 11 15:11:47 volumiosmart volumio[777]: info: Apr 11 15:11:47 volumiosmart volumio[777]: ---------------------------- MPD announces state update: player Apr 11 15:11:47 volumiosmart volumio[777]: info: ControllerMpd::getState Apr 11 15:11:47 volumiosmart volumio[777]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:11:47 volumiosmart volumio[777]: info: ------------------------------ 5ms Apr 11 15:11:47 volumiosmart volumio[777]: info: sendMpdCommand status took 4 milliseconds Apr 11 15:11:47 volumiosmart volumio[777]: verbose: ControllerMpd::parseState Apr 11 15:11:47 volumiosmart volumio[777]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:11:47 volumiosmart volumio[777]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:11:47 volumiosmart volumio[777]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:11:47 volumiosmart volumio[777]: info: ControllerMpd::pushState Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreCommandRouter::servicePushState Apr 11 15:11:47 volumiosmart volumio[777]: info: CorePlayQueue::getTrack 0 Apr 11 15:11:47 volumiosmart volumio[777]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83217629,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"*WWW.GAMMARADIO.IT* ","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 11 15:11:47 volumiosmart volumio[777]: verbose: CURRENT POSITION 0 Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreStateMachine::syncState stateService play Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:11:47 volumiosmart volumio[777]: info: Received an update from plugin. extracting info from payload Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreStateMachine::pushState Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreCommandRouter::volumioPushState Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreStateMachine::pushState Apr 11 15:11:47 volumiosmart volumio[777]: info: CoreCommandRouter::volumioPushState Apr 11 15:11:47 volumiosmart volumio[777]: info: ------------------------------ 17ms Apr 11 15:11:49 volumiosmart sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 15:11:49 volumiosmart sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:11:49 volumiosmart sudo[1440]: pam_unix(sudo:session): session closed for user root Apr 11 15:11:49 volumiosmart sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 15:11:49 volumiosmart sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:11:49 volumiosmart sudo[1443]: pam_unix(sudo:session): session closed for user root Apr 11 15:11:49 volumiosmart volumio[777]: verbose: New Socket.io Connection to 192.4.0.137 from 192.4.0.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Edg/123.0.0.0 Total Clients: 4 Apr 11 15:11:49 volumiosmart sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 11 15:11:49 volumiosmart sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:11:49 volumiosmart sudo[1446]: pam_unix(sudo:session): session closed for user root Apr 11 15:11:49 volumiosmart sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 11 15:11:49 volumiosmart sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 11 15:11:49 volumiosmart sudo[1449]: pam_unix(sudo:session): session closed for user root Apr 11 15:11:49 volumiosmart volumio[777]: verbose: New Socket.io Connection to 192.4.0.137 from 192.4.0.144 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Edg/123.0.0.0 Total Clients: 5 Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetVisibleSources Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetState Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 11 15:11:49 volumiosmart volumio[777]: info: Received Get System Info Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:11:49 volumiosmart volumio[777]: info: Discovery: Getting this device information Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetState Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetState Apr 11 15:11:49 volumiosmart volumio[777]: info: Listing playlists Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 11 15:11:49 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 11 15:11:50 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 11 15:11:50 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetState Apr 11 15:11:51 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 15:11:51 volumiosmart volumio[777]: info: Received Get System Info Apr 11 15:11:51 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:11:51 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:11:51 volumiosmart volumio[777]: info: Discovery: Getting this device information Apr 11 15:11:51 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetState Apr 11 15:11:51 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:11:52 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 11 15:11:52 volumiosmart volumio[777]: info: Received Get System Info Apr 11 15:11:52 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 11 15:11:52 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 11 15:11:52 volumiosmart volumio[777]: info: Discovery: Getting this device information Apr 11 15:11:52 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetState Apr 11 15:11:52 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 11 15:11:54 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.192:5353: type 47 is invalid Apr 11 15:11:55 volumiosmart volumio[777]: info: CoreCommandRouter::volumioGetQueue Apr 11 15:11:55 volumiosmart volumio[777]: info: CoreStateMachine::getQueue Apr 11 15:11:55 volumiosmart volumio[777]: info: CorePlayQueue::getQueue Apr 11 15:11:57 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.193:5353: type 47 is invalid Apr 11 15:11:57 volumiosmart volumio[777]: info: Apr 11 15:11:57 volumiosmart volumio[777]: ---------------------------- MPD announces system playlist update Apr 11 15:11:57 volumiosmart volumio[777]: info: Ignoring MPD Status Update Apr 11 15:11:57 volumiosmart volumio[777]: info: Apr 11 15:11:57 volumiosmart volumio[777]: ---------------------------- MPD announces state update: player Apr 11 15:11:57 volumiosmart volumio[777]: info: ControllerMpd::getState Apr 11 15:11:57 volumiosmart volumio[777]: verbose: ControllerMpd::sendMpdCommand status Apr 11 15:11:57 volumiosmart volumio[777]: info: ------------------------------ 4ms Apr 11 15:11:57 volumiosmart volumio[777]: info: sendMpdCommand status took 2 milliseconds Apr 11 15:11:57 volumiosmart volumio[777]: verbose: ControllerMpd::parseState Apr 11 15:11:57 volumiosmart volumio[777]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 11 15:11:57 volumiosmart volumio[777]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 11 15:11:57 volumiosmart volumio[777]: verbose: ControllerMpd::parseTrackInfo Apr 11 15:11:57 volumiosmart volumio[777]: info: ControllerMpd::pushState Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreCommandRouter::servicePushState Apr 11 15:11:57 volumiosmart volumio[777]: info: CorePlayQueue::getTrack 0 Apr 11 15:11:57 volumiosmart volumio[777]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83228004,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"KAISER CHIEFS - RUBY","artist":"GAMMARADIO","album":null,"uri":"https://rn2.fluidstream.eu/gammaradio.mp3?FLID=3","trackType":"mp3"} Apr 11 15:11:57 volumiosmart volumio[777]: verbose: CURRENT POSITION 0 Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreStateMachine::syncState stateService play Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreStateMachine::syncState currentStatus play Apr 11 15:11:57 volumiosmart volumio[777]: info: Received an update from plugin. extracting info from payload Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreStateMachine::pushState Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreCommandRouter::volumioPushState Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreStateMachine::pushState Apr 11 15:11:57 volumiosmart volumio[777]: info: CoreCommandRouter::volumioPushState Apr 11 15:11:57 volumiosmart volumio[777]: info: ------------------------------ 17ms Apr 11 15:11:58 volumiosmart volumio[28447]: [Vollibrespot] : couldn't parse packet from 192.4.0.160:5353: type 47 is invalid Apr 11 15:11:59 volumiosmart volumio[777]: Searching all installed plugins Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: , search Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: mpd , search Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Apr 11 15:11:59 volumiosmart volumio[777]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 11 15:11:59 volumiosmart volumio[777]: info: CoreCommandRouter::executeOnPlugin: spop , search Apr 11 15:11:59 volumiosmart volumio[777]: info: Renewing Access Token via Daemon Apr 11 15:11:59 volumiosmart volumio[777]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 15:11:59 volumiosmart volumio[777]: TypeError: self.refreshAccessToken(...).then(...).fail(...).catch is not a function Apr 11 15:11:59 volumiosmart volumio[777]: at ControllerSpotify.spotifyCheckAccessToken (/data/plugins/music_service/spop/index.js:251:17) Apr 11 15:11:59 volumiosmart volumio[777]: at ControllerSpotify.search (/data/plugins/music_service/spop/index.js:1782:10) Apr 11 15:11:59 volumiosmart volumio[777]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1071:32) Apr 11 15:11:59 volumiosmart volumio[777]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:666:44) Apr 11 15:11:59 volumiosmart volumio[777]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:453:27) Apr 11 15:11:59 volumiosmart volumio[777]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Apr 11 15:11:59 volumiosmart volumio[777]: at Socket.emit (events.js:315:20) Apr 11 15:11:59 volumiosmart volumio[777]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 11 15:11:59 volumiosmart volumio[777]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 11 15:11:59 volumiosmart volumio[777]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 15:12:00 volumiosmart sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-11 15:11 Apr 11 15:12:00 volumiosmart sudo[1485]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"