-- Logs begin at Mon 2024-10-07 01:19:08 CEST, end at Tue 2024-10-08 09:44:54 CEST. -- Oct 08 09:43:07 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:43:07 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:43:15 volumio nmbd[790]: [2024/10/08 09:43:15.914907, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 08 09:43:15 volumio nmbd[790]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.101.59 for name WORKGROUP<1d>. Oct 08 09:43:15 volumio nmbd[790]: This response was from IP 192.168.101.42, reporting an IP address of 192.168.101.42. Oct 08 09:43:17 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:43:17 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:43:17 volumio volumio[887]: info: Listing playlists Oct 08 09:43:17 volumio volumio[887]: info: Listing playlists Oct 08 09:43:27 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:43:27 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:43:37 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:43:37 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:43:37 volumio volumio[887]: info: Listing playlists Oct 08 09:43:37 volumio volumio[887]: info: Listing playlists Oct 08 09:43:47 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:43:47 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:43:51 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 08 09:43:51 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 08 09:43:57 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:43:57 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:43:57 volumio volumio[887]: info: Listing playlists Oct 08 09:43:57 volumio volumio[887]: info: Listing playlists Oct 08 09:44:07 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:44:07 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:11 volumio volumio[887]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/amd64 Oct 08 09:44:13 volumio volumio[887]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/amd64 Oct 08 09:44:13 volumio volumio[887]: info: Folder /tmp/plugins removed Oct 08 09:44:13 volumio volumio[887]: info: Check plugin dependencies Oct 08 09:44:13 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 08 09:44:13 volumio volumio[887]: info: Checking if plugin already exists Oct 08 09:44:13 volumio volumio[887]: info: Rename folder Oct 08 09:44:13 volumio volumio[887]: info: Folder /tmp/downloaded_plugin.zip removed Oct 08 09:44:13 volumio volumio[887]: info: Move to category Oct 08 09:44:13 volumio volumio[887]: info: Checking if install.sh is present Oct 08 09:44:13 volumio volumio[887]: info: Check return the error Error: ENOENT: no such file or directory, stat '/data/plugins/user_interface/now_playing/install.sh' Oct 08 09:44:13 volumio volumio[887]: info: An error occurred installing the plugin. Rolling back config Oct 08 09:44:13 volumio volumio[887]: info: Plugin folders cleanup Oct 08 09:44:13 volumio volumio[887]: info: Scanning into folder /volumio/app/plugins/ Oct 08 09:44:13 volumio volumio[887]: info: Scanning category audio_interface Oct 08 09:44:13 volumio volumio[887]: info: Scanning category miscellanea Oct 08 09:44:13 volumio volumio[887]: info: Scanning category music_service Oct 08 09:44:13 volumio volumio[887]: info: Scanning category plugins.json Oct 08 09:44:13 volumio volumio[887]: info: Scanning category system_controller Oct 08 09:44:13 volumio volumio[887]: info: Scanning category user_interface Oct 08 09:44:13 volumio volumio[887]: info: Scanning into folder /data/plugins/ Oct 08 09:44:13 volumio volumio[887]: info: Scanning category music_service Oct 08 09:44:13 volumio volumio[887]: info: Scanning category system_controller Oct 08 09:44:13 volumio volumio[887]: info: Scanning category user_interface Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/README.md Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/config.json Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/eslintignore Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/eslintrc.yml Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/fixup.sh Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/gitignore Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/install.sh Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/package-lock.json Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/package.json Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/tsconfig.json Oct 08 09:44:13 volumio volumio[887]: info: Removing /data/plugins//user_interface/uninstall.sh Oct 08 09:44:13 volumio volumio[887]: info: Plugin folders cleanup completed Oct 08 09:44:13 volumio volumio[887]: info: Error: Error Oct 08 09:44:13 volumio volumio[887]: info: Folder /tmp/plugins removed Oct 08 09:44:13 volumio volumio[887]: info: Folder /tmp/downloaded_plugin.zip removed Oct 08 09:44:13 volumio volumio[887]: info: Folder /data/temp removed Oct 08 09:44:17 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:44:17 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:17 volumio volumio[887]: info: Listing playlists Oct 08 09:44:17 volumio volumio[887]: info: Listing playlists Oct 08 09:44:27 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:44:27 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:37 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:44:37 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:37 volumio volumio[887]: info: Listing playlists Oct 08 09:44:37 volumio volumio[887]: info: Listing playlists Oct 08 09:44:46 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:44:46 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:47 volumio volumio[887]: info: CoreCommandRouter::volumioGetState Oct 08 09:44:47 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::volumioPause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::stPlaybackTimer Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::servicePause Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::servicePause Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::pause Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand pause Oct 08 09:44:49 volumio volumio[887]: info: Oct 08 09:44:49 volumio volumio[887]: ---------------------------- MPD announces state update: player Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand pause took 2 milliseconds Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::getState Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Oct 08 09:44:49 volumio volumio[887]: info: Oct 08 09:44:49 volumio volumio[887]: ---------------------------- MPD announces state update: player Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::getState Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Oct 08 09:44:49 volumio volumio[887]: info: Oct 08 09:44:49 volumio volumio[887]: ---------------------------- MPD announces state update: player Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::getState Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand status Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand status took 1 milliseconds Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand status took 0 milliseconds Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand status took 0 milliseconds Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::parseState Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::parseState Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::parseState Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 08 09:44:49 volumio volumio[887]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Oct 08 09:44:49 volumio volumio[887]: verbose: ControllerMpd::parseTrackInfo Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::pushState Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::servicePushState Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":237612,"duration":247,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Judith","artist":"A Perfect Circle","album":"Mer de noms","uri":"NAS/TrueNAS/Mer de noms/A Perfect Circle/04_Judith.m4a","trackType":"m4a"} Oct 08 09:44:49 volumio volumio[887]: verbose: CURRENT POSITION 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::syncState stateService pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::pushState Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::stPlaybackTimer Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::pushState Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::servicePushState Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":237612,"duration":247,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Judith","artist":"A Perfect Circle","album":"Mer de noms","uri":"NAS/TrueNAS/Mer de noms/A Perfect Circle/04_Judith.m4a","trackType":"m4a"} Oct 08 09:44:49 volumio volumio[887]: verbose: CURRENT POSITION 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::syncState stateService pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::pushState Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::stPlaybackTimer Oct 08 09:44:49 volumio volumio[887]: info: ControllerMpd::pushState Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::servicePushState Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":237612,"duration":247,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Judith","artist":"A Perfect Circle","album":"Mer de noms","uri":"NAS/TrueNAS/Mer de noms/A Perfect Circle/04_Judith.m4a","trackType":"m4a"} Oct 08 09:44:49 volumio volumio[887]: verbose: CURRENT POSITION 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::syncState stateService pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::syncState currentStatus pause Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::pushState Oct 08 09:44:49 volumio volumio[887]: info: CorePlayQueue::getTrack 302 Oct 08 09:44:49 volumio volumio[887]: info: CoreCommandRouter::volumioPushState Oct 08 09:44:49 volumio volumio[887]: info: CoreStateMachine::stPlaybackTimer Oct 08 09:44:49 volumio volumio[887]: info: ------------------------------ 6ms Oct 08 09:44:49 volumio volumio[887]: info: ------------------------------ 7ms Oct 08 09:44:49 volumio volumio[887]: info: ------------------------------ 6ms Oct 08 09:44:54 volumio volumio[887]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 09:44:54 volumio volumio[887]: TypeError: Cannot read property 'plugin_type' of undefined Oct 08 09:44:54 volumio volumio[887]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Oct 08 09:44:54 volumio volumio[887]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Oct 08 09:44:54 volumio volumio[887]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1328:45) Oct 08 09:44:54 volumio volumio[887]: at Socket.emit (events.js:400:28) Oct 08 09:44:54 volumio volumio[887]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 08 09:44:54 volumio volumio[887]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Oct 08 09:44:54 volumio volumio[887]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 08 09:44:54 volumio sudo[23714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-08 09:43 Oct 08 09:44:54 volumio sudo[23714]: 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="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"