-- Logs begin at Thu 2019-02-14 19:11:58 JST, end at Mon 2024-04-08 23:36:58 JST. -- Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 23:35:00 primo volumio[5667]: info: Discovery: Getting this device information Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::volumioGetState Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 23:35:00 primo volumio[5667]: info: Discovery: Getting this device information Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::volumioGetState Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 23:35:00 primo volumio[5667]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.4 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 11 Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::volumioGetState Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 08 23:35:00 primo volumio[5667]: info: Received Get System Info Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 23:35:00 primo volumio[5667]: info: Discovery: Getting this device information Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::volumioGetState Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::volumioGetState Apr 08 23:35:00 primo volumio[5667]: info: Listing playlists Apr 08 23:35:00 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 08 23:35:08 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: spop , getTrack Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 08 23:35:09 primo volumio[5667]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 08 23:35:09 primo volumio[5667]: info: Received Get System Version Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 23:35:09 primo volumio[5667]: info: Received Get System Info Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 23:35:09 primo volumio[5667]: info: Discovery: Getting this device information Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::volumioGetState Apr 08 23:35:09 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 23:35:13 primo go-librespot[6532]: time="2024-04-08T23:35:13+09:00" level=debug msg="fetched chunk 5/14, size: 524288" Apr 08 23:35:25 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: spop , getTrack Apr 08 23:35:26 primo go-librespot[6532]: time="2024-04-08T23:35:26+09:00" level=debug msg="fetched chunk 6/14, size: 524288" Apr 08 23:35:33 primo volumio[5667]: info: Adding spotify:track:5nofQAlk7aSin5fZvkM3Vv to favourites using generic method Apr 08 23:35:33 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: spop , getTrack Apr 08 23:35:36 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: metavolumio , setInfinityPlayback Apr 08 23:35:36 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 08 23:35:36 primo volumio[5667]: info: CoreCommandRouter::volumioRandom Apr 08 23:35:36 primo volumio[5667]: info: CoreCommandRouter::writePlayerControls Apr 08 23:35:36 primo volumio[5667]: info: CoreStateMachine::setRepeat false single false Apr 08 23:35:36 primo volumio[5667]: info: Spotify Repeat: false - false Apr 08 23:35:36 primo volumio[5667]: info: Sending Spotify command with payload to local API: /player/repeat_context Apr 08 23:35:36 primo volumio[5667]: info: Sending Spotify command with payload to local API: /player/repeat_track Apr 08 23:35:39 primo go-librespot[6532]: time="2024-04-08T23:35:39+09:00" level=debug msg="fetched chunk 7/14, size: 524288" Apr 08 23:35:48 primo volumio[5667]: info: CoreCommandRouter::volumioGetQueue Apr 08 23:35:48 primo volumio[5667]: info: CoreStateMachine::getQueue Apr 08 23:35:48 primo volumio[5667]: info: CorePlayQueue::getQueue Apr 08 23:35:52 primo go-librespot[6532]: time="2024-04-08T23:35:52+09:00" level=debug msg="fetched chunk 8/14, size: 524288" Apr 08 23:36:05 primo go-librespot[6532]: time="2024-04-08T23:36:05+09:00" level=debug msg="fetched chunk 9/14, size: 524288" Apr 08 23:36:18 primo go-librespot[6532]: time="2024-04-08T23:36:18+09:00" level=debug msg="fetched chunk 10/14, size: 524288" Apr 08 23:36:20 primo volumio[5667]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 08 23:36:20 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 08 23:36:20 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 08 23:36:20 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 08 23:36:20 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Apr 08 23:36:20 primo volumio[5667]: info: Received Get System Version Apr 08 23:36:20 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 23:36:31 primo go-librespot[6532]: time="2024-04-08T23:36:31+09:00" level=debug msg="fetched chunk 11/14, size: 524288" Apr 08 23:36:33 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 23:36:33 primo volumio[5667]: info: CURURI: playlists Apr 08 23:36:33 primo volumio[5667]: info: Listing playlists Apr 08 23:36:33 primo volumio[5667]: info: Preload queue cleared Apr 08 23:36:35 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 23:36:35 primo volumio[5667]: info: CURURI: playlists/undefined Apr 08 23:36:35 primo volumio[5667]: info: Preload queue cleared Apr 08 23:36:44 primo go-librespot[6532]: time="2024-04-08T23:36:44+09:00" level=debug msg="fetched chunk 12/14, size: 524288" Apr 08 23:36:57 primo volumio[5667]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Apr 08 23:36:57 primo volumio[5667]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 23:36:57 primo volumio[5667]: TypeError: Cannot read property 'then' of undefined Apr 08 23:36:57 primo volumio[5667]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:470:20) Apr 08 23:36:57 primo volumio[5667]: at Socket.emit (events.js:400:28) Apr 08 23:36:57 primo volumio[5667]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 08 23:36:57 primo volumio[5667]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 08 23:36:57 primo volumio[5667]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 23:36:58 primo go-librespot[6532]: time="2024-04-08T23:36:58+09:00" level=debug msg="fetched chunk 13/14, size: 524288" Apr 08 23:36:58 primo sudo[29572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 23:35 Apr 08 23:36:58 primo sudo[29572]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 12:01:04 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="728189e850980f21ef569472434ec23d"