-- Logs begin at Thu 2024-11-28 12:40:32 CET, end at Sat 2024-11-30 14:25:17 CET. -- Nov 30 14:24:09 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 14:24:09 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 14:24:09 rivo volumio[6264]: info: Discovery: Getting this device information Nov 30 14:24:09 rivo volumio[6264]: info: CoreCommandRouter::volumioGetState Nov 30 14:24:09 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 14:24:25 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 30 14:24:25 rivo volumio[6264]: info: Preload queue cleared Nov 30 14:24:35 rivo volumio[6264]: info: Preload queue cleared Nov 30 14:24:40 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 14:24:40 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 14:24:40 rivo volumio[6264]: info: Discovery: Getting this device information Nov 30 14:24:40 rivo volumio[6264]: info: CoreCommandRouter::volumioGetState Nov 30 14:24:40 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::volumioPause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::stPlaybackTimer Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::servicePause Nov 30 14:24:58 rivo volumio[6264]: info: CorePlayQueue::getTrack 2 Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::servicePause Nov 30 14:24:58 rivo volumio[6264]: info: [1732973098048] ControllerUPNPBrowser::pause Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand pause Nov 30 14:24:58 rivo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Nov 30 14:24:58 rivo kernel: spdif_a keep clk continuous Nov 30 14:24:58 rivo kernel: aml_spdif_close Nov 30 14:24:58 rivo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Nov 30 14:24:58 rivo volumio[6264]: info: Nov 30 14:24:58 rivo volumio[6264]: ---------------------------- MPD announces state update: player Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand pause took 3 milliseconds Nov 30 14:24:58 rivo volumio[6264]: info: ControllerMpd::getState Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand status Nov 30 14:24:58 rivo volumio[6264]: info: Nov 30 14:24:58 rivo volumio[6264]: ---------------------------- MPD announces state update: player Nov 30 14:24:58 rivo volumio[6264]: info: ControllerMpd::getState Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand status Nov 30 14:24:58 rivo volumio[6264]: info: Nov 30 14:24:58 rivo volumio[6264]: ---------------------------- MPD announces state update: player Nov 30 14:24:58 rivo volumio[6264]: info: ControllerMpd::getState Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand status Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand status took 5 milliseconds Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand status took 3 milliseconds Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand status took 2 milliseconds Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::parseState Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::parseState Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::parseState Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 14:24:58 rivo volumio[6264]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::parseTrackInfo Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::parseTrackInfo Nov 30 14:24:58 rivo volumio[6264]: verbose: ControllerMpd::parseTrackInfo Nov 30 14:24:58 rivo volumio[6264]: info: ControllerMpd::pushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::servicePushState Nov 30 14:24:58 rivo volumio[6264]: info: CorePlayQueue::getTrack 2 Nov 30 14:24:58 rivo volumio[6264]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":278101,"duration":479,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"14041.wma","artist":null,"album":null,"uri":"http://192.168.50.1:8200/MediaItems/14041.wma","trackType":"wma"} Nov 30 14:24:58 rivo volumio[6264]: verbose: CURRENT POSITION 2 Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::syncState stateService pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::syncState currentStatus pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::pushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::volumioPushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::stPlaybackTimer Nov 30 14:24:58 rivo volumio[6264]: info: ControllerMpd::pushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::servicePushState Nov 30 14:24:58 rivo volumio[6264]: info: CorePlayQueue::getTrack 2 Nov 30 14:24:58 rivo volumio[6264]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":278101,"duration":479,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"14041.wma","artist":null,"album":null,"uri":"http://192.168.50.1:8200/MediaItems/14041.wma","trackType":"wma"} Nov 30 14:24:58 rivo volumio[6264]: verbose: CURRENT POSITION 2 Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::syncState stateService pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::syncState currentStatus pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::pushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::volumioPushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::stPlaybackTimer Nov 30 14:24:58 rivo volumio[6264]: info: ControllerMpd::pushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::servicePushState Nov 30 14:24:58 rivo volumio[6264]: info: CorePlayQueue::getTrack 2 Nov 30 14:24:58 rivo volumio[6264]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":278101,"duration":479,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"14041.wma","artist":null,"album":null,"uri":"http://192.168.50.1:8200/MediaItems/14041.wma","trackType":"wma"} Nov 30 14:24:58 rivo volumio[6264]: verbose: CURRENT POSITION 2 Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::syncState stateService pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::syncState currentStatus pause Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::pushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreCommandRouter::volumioPushState Nov 30 14:24:58 rivo volumio[6264]: info: CoreStateMachine::stPlaybackTimer Nov 30 14:24:58 rivo volumio[6264]: info: ------------------------------ 41ms Nov 30 14:24:58 rivo volumio[6264]: info: ------------------------------ 40ms Nov 30 14:24:58 rivo volumio[6264]: info: ------------------------------ 39ms Nov 30 14:24:58 rivo volumio[6264]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 30 14:24:58 rivo volumio[6264]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 30 14:24:58 rivo volumio[6264]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 30 14:24:58 rivo volumio[6264]: info: MCU Signalled Playback Inactive Nov 30 14:25:10 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 30 14:25:10 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 30 14:25:10 rivo volumio[6264]: info: Discovery: Getting this device information Nov 30 14:25:10 rivo volumio[6264]: info: CoreCommandRouter::volumioGetState Nov 30 14:25:10 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 30 14:25:16 rivo volumio[6264]: Searching all installed plugins Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: , search Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: mpd , search Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Nov 30 14:25:16 rivo volumio[6264]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: webradio , search Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: bandcamp , search Nov 30 14:25:16 rivo volumio[6264]: info: CoreCommandRouter::executeOnPlugin: calmradio , search Nov 30 14:25:16 rivo volumio[6264]: info: [1732973116715] ControllerCalmRadio::searchCategories Nov 30 14:25:16 rivo volumio[6264]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 14:25:16 rivo volumio[6264]: TypeError: Cannot read property 'cats' of undefined Nov 30 14:25:16 rivo volumio[6264]: at ControllerCalmRadio.searchCategories (/data/plugins/music_service/calmradio/index.js:615:42) Nov 30 14:25:16 rivo volumio[6264]: at ControllerCalmRadio.search (/data/plugins/music_service/calmradio/index.js:665:28) Nov 30 14:25:16 rivo volumio[6264]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1097:32) Nov 30 14:25:16 rivo volumio[6264]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Nov 30 14:25:16 rivo volumio[6264]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Nov 30 14:25:16 rivo volumio[6264]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Nov 30 14:25:16 rivo volumio[6264]: at Socket.emit (events.js:400:28) Nov 30 14:25:16 rivo volumio[6264]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 30 14:25:16 rivo volumio[6264]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 30 14:25:16 rivo volumio[6264]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 30 14:25:17 rivo sudo[21254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-30 14:24 Nov 30 14:25:17 rivo sudo[21254]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"