-- Logs begin at Mon 2024-07-22 02:33:02 CEST, end at Mon 2024-07-22 11:52:19 CEST. -- Jul 22 11:51:00 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.159:5353: type 47 is invalid Jul 22 11:51:03 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.193:5353: type 47 is invalid Jul 22 11:51:07 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.192:5353: type 47 is invalid Jul 22 11:51:12 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.191:5353: type 47 is invalid Jul 22 11:51:14 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.160:5353: type 47 is invalid Jul 22 11:51:17 volumiosmart volumio[762]: info: Jul 22 11:51:17 volumiosmart volumio[762]: ---------------------------- MPD announces system playlist update Jul 22 11:51:17 volumiosmart volumio[762]: info: Ignoring MPD Status Update Jul 22 11:51:17 volumiosmart volumio[762]: info: Jul 22 11:51:17 volumiosmart volumio[762]: ---------------------------- MPD announces state update: player Jul 22 11:51:17 volumiosmart volumio[762]: info: ControllerMpd::getState Jul 22 11:51:17 volumiosmart volumio[762]: verbose: ControllerMpd::sendMpdCommand status Jul 22 11:51:17 volumiosmart volumio[762]: info: ------------------------------ 1ms Jul 22 11:51:17 volumiosmart volumio[762]: info: sendMpdCommand status took 1 milliseconds Jul 22 11:51:17 volumiosmart volumio[762]: verbose: ControllerMpd::parseState Jul 22 11:51:17 volumiosmart volumio[762]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 11:51:17 volumiosmart volumio[762]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 22 11:51:17 volumiosmart volumio[762]: verbose: ControllerMpd::parseTrackInfo Jul 22 11:51:17 volumiosmart volumio[762]: info: ControllerMpd::pushState Jul 22 11:51:17 volumiosmart volumio[762]: info: CoreCommandRouter::servicePushState Jul 22 11:51:17 volumiosmart volumio[762]: info: CorePlayQueue::getTrack 24 Jul 22 11:51:17 volumiosmart volumio[762]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240404301,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"For Those About To Rock (We Salute You)~AC/DC~~1981~~343~2024-07-22T09:36:57~2024-07-22T09:40:28~Virgin Radio Classic Rock~211.29~1466adea-48a9-4bed-95fc-b83a69d825f4","artist":"VirginRockClassics_mp3_segrate","album":null,"uri":"http://icy.unitedradio.it/VirginRockClassics.mp3","trackType":"mp3"} Jul 22 11:51:17 volumiosmart volumio[762]: verbose: CURRENT POSITION 24 Jul 22 11:51:17 volumiosmart volumio[762]: info: CoreStateMachine::syncState stateService play Jul 22 11:51:18 volumiosmart volumio[762]: info: CoreStateMachine::syncState currentStatus play Jul 22 11:51:18 volumiosmart volumio[762]: info: Received an update from plugin. extracting info from payload Jul 22 11:51:18 volumiosmart volumio[762]: info: CoreStateMachine::pushState Jul 22 11:51:18 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:51:18 volumiosmart volumio[762]: info: CoreCommandRouter::volumioPushState Jul 22 11:51:18 volumiosmart volumio[762]: info: CoreStateMachine::pushState Jul 22 11:51:18 volumiosmart volumio[762]: info: CoreCommandRouter::volumioPushState Jul 22 11:51:18 volumiosmart volumio[762]: info: ------------------------------ 6ms Jul 22 11:51:28 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.159:5353: type 47 is invalid Jul 22 11:51:31 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.193:5353: type 47 is invalid Jul 22 11:51:36 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.192:5353: type 47 is invalid Jul 22 11:51:40 volumiosmart nmbd[602]: [2024/07/22 11:51:40.094881, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 22 11:51:40 volumiosmart nmbd[602]: query_name_response: Multiple (2) responses received for a query on subnet 192.4.0.137 for name WORKGROUP<1d>. Jul 22 11:51:40 volumiosmart nmbd[602]: This response was from IP 192.4.0.33, reporting an IP address of 192.4.0.33. Jul 22 11:51:41 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.191:5353: type 47 is invalid Jul 22 11:51:44 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.160:5353: type 47 is invalid Jul 22 11:51:48 volumiosmart volumio[762]: info: Jul 22 11:51:48 volumiosmart volumio[762]: ---------------------------- MPD announces system playlist update Jul 22 11:51:48 volumiosmart volumio[762]: info: Ignoring MPD Status Update Jul 22 11:51:48 volumiosmart volumio[762]: info: Jul 22 11:51:48 volumiosmart volumio[762]: ---------------------------- MPD announces state update: player Jul 22 11:51:48 volumiosmart volumio[762]: info: ControllerMpd::getState Jul 22 11:51:48 volumiosmart volumio[762]: verbose: ControllerMpd::sendMpdCommand status Jul 22 11:51:48 volumiosmart volumio[762]: info: ------------------------------ 1ms Jul 22 11:51:48 volumiosmart volumio[762]: info: sendMpdCommand status took 1 milliseconds Jul 22 11:51:48 volumiosmart volumio[762]: verbose: ControllerMpd::parseState Jul 22 11:51:48 volumiosmart volumio[762]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 11:51:48 volumiosmart volumio[762]: info: sendMpdCommand playlistinfo took 0 milliseconds Jul 22 11:51:48 volumiosmart volumio[762]: verbose: ControllerMpd::parseTrackInfo Jul 22 11:51:48 volumiosmart volumio[762]: info: ControllerMpd::pushState Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreCommandRouter::servicePushState Jul 22 11:51:48 volumiosmart volumio[762]: info: CorePlayQueue::getTrack 24 Jul 22 11:51:48 volumiosmart volumio[762]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240434553,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"For Those About To Rock (We Salute You)~AC/DC~~1981~~343~2024-07-22T09:36:57~2024-07-22T09:40:58~Virgin Radio Classic Rock~241.30~1466adea-48a9-4bed-95fc-b83a69d825f4","artist":"VirginRockClassics_mp3_segrate","album":null,"uri":"http://icy.unitedradio.it/VirginRockClassics.mp3","trackType":"mp3"} Jul 22 11:51:48 volumiosmart volumio[762]: verbose: CURRENT POSITION 24 Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreStateMachine::syncState stateService play Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreStateMachine::syncState currentStatus play Jul 22 11:51:48 volumiosmart volumio[762]: info: Received an update from plugin. extracting info from payload Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreStateMachine::pushState Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreCommandRouter::volumioPushState Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreStateMachine::pushState Jul 22 11:51:48 volumiosmart volumio[762]: info: CoreCommandRouter::volumioPushState Jul 22 11:51:48 volumiosmart volumio[762]: info: ------------------------------ 6ms Jul 22 11:51:55 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.159:5353: type 47 is invalid Jul 22 11:52:00 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.193:5353: type 47 is invalid Jul 22 11:52:04 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.192:5353: type 47 is invalid Jul 22 11:52:10 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.191:5353: type 47 is invalid Jul 22 11:52:13 volumiosmart volumio[10415]: [Vollibrespot] : couldn't parse packet from 192.4.0.160:5353: type 47 is invalid Jul 22 11:52:13 volumiosmart sudo[11997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 11:52:13 volumiosmart sudo[11997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:13 volumiosmart sudo[11997]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:13 volumiosmart sudo[12000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 11:52:13 volumiosmart sudo[12000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:13 volumiosmart sudo[12000]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:13 volumiosmart volumio[762]: verbose: New Socket.io Connection to 192.4.0.137 from 192.4.0.130 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Edg/126.0.0.0 Total Clients: 4 Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetVisibleSources Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetState Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetQueue Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreStateMachine::getQueue Jul 22 11:52:14 volumiosmart volumio[762]: info: CorePlayQueue::getQueue Jul 22 11:52:14 volumiosmart volumio[762]: info: Listing playlists Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 22 11:52:14 volumiosmart volumio[762]: info: Received Get System Info Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 11:52:14 volumiosmart volumio[762]: info: Discovery: Getting this device information Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetState Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetState Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 22 11:52:14 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 22 11:52:15 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 22 11:52:15 volumiosmart volumio[762]: info: Received Get System Info Jul 22 11:52:15 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 11:52:15 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 11:52:15 volumiosmart volumio[762]: info: Discovery: Getting this device information Jul 22 11:52:15 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetState Jul 22 11:52:15 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 22 11:52:17 volumiosmart volumio[762]: info: Received Get System Info Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 11:52:17 volumiosmart volumio[762]: info: Discovery: Getting this device information Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::volumioGetState Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 11:52:17 volumiosmart volumio[762]: info: Jul 22 11:52:17 volumiosmart volumio[762]: ---------------------------- MPD announces system playlist update Jul 22 11:52:17 volumiosmart volumio[762]: info: Ignoring MPD Status Update Jul 22 11:52:17 volumiosmart volumio[762]: info: Jul 22 11:52:17 volumiosmart volumio[762]: ---------------------------- MPD announces state update: player Jul 22 11:52:17 volumiosmart volumio[762]: info: ControllerMpd::getState Jul 22 11:52:17 volumiosmart volumio[762]: verbose: ControllerMpd::sendMpdCommand status Jul 22 11:52:17 volumiosmart volumio[762]: info: ------------------------------ 2ms Jul 22 11:52:17 volumiosmart volumio[762]: info: sendMpdCommand status took 1 milliseconds Jul 22 11:52:17 volumiosmart volumio[762]: verbose: ControllerMpd::parseState Jul 22 11:52:17 volumiosmart volumio[762]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 22 11:52:17 volumiosmart volumio[762]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 22 11:52:17 volumiosmart volumio[762]: verbose: ControllerMpd::parseTrackInfo Jul 22 11:52:17 volumiosmart volumio[762]: info: ControllerMpd::pushState Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::servicePushState Jul 22 11:52:17 volumiosmart volumio[762]: info: CorePlayQueue::getTrack 24 Jul 22 11:52:17 volumiosmart volumio[762]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240464290,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"For Those About To Rock (We Salute You)~AC/DC~~1981~~343~2024-07-22T09:36:57~2024-07-22T09:41:28~Virgin Radio Classic Rock~271.31~1466adea-48a9-4bed-95fc-b83a69d825f4","artist":"VirginRockClassics_mp3_segrate","album":null,"uri":"http://icy.unitedradio.it/VirginRockClassics.mp3","trackType":"mp3"} Jul 22 11:52:17 volumiosmart volumio[762]: verbose: CURRENT POSITION 24 Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreStateMachine::syncState stateService play Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreStateMachine::syncState currentStatus play Jul 22 11:52:17 volumiosmart volumio[762]: info: Received an update from plugin. extracting info from payload Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreStateMachine::pushState Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::volumioPushState Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreStateMachine::pushState Jul 22 11:52:17 volumiosmart volumio[762]: info: CoreCommandRouter::volumioPushState Jul 22 11:52:17 volumiosmart volumio[762]: info: ------------------------------ 8ms Jul 22 11:52:19 volumiosmart volumio[762]: Searching all installed plugins Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: , search Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 22 11:52:19 volumiosmart volumio[762]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jul 22 11:52:19 volumiosmart volumio[762]: info: CoreCommandRouter::executeOnPlugin: spop , search Jul 22 11:52:19 volumiosmart volumio[762]: info: Renewing Access Token via Daemon Jul 22 11:52:19 volumiosmart volumio[762]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 11:52:19 volumiosmart volumio[762]: TypeError: self.refreshAccessToken(...).then(...).fail(...).catch is not a function Jul 22 11:52:19 volumiosmart volumio[762]: at ControllerSpotify.spotifyCheckAccessToken (/data/plugins/music_service/spop/index.js:251:17) Jul 22 11:52:19 volumiosmart volumio[762]: at ControllerSpotify.search (/data/plugins/music_service/spop/index.js:1782:10) Jul 22 11:52:19 volumiosmart volumio[762]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1071:32) Jul 22 11:52:19 volumiosmart volumio[762]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:666:44) Jul 22 11:52:19 volumiosmart volumio[762]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:453:27) Jul 22 11:52:19 volumiosmart volumio[762]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:457:44) Jul 22 11:52:19 volumiosmart volumio[762]: at Socket.emit (events.js:315:20) Jul 22 11:52:19 volumiosmart volumio[762]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 22 11:52:19 volumiosmart volumio[762]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 22 11:52:19 volumiosmart volumio[762]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 11:52:19 volumiosmart sudo[12026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 11:51 Jul 22 11:52:19 volumiosmart sudo[12026]: 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"