-- Logs begin at Wed 2024-04-24 19:36:17 EEST, end at Wed 2024-04-24 20:56:23 EEST. -- Apr 24 20:55:32 coro ntpd[660]: 83.137.41.14 local addr 192.168.1.243 -> Apr 24 20:55:40 coro volumio[4221]: info: Starting Uninstall of plugin system_hardware - gpio-buttons Apr 24 20:55:40 coro volumio[4221]: info: Uninstalling plugin gpio-buttons Apr 24 20:55:40 coro volumio[4221]: info: Disabling plugin gpio-buttons Apr 24 20:55:40 coro volumio[4221]: info: Checking if uninstall.sh is present Apr 24 20:55:40 coro volumio[4221]: info: Executing uninstall.sh Apr 24 20:55:40 coro sudo[4861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_hardware/gpio-buttons/uninstall.sh Apr 24 20:55:40 coro sudo[4861]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 20:55:40 coro sudo[4861]: pam_unix(sudo:session): session closed for user root Apr 24 20:55:40 coro volumio[4221]: info: Uninstall script completed Apr 24 20:55:40 coro volumio[4221]: info: Removing plugin gpio-buttons from configuration Apr 24 20:55:40 coro volumio[4221]: info: Successfully removed gpio-buttons configuration files Apr 24 20:55:40 coro volumio[4221]: info: Plugin folders cleanup Apr 24 20:55:40 coro volumio[4221]: info: Scanning into folder /volumio/app/plugins/ Apr 24 20:55:40 coro volumio[4221]: info: Scanning category audio_interface Apr 24 20:55:40 coro volumio[4221]: info: Scanning category miscellanea Apr 24 20:55:40 coro volumio[4221]: info: Scanning category music_service Apr 24 20:55:40 coro volumio[4221]: info: Scanning category plugins.json Apr 24 20:55:40 coro volumio[4221]: info: Scanning category system_controller Apr 24 20:55:40 coro volumio[4221]: info: Scanning category user_interface Apr 24 20:55:40 coro volumio[4221]: info: Scanning into folder /data/plugins/ Apr 24 20:55:40 coro volumio[4221]: info: Scanning category music_service Apr 24 20:55:40 coro volumio[4221]: info: Scanning category system_controller Apr 24 20:55:40 coro volumio[4221]: info: Scanning category system_hardware Apr 24 20:55:40 coro volumio[4221]: info: Cleaning folder for gpio-buttons Apr 24 20:55:41 coro volumio[4221]: info: Scanning category user_interface Apr 24 20:55:41 coro volumio[4221]: info: Plugin folders cleanup completed Apr 24 20:55:41 coro volumio[4221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 24 20:55:41 coro volumio[4221]: info: [{"prettyName":"Roon Bridge","name":"RoonBridge","category":"music_service","version":"1.0.0","icon":"fas fa-volume-up","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.0.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Amplifier Switch","name":"ampswitch","category":"system_controller","version":"0.2.0","icon":"fa-power-off","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Music Services Shield","name":"music_services_shield","category":"system_hardware","version":"1.3.1","icon":"fa-shield","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Rotary Encoder II","name":"rotaryencoder2","category":"system_hardware","version":"1.0.22","icon":"fa-circle-o-notch","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"user_interface","version":"2.0.0","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":false,"active":false},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.4.0","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Apr 24 20:55:41 coro volumio[4221]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 24 20:56:02 coro volumio[4221]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 24 20:56:02 coro volumio[4221]: info: Uninstalling plugin ampswitch Apr 24 20:56:02 coro volumio[4221]: info: [ASDebug] Port: 24 Apr 24 20:56:02 coro volumio[4221]: info: [ASDebug] Inverted: false Apr 24 20:56:02 coro volumio[4221]: info: [ASDebug] Delay: 720 Apr 24 20:56:10 coro volumio[4221]: info: Enabling plugin ampswitch Apr 24 20:56:10 coro volumio[4221]: info: Loading plugin "ampswitch"... Apr 24 20:56:10 coro volumio[4221]: info: Applying required configuration parameters for plugin ampswitch Apr 24 20:56:10 coro volumio[4221]: info: PLUGIN START: ampswitch Apr 24 20:56:10 coro volumio[4221]: info: Done. Apr 24 20:56:10 coro volumio[4221]: info: CoreCommandRouter::volumioGetState Apr 24 20:56:10 coro volumio[4221]: info: CorePlayQueue::getTrack 0 Apr 24 20:56:10 coro volumio[4221]: info: [ASDebug] CurState: stop PrevState: stop Apr 24 20:56:10 coro volumio[4221]: info: [ASDebug] CurState: stop PrevState: stop Apr 24 20:56:10 coro volumio[4221]: info: [ASDebug] CurState: stop PrevState: stop Apr 24 20:56:14 coro volumio[4221]: info: Starting Uninstall of plugin system_controller - ampswitch Apr 24 20:56:14 coro volumio[4221]: info: Uninstalling plugin ampswitch Apr 24 20:56:14 coro volumio[4221]: info: [ASDebug] Port: 24 Apr 24 20:56:14 coro volumio[4221]: info: [ASDebug] Inverted: false Apr 24 20:56:14 coro volumio[4221]: info: [ASDebug] Delay: 720 Apr 24 20:56:21 coro volumio[4221]: info: CoreCommandRouter::volumioPlay Apr 24 20:56:21 coro volumio[4221]: info: CoreStateMachine::play index undefined Apr 24 20:56:21 coro volumio[4221]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 20:56:21 coro volumio[4221]: info: CorePlayQueue::getTrack 0 Apr 24 20:56:21 coro volumio[4221]: info: CoreStateMachine::startPlaybackTimer Apr 24 20:56:21 coro volumio[4221]: info: CorePlayQueue::getTrack 0 Apr 24 20:56:21 coro volumio[4221]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 20:56:21 coro volumio[4221]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 20:56:21 coro volumio[4221]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 20:56:21 coro volumio[4221]: info: [1713981381018] ControllerWebradio::clearAddPlayTrack Apr 24 20:56:21 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 20:56:21 coro volumio[4221]: info: sendMpdCommand stop took 2 milliseconds Apr 24 20:56:21 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 20:56:21 coro volumio[4221]: info: Apr 24 20:56:21 coro volumio[4221]: ---------------------------- MPD announces system playlist update Apr 24 20:56:21 coro volumio[4221]: info: Ignoring MPD Status Update Apr 24 20:56:21 coro volumio[4221]: info: sendMpdCommand clear took 8 milliseconds Apr 24 20:56:21 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand load "http://online.kissfm.ua/KissFM_Digital" Apr 24 20:56:21 coro volumio[4221]: error: updateQueue error: null Apr 24 20:56:21 coro volumio[4221]: info: ------------------------------ 9ms Apr 24 20:56:21 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand add "http://online.kissfm.ua/KissFM_Digital" Apr 24 20:56:21 coro volumio[4221]: info: Apr 24 20:56:21 coro volumio[4221]: ---------------------------- MPD announces system playlist update Apr 24 20:56:21 coro volumio[4221]: info: Ignoring MPD Status Update Apr 24 20:56:21 coro volumio[4221]: info: sendMpdCommand add "http://online.kissfm.ua/KissFM_Digital" took 4 milliseconds Apr 24 20:56:21 coro volumio[4221]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 20:56:21 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand play Apr 24 20:56:21 coro volumio[4221]: info: ------------------------------ 12ms Apr 24 20:56:21 coro volumio[4221]: info: sendMpdCommand play took 6 milliseconds Apr 24 20:56:22 coro volumio[4221]: info: Apr 24 20:56:22 coro volumio[4221]: ---------------------------- MPD announces state update: player Apr 24 20:56:22 coro volumio[4221]: info: ControllerMpd::getState Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand status Apr 24 20:56:22 coro volumio[4221]: info: Apr 24 20:56:22 coro volumio[4221]: ---------------------------- MPD announces system playlist update Apr 24 20:56:22 coro volumio[4221]: info: Ignoring MPD Status Update Apr 24 20:56:22 coro volumio[4221]: info: Apr 24 20:56:22 coro volumio[4221]: ---------------------------- MPD announces state update: player Apr 24 20:56:22 coro volumio[4221]: info: sendMpdCommand status took 42 milliseconds Apr 24 20:56:22 coro volumio[4221]: info: ControllerMpd::getState Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand status Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::parseState Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 20:56:22 coro volumio[4221]: info: Apr 24 20:56:22 coro volumio[4221]: ---------------------------- MPD announces system playlist update Apr 24 20:56:22 coro volumio[4221]: info: Ignoring MPD Status Update Apr 24 20:56:22 coro volumio[4221]: info: Apr 24 20:56:22 coro volumio[4221]: ---------------------------- MPD announces state update: player Apr 24 20:56:22 coro volumio[4221]: info: ------------------------------ 26ms Apr 24 20:56:22 coro volumio[4221]: info: sendMpdCommand status took 13 milliseconds Apr 24 20:56:22 coro volumio[4221]: info: ControllerMpd::getState Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand status Apr 24 20:56:22 coro volumio[4221]: info: sendMpdCommand playlistinfo took 13 milliseconds Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::parseState Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::parseTrackInfo Apr 24 20:56:22 coro volumio[4221]: info: ControllerMpd::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::servicePushState Apr 24 20:56:22 coro volumio[4221]: info: CorePlayQueue::getTrack 0 Apr 24 20:56:22 coro volumio[4221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Wassermann - W.I.R. (Dj Hell Mix)","artist":"Kiss FM Digital","album":null,"uri":"http://online.kissfm.ua/KissFM_Digital","trackType":""} Apr 24 20:56:22 coro volumio[4221]: verbose: CURRENT POSITION 0 Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::syncState stateService play Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::syncState currentStatus stop Apr 24 20:56:22 coro volumio[4221]: info: ------------------------------ 82ms Apr 24 20:56:22 coro volumio[4221]: info: ------------------------------ 24ms Apr 24 20:56:22 coro volumio[4221]: info: sendMpdCommand status took 16 milliseconds Apr 24 20:56:22 coro volumio[4221]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::parseState Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::parseTrackInfo Apr 24 20:56:22 coro volumio[4221]: info: ControllerMpd::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::servicePushState Apr 24 20:56:22 coro volumio[4221]: info: CorePlayQueue::getTrack 0 Apr 24 20:56:22 coro volumio[4221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":377,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wassermann - W.I.R. (Dj Hell Mix)","artist":"Kiss FM Digital","album":null,"uri":"http://online.kissfm.ua/KissFM_Digital","trackType":""} Apr 24 20:56:22 coro volumio[4221]: verbose: CURRENT POSITION 0 Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::syncState stateService play Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::syncState currentStatus play Apr 24 20:56:22 coro volumio[4221]: info: Received an update from plugin. extracting info from payload Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::volumioPushState Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output update for this device Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::volumioPushState Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output update for this device Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output Apr 24 20:56:22 coro volumio[4221]: info: ------------------------------ 172ms Apr 24 20:56:22 coro volumio[4221]: info: sendMpdCommand playlistinfo took 126 milliseconds Apr 24 20:56:22 coro volumio[4221]: verbose: ControllerMpd::parseTrackInfo Apr 24 20:56:22 coro volumio[4221]: info: ControllerMpd::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::servicePushState Apr 24 20:56:22 coro volumio[4221]: info: CorePlayQueue::getTrack 0 Apr 24 20:56:22 coro volumio[4221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Wassermann - W.I.R. (Dj Hell Mix)","artist":"Kiss FM Digital","album":null,"uri":"http://online.kissfm.ua/KissFM_Digital","trackType":""} Apr 24 20:56:22 coro volumio[4221]: verbose: CURRENT POSITION 0 Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::syncState stateService play Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::syncState currentStatus play Apr 24 20:56:22 coro volumio[4221]: info: Received an update from plugin. extracting info from payload Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::volumioPushState Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output update for this device Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output Apr 24 20:56:22 coro volumio[4221]: info: CoreStateMachine::pushState Apr 24 20:56:22 coro volumio[4221]: info: CoreCommandRouter::volumioPushState Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output update for this device Apr 24 20:56:22 coro volumio[4221]: info: MRS: Pushing multiroomSync output Apr 24 20:56:22 coro volumio[4221]: info: ------------------------------ 200ms Apr 24 20:56:22 coro volumio[4221]: info: [ASDebug] CurState: play PrevState: stop Apr 24 20:56:22 coro volumio[4221]: info: [ASDebug] Togle GPIO: ON Apr 24 20:56:22 coro volumio[4221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 20:56:22 coro volumio[4221]: Error: ESPIPE: invalid seek, write Apr 24 20:56:22 coro volumio[4221]: at Object.writeSync (fs.js:711:3) Apr 24 20:56:22 coro volumio[4221]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 24 20:56:22 coro volumio[4221]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:199:23) Apr 24 20:56:22 coro volumio[4221]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:182:86) Apr 24 20:56:22 coro volumio[4221]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 24 20:56:22 coro volumio[4221]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Apr 24 20:56:22 coro volumio[4221]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Apr 24 20:56:22 coro volumio[4221]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 24 20:56:22 coro volumio[4221]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 24 20:56:22 coro volumio[4221]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Apr 24 20:56:22 coro volumio[4221]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 24 20:56:22 coro volumio[4221]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 24 20:56:22 coro volumio[4221]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:251:12) Apr 24 20:56:22 coro volumio[4221]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Apr 24 20:56:22 coro volumio[4221]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 24 20:56:22 coro volumio[4221]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Apr 24 20:56:22 coro volumio[4221]: errno: -29, Apr 24 20:56:22 coro volumio[4221]: syscall: 'write', Apr 24 20:56:22 coro volumio[4221]: code: 'ESPIPE' Apr 24 20:56:22 coro volumio[4221]: } Apr 24 20:56:22 coro volumio[4221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 20:56:23 coro sudo[4940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-24 20:55 Apr 24 20:56:23 coro sudo[4940]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"