-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Tue 2025-01-14 07:51:25 GMT. -- Jan 14 07:50:01 volumio-pi4 systemd[1]: man-db.service: Succeeded. Jan 14 07:50:01 volumio-pi4 systemd[1]: Started Daily man-db regeneration. Jan 14 07:50:01 volumio-pi4 systemd[1]: apt-daily.service: Succeeded. Jan 14 07:50:01 volumio-pi4 systemd[1]: Started Daily apt download activities. Jan 14 07:50:01 volumio-pi4 systemd[1]: Starting Daily apt upgrade and clean activities... Jan 14 07:50:01 volumio-pi4 volumio[1086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 07:50:02 volumio-pi4 systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 14 07:50:02 volumio-pi4 systemd[1]: Started Daily apt upgrade and clean activities. Jan 14 07:50:04 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 07:50:04 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 07:50:04 volumio-pi4 volumio[1086]: info: Discovery: Getting this device information Jan 14 07:50:04 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioGetState Jan 14 07:50:04 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:04 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: A device disappeared from network Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: Device volumio-pi4 disappeared from network Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: A device disappeared from network Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: adding f96c414a-a7b2-4af3-ad10-bbf546dc985f Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: Found device Volumio-pi4 Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioGetState Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: this is already registered, f96c414a-a7b2-4af3-ad10-bbf546dc985f Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: Discovery: Found device Volumio-pi4 Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioGetState Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: MRS: Found cast device: SHIELD-Android-TV-0baa3382d84bfae17d6f54acc93c5b5a Jan 14 07:50:05 volumio-pi4 volumio[1086]: info: MRS: Found cast device: SHIELD-Android-TV-0baa3382d84bfae17d6f54acc93c5b5a Jan 14 07:50:09 volumio-pi4 volumio[1086]: info: CALLMETHOD: user_interface randomizer saveSettings [object Object] Jan 14 07:50:09 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: randomizer , saveSettings Jan 14 07:50:11 volumio-pi4 volumio-time-update[641]: volumio-time-update-util: Setting system time to: 2025-01-14 07:48:53 Jan 14 07:50:11 volumio-pi4 sudo[2328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-01-14 07:48:53 Jan 14 07:50:11 volumio-pi4 sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 07:50:11 volumio-pi4 dbus-daemon[642]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.55' (uid=0 pid=2329 comm="timedatectl set-time 2025-01-14 07:48:53 ") Jan 14 07:50:12 volumio-pi4 systemd[1]: Starting Time & Date Service... Jan 14 07:50:12 volumio-pi4 volumio[1086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 07:50:12 volumio-pi4 dbus-daemon[642]: [system] Successfully activated service 'org.freedesktop.timedate1' Jan 14 07:50:12 volumio-pi4 systemd[1]: Started Time & Date Service. Jan 14 07:50:08 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 14 07:50:08 volumio-pi4 volumio[1086]: info: In handleBrowseUri, curUri=spotify Jan 14 07:50:08 volumio-pi4 volumio[1086]: info: New access token = BQDgPXYFduqxLetXONil0uXyyNmitkik8DXneQfSzlFGyMs-GAxg-Z_4yqhqsk7JurSuuB_ygSA1lm_0yXfxU39NM6SzkBS4m2bReev5UAxHHJ2moEHjhje7bsnaJfIsdROYAms3RqhYpBikA4hIgRUiQYK1DCoYhLmkWsI9tFXaUm4i_O5B9smImMAlV0L72m-bM9oE1LkJBmpxY-zvwBZdPMocP_02qQxbZx6C9CchXUGn0prl Jan 14 07:50:08 volumio-pi4 volumio[1086]: info: New access token = BQDON0xJe_Vf7yKd378JTmoRDSy5Ec0UzdEGWJA6ABL6G5INe98HoiKG-FUBiQj4LqMON-_5t5_JGcU63D8sLEz7A4RoaIWdlW3EeJwA9QZ530CP86rnnYIrJFFX5Nv8OTkTUj63EaKplaC9OjduJKQJ8JorvuD_v3latxKNah62zs1XhhHkXAZ92xQXD8ujVFHeHtCN2Vcm4MUXaehl-Cm7Wa3tMU3-LGCJe5xQLjoIpQq4xw2y Jan 14 07:50:08 volumio-pi4 volumio[1086]: info: New access token = BQCQWbLeLNhgJUK50DjoV-w9wN3coXU3VnratWq6y-AUbmKYkHLcH8NAjQY5gC6wGI2SBs8uY05umCoKh9G_f5DpJN9aK51Wx3pa1JNl2vpFCsgZ6cOeSnG3hW3G1hNrQdBPjbvc8sQzYFWUiKZJa7L1qzCCCSmE15qP5XidGDNZ3yCYQ91NebSj7l-dRov15zcSyDcWBjsfCKdU0w4ZhKUfEcrLRfeXryaBlvNXjoVPyF3blKHW Jan 14 07:50:09 volumio-pi4 volumio[1086]: info: Preload queue cleared Jan 14 07:50:09 volumio-pi4 volumio[1086]: info: Preload queue cleared Jan 14 07:50:09 volumio-pi4 volumio[1086]: info: Preload queue cleared Jan 14 07:50:09 volumio-pi4 volumio[1086]: info: Preload queue cleared Jan 14 07:50:12 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 14 07:50:12 volumio-pi4 volumio[1086]: info: In handleBrowseUri, curUri=spotify/playlists Jan 14 07:50:13 volumio-pi4 volumio[1086]: info: Preload queue cleared Jan 14 07:50:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 07:50:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 07:50:15 volumio-pi4 volumio[1086]: info: Discovery: Getting this device information Jan 14 07:50:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioGetState Jan 14 07:50:15 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 07:50:30 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioPlay Jan 14 07:50:30 volumio-pi4 volumio[1086]: info: CoreStateMachine::play index undefined Jan 14 07:50:30 volumio-pi4 volumio[1086]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 07:50:30 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:33 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioPlay Jan 14 07:50:33 volumio-pi4 volumio[1086]: info: CoreStateMachine::play index undefined Jan 14 07:50:33 volumio-pi4 volumio[1086]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 14 07:50:33 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:40 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 07:50:40 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Discovery: Getting this device information Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioGetState Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Starting Uninstall of plugin music_service - spop Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Uninstalling plugin spop Jan 14 07:50:45 volumio-pi4 go-librespot[2503]: time="2025-01-14T07:50:45Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 14 07:50:45 volumio-pi4 volumio[1086]: Cannot find translation for source Randomizer Jan 14 07:50:45 volumio-pi4 sudo[2671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Jan 14 07:50:45 volumio-pi4 sudo[2671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Disabling plugin spop Jan 14 07:50:45 volumio-pi4 systemd[1]: Stopping go-librespot Daemon... Jan 14 07:50:45 volumio-pi4 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Checking if uninstall.sh is present Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Connection to go-librespot Websocket closed Jan 14 07:50:45 volumio-pi4 systemd[1]: go-librespot-daemon.service: Succeeded. Jan 14 07:50:45 volumio-pi4 systemd[1]: Stopped go-librespot Daemon. Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Executing uninstall.sh Jan 14 07:50:45 volumio-pi4 sudo[2671]: pam_unix(sudo:session): session closed for user root Jan 14 07:50:45 volumio-pi4 volumio[1086]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Connection to go-librespot Websocket closed Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Connection to go-librespot Websocket closed Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Connection to go-librespot Websocket closed Jan 14 07:50:45 volumio-pi4 sudo[2675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/uninstall.sh Jan 14 07:50:45 volumio-pi4 sudo[2675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 14 07:50:45 volumio-pi4 sudo[2675]: pam_unix(sudo:session): session closed for user root Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Uninstall script completed Jan 14 07:50:45 volumio-pi4 volumio[1086]: info: Removing plugin spop from configuration Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Successfully removed spop configuration files Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Plugin folders cleanup Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning into folder /volumio/app/plugins/ Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category audio_interface Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category miscellanea Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category music_service Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category plugins.json Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category system_controller Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category user_interface Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning into folder /data/plugins/ Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category music_service Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Cleaning folder for spop Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category system_controller Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Scanning category user_interface Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: Plugin folders cleanup completed Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: [{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.1","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Peppy-meter-Basic","name":"peppymeterbasic","category":"user_interface","version":"0.0.1","icon":"fa-bar-chart","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Randomizer","name":"randomizer","category":"user_interface","version":"3.1.2","icon":"fa-random","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Jan 14 07:50:46 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: [ASDebug] Setting UI defaults Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: [ASDebug] Port: 24 Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: [ASDebug] Inverted: false Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: [ASDebug] Latched: false Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: [ASDebug] On pulse width: 500 Jan 14 07:51:00 volumio-pi4 volumio[1086]: info: [ASDebug] Off pulse width: 500 Jan 14 07:51:12 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 14 07:51:12 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 14 07:51:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 14 07:51:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 14 07:51:15 volumio-pi4 volumio[1086]: info: Discovery: Getting this device information Jan 14 07:51:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::volumioGetState Jan 14 07:51:15 volumio-pi4 volumio[1086]: info: CorePlayQueue::getTrack 0 Jan 14 07:51:15 volumio-pi4 volumio[1086]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 14 07:51:17 volumio-pi4 volumio[1086]: info: Starting Uninstall of plugin system_controller - ampswitch Jan 14 07:51:17 volumio-pi4 volumio[1086]: info: Uninstalling plugin ampswitch Jan 14 07:51:17 volumio-pi4 volumio[1086]: info: [ASDebug] Port: 24 Jan 14 07:51:17 volumio-pi4 volumio[1086]: info: [ASDebug] Inverted: false Jan 14 07:51:17 volumio-pi4 volumio[1086]: info: [ASDebug] Delay: 720 Jan 14 07:51:25 volumio-pi4 volumio[1086]: info: [ASDebug] Port: 24 Jan 14 07:51:25 volumio-pi4 volumio[1086]: info: [ASDebug] Inverted: false Jan 14 07:51:25 volumio-pi4 volumio[1086]: info: [ASDebug] Delay: 720 Jan 14 07:51:25 volumio-pi4 volumio[1086]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 07:51:25 volumio-pi4 volumio[1086]: Error: EBADF: bad file descriptor, close Jan 14 07:51:25 volumio-pi4 volumio[1086]: at Object.closeSync (fs.js:439:3) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1550:29) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at Socket.emit (events.js:315:20) Jan 14 07:51:25 volumio-pi4 volumio[1086]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 14 07:51:25 volumio-pi4 volumio[1086]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jan 14 07:51:25 volumio-pi4 volumio[1086]: errno: -9, Jan 14 07:51:25 volumio-pi4 volumio[1086]: syscall: 'close', Jan 14 07:51:25 volumio-pi4 volumio[1086]: code: 'EBADF' Jan 14 07:51:25 volumio-pi4 volumio[1086]: } Jan 14 07:51:25 volumio-pi4 volumio[1086]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 14 07:51:25 volumio-pi4 sudo[2805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-14 07:50 Jan 14 07:51:25 volumio-pi4 sudo[2805]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"