-- Logs begin at Fri 2024-07-26 12:55:26 UTC, end at Fri 2024-07-26 13:03:14 UTC. -- Jul 26 13:02:01 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:02:10 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:02:11 volumio volumio[1187]: info: Jul 26 13:02:12 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Jul 26 13:02:12 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:02:12 volumio volumio[1187]: info: Ignoring MPD Status Update Jul 26 13:02:12 volumio volumio[1187]: info: Jul 26 13:02:12 volumio volumio[1187]: ---------------------------- MPD announces state update: player Jul 26 13:02:12 volumio volumio[1187]: info: ControllerMpd::getState Jul 26 13:02:12 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:02:12 volumio volumio[1187]: info: ------------------------------ 360ms Jul 26 13:02:12 volumio volumio[1187]: verbose: ControllerMpd::parseState Jul 26 13:02:12 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:02:12 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:02:12 volumio volumio[1187]: info: ControllerMpd::pushState Jul 26 13:02:12 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 26 13:02:12 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:02:12 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"59 Kbps","isStreaming":false,"title":"Radio ZU","artist":"Radio ZU Live","album":null,"uri":"http://zuicast.digitalag.ro:9420/zu","trackType":"ro:9420/zu"} Jul 26 13:02:12 volumio volumio[1187]: verbose: CURRENT POSITION 5 Jul 26 13:02:12 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Jul 26 13:02:12 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:02:12 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Jul 26 13:02:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 26 13:02:12 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:02:12 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:02:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:02:12 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:02:12 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:02:12 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:02:12 volumio volumio[1187]: info: ------------------------------ 440ms Jul 26 13:02:13 volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 26 13:02:13 volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 26 13:02:19 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:02:21 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:02:22 volumio volumio[1187]: info: Jul 26 13:02:22 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Jul 26 13:02:22 volumio volumio[1187]: info: Ignoring MPD Status Update Jul 26 13:02:22 volumio volumio[1187]: info: Jul 26 13:02:22 volumio volumio[1187]: ---------------------------- MPD announces state update: player Jul 26 13:02:22 volumio volumio[1187]: info: ControllerMpd::getState Jul 26 13:02:22 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:02:22 volumio volumio[1187]: info: ------------------------------ 77ms Jul 26 13:02:22 volumio volumio[1187]: verbose: ControllerMpd::parseState Jul 26 13:02:22 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:02:22 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:02:22 volumio volumio[1187]: info: ControllerMpd::pushState Jul 26 13:02:22 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 26 13:02:22 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:02:22 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":37709,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Radio ZU","artist":"Radio ZU Live","album":null,"uri":"http://zuicast.digitalag.ro:9420/zu","trackType":"ro:9420/zu"} Jul 26 13:02:22 volumio volumio[1187]: verbose: CURRENT POSITION 5 Jul 26 13:02:22 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Jul 26 13:02:22 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:02:22 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Jul 26 13:02:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 26 13:02:22 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:02:22 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:02:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:02:22 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:02:22 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:02:22 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:02:22 volumio volumio[1187]: info: ------------------------------ 442ms Jul 26 13:02:22 volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 26 13:02:22 volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 26 13:02:28 volumio volumio[1187]: info: [ASDebug] Port: 24 Jul 26 13:02:28 volumio volumio[1187]: info: [ASDebug] Inverted: false Jul 26 13:02:28 volumio volumio[1187]: info: [ASDebug] Delay: 720 Jul 26 13:02:29 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:02:31 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:02:36 volumio volumio[1187]: info: Starting Uninstall of plugin user_interface - rotaryencoder Jul 26 13:02:36 volumio volumio[1187]: info: Uninstalling plugin rotaryencoder Jul 26 13:02:36 volumio volumio[1187]: info: Disabling plugin rotaryencoder Jul 26 13:02:36 volumio volumio[1187]: info: Checking if uninstall.sh is present Jul 26 13:02:36 volumio volumio[1187]: info: Executing uninstall.sh Jul 26 13:02:39 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:02:40 volumio sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/rotaryencoder/uninstall.sh Jul 26 13:02:40 volumio sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 26 13:02:40 volumio sudo[1716]: pam_unix(sudo:session): session closed for user root Jul 26 13:02:40 volumio volumio[1187]: info: Uninstall script completed Jul 26 13:02:40 volumio volumio[1187]: info: Removing plugin rotaryencoder from configuration Jul 26 13:02:41 volumio volumio[1187]: info: Successfully removed rotaryencoder configuration files Jul 26 13:02:41 volumio volumio[1187]: info: Plugin folders cleanup Jul 26 13:02:41 volumio volumio[1187]: info: Scanning into folder /volumio/app/plugins/ Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category audio_interface Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category miscellanea Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category music_service Jul 26 13:02:41 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category plugins.json Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category system_controller Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category user_interface Jul 26 13:02:41 volumio volumio[1187]: info: Scanning into folder /data/plugins/ Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category miscellanea Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category system_controller Jul 26 13:02:41 volumio volumio[1187]: info: Scanning category user_interface Jul 26 13:02:41 volumio volumio[1187]: info: Cleaning folder for rotaryencoder Jul 26 13:02:44 volumio volumio[1187]: info: Plugin folders cleanup completed Jul 26 13:02:44 volumio volumio[1187]: info: [{"prettyName":"Amplifier Switch","name":"ampswitch","category":"miscellanea","version":"0.1.2","icon":"fa-power-off","enabled":true,"active":false},{"prettyName":"Autoplay","name":"auto_play","category":"miscellanea","version":"1.0.0","icon":"fa-play-circle-o","enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_controller","version":"0.8.1","icon":"fa-microchip","enabled":true,"active":true}] Jul 26 13:02:48 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:02:50 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:02:52 volumio volumio[1187]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 26 13:02:58 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:02:58 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jul 26 13:03:00 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:03:00 volumio volumio[1187]: info: Jul 26 13:03:00 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Jul 26 13:03:00 volumio volumio[1187]: info: Ignoring MPD Status Update Jul 26 13:03:00 volumio volumio[1187]: info: Jul 26 13:03:00 volumio volumio[1187]: ---------------------------- MPD announces state update: player Jul 26 13:03:00 volumio volumio[1187]: info: ControllerMpd::getState Jul 26 13:03:00 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:03:00 volumio volumio[1187]: info: ------------------------------ 54ms Jul 26 13:03:00 volumio volumio[1187]: verbose: ControllerMpd::parseState Jul 26 13:03:00 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:03:00 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:03:00 volumio volumio[1187]: info: ControllerMpd::pushState Jul 26 13:03:00 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 26 13:03:00 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:00 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":76463,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"50 Kbps","isStreaming":false,"title":"Radio ZU","artist":"Radio ZU Live","album":null,"uri":"http://zuicast.digitalag.ro:9420/zu","trackType":"ro:9420/zu"} Jul 26 13:03:00 volumio volumio[1187]: verbose: CURRENT POSITION 5 Jul 26 13:03:00 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Jul 26 13:03:00 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Jul 26 13:03:00 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Jul 26 13:03:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jul 26 13:03:00 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:03:00 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:03:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:03:00 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:03:00 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:03:01 volumio volumio[1187]: info: ------------------------------ 364ms Jul 26 13:03:01 volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 26 13:03:01 volumio volumio[1187]: info: [ASDebug] CurState: play PrevState: play Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::volumioAddQueueItems Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Jul 26 13:03:01 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Jul 26 13:03:01 volumio volumio[1187]: info: Adding Item to queue: http://zuicast.digitalag.ro:9420/zu.m3u Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jul 26 13:03:01 volumio volumio[1187]: info: CorePlayQueue::saveQueue Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Jul 26 13:03:01 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Jul 26 13:03:01 volumio volumio[1187]: UNSET VOLATILE Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::play index 5 Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::stop Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:03:01 volumio volumio[1187]: UNSET VOLATILE Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::stPlaybackTimer Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Jul 26 13:03:01 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:03:01 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:03:01 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:03:01 volumio volumio[1187]: info: CoreStateMachine::serviceStop Jul 26 13:03:01 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:02 volumio volumio[1187]: info: CoreCommandRouter::serviceStop Jul 26 13:03:02 volumio volumio[1187]: info: [1721998982015] ControllerWebradio::stop Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 13:03:02 volumio volumio[1187]: info: Jul 26 13:03:02 volumio volumio[1187]: ---------------------------- MPD announces state update: player Jul 26 13:03:02 volumio volumio[1187]: info: ControllerMpd::getState Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Jul 26 13:03:02 volumio volumio[1187]: info: CoreStateMachine::play index undefined Jul 26 13:03:02 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 26 13:03:02 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:02 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Jul 26 13:03:02 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:02 volumio volumio[1187]: info: [1721998982357] ControllerWebradio::clearAddPlayTrack Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Jul 26 13:03:02 volumio volumio[1187]: info: [ASDebug] CurState: stop PrevState: play Jul 26 13:03:02 volumio volumio[1187]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::parseState Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Jul 26 13:03:02 volumio volumio[1187]: info: Jul 26 13:03:02 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Jul 26 13:03:02 volumio volumio[1187]: info: Ignoring MPD Status Update Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand load "http://zuicast.digitalag.ro:9420/zu.m3u" Jul 26 13:03:02 volumio volumio[1187]: info: ControllerMpd::pushState Jul 26 13:03:02 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Jul 26 13:03:02 volumio volumio[1187]: info: CoreStateMachine::pushState Jul 26 13:03:02 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:02 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Jul 26 13:03:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 26 13:03:02 volumio volumio[1187]: info: CorePlayQueue::getTrack 5 Jul 26 13:03:02 volumio volumio[1187]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 26 13:03:02 volumio volumio[1187]: info: ------------------------------ 445ms Jul 26 13:03:02 volumio volumio[1187]: error: updateQueue error: null Jul 26 13:03:02 volumio volumio[1187]: info: ------------------------------ 176ms Jul 26 13:03:02 volumio volumio[1187]: info: [ASDebug] CurState: stop PrevState: play Jul 26 13:03:02 volumio volumio[1187]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 26 13:03:02 volumio volumio[1187]: info: Jul 26 13:03:02 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Jul 26 13:03:02 volumio volumio[1187]: info: Ignoring MPD Status Update Jul 26 13:03:02 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 26 13:03:02 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Jul 26 13:03:03 volumio volumio[1187]: info: ------------------------------ 83ms Jul 26 13:03:03 volumio volumio[1187]: info: [ASDebug] Togle GPIO: OFF Jul 26 13:03:03 volumio volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:03:03 volumio volumio[1187]: { Error: EBADF: bad file descriptor, write Jul 26 13:03:03 volumio volumio[1187]: at Object.fs.writeSync (fs.js:735:20) Jul 26 13:03:03 volumio volumio[1187]: at Gpio.writeSync (/data/plugins/miscellanea/ampswitch/node_modules/onoff/onoff.js:212:6) Jul 26 13:03:03 volumio volumio[1187]: at AmpSwitchController.off (/data/plugins/miscellanea/ampswitch/index.js:209:23) Jul 26 13:03:03 volumio volumio[1187]: at Timeout._onTimeout (/data/plugins/miscellanea/ampswitch/index.js:185:88) Jul 26 13:03:03 volumio volumio[1187]: at ontimeout (timers.js:482:11) Jul 26 13:03:03 volumio volumio[1187]: at tryOnTimeout (timers.js:317:5) Jul 26 13:03:03 volumio volumio[1187]: at Timer.listOnTimeout (timers.js:277:5) errno: -9, code: 'EBADF', syscall: 'write' } Jul 26 13:03:03 volumio volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 26 13:03:08 volumio kernel: RTL871X: nolinked power save leave Jul 26 13:03:10 volumio kernel: RTL871X: nolinked power save enter Jul 26 13:03:14 volumio sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-26 13:02 Jul 26 13:03:14 volumio sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="b9cd485c2d7861a79d18f42ff0fdb052338b2239" VOLUMIO_FE_VERSION="2932a2aa65665ab9ec21cd0bdf3d21669bbbf643" VOLUMIO_BE_VERSION="8583c814bdc88e8c28c10464bec8226bce2b0634" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon May 25 18:53:02 CEST 2020" VOLUMIO_VERSION="2.777" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c75975235b22f56393d7c4516aa10590"