-- Logs begin at Mon 2025-02-17 21:30:29 KST, end at Tue 2025-02-18 13:34:49 KST. -- Feb 18 13:33:04 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:33:04 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:33:14 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:33:14 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:33:14 volumio volumio[16226]: info: Listing playlists Feb 18 13:33:14 volumio volumio[16226]: info: Listing playlists Feb 18 13:33:24 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:33:24 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:33:34 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:33:34 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:33:34 volumio volumio[16226]: info: Listing playlists Feb 18 13:33:34 volumio volumio[16226]: info: Listing playlists Feb 18 13:33:44 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:33:44 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:33:54 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:33:54 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:33:54 volumio volumio[16226]: info: Listing playlists Feb 18 13:33:54 volumio volumio[16226]: info: Listing playlists Feb 18 13:34:04 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:34:04 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:34:14 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:34:14 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:34:14 volumio volumio[16226]: info: Listing playlists Feb 18 13:34:14 volumio volumio[16226]: info: Listing playlists Feb 18 13:34:24 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:34:24 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:34:28 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:34:28 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:34:31 volumio volumio[16226]: info: CoreCommandRouter::volumioPause Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::pause Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::stPlaybackTimer Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::servicePause Feb 18 13:34:31 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:34:31 volumio volumio[16226]: info: CoreCommandRouter::servicePause Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 18 13:34:31 volumio volumio[16226]: info: ControllerMpd::pause Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::sendMpdCommand pause Feb 18 13:34:31 volumio volumio[16226]: info: sendMpdCommand pause took 2 milliseconds Feb 18 13:34:31 volumio volumio[16226]: info: ControllerMpd::getState Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::sendMpdCommand status Feb 18 13:34:31 volumio volumio[16226]: info: Feb 18 13:34:31 volumio volumio[16226]: ---------------------------- MPD announces state update: player Feb 18 13:34:31 volumio volumio[16226]: info: ControllerMpd::getState Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::sendMpdCommand status Feb 18 13:34:31 volumio volumio[16226]: info: sendMpdCommand status took 4 milliseconds Feb 18 13:34:31 volumio volumio[16226]: info: sendMpdCommand status took 3 milliseconds Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::parseState Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::parseState Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 18 13:34:31 volumio volumio[16226]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 18 13:34:31 volumio volumio[16226]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::parseTrackInfo Feb 18 13:34:31 volumio volumio[16226]: verbose: ControllerMpd::parseTrackInfo Feb 18 13:34:31 volumio volumio[16226]: info: ControllerMpd::pushState Feb 18 13:34:31 volumio volumio[16226]: info: CoreCommandRouter::servicePushState Feb 18 13:34:31 volumio volumio[16226]: info: CorePlayQueue::getTrack 3 Feb 18 13:34:31 volumio volumio[16226]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":155222,"duration":210,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Put Your Records On","artist":"Ritt Momney","album":"Put Your Records On","uri":"https://rr3---sn-n3cgv5qc5oq-bh26r.googlevideo.com/videoplayback?expire=1739874711&ei=Nw20Z9m6B5KT1d8PyIOV6AY&ip=211.207.105.113&id=o-AL9PtvNRIHesHW6eWEJW91XZZKf9Np9FBCCHJIGLWVZY&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1739853111%2C&mh=uv&mm=31%2C26&mn=sn-n3cgv5qc5oq-bh26r%2Csn-3pm7kn76&ms=au%2Conr&mv=m&mvi=3&pl=21&rms=au%2Cau&ctier=A&pfa=5&gcr=kr&initcwndbps=3852500&hightc=yes&siu=1&bui=AUWDL3wy7mS1svAj2DYiBD3-IpPjPSe-AYtdpUXWJq7ewdRd73cBXoxIvCOhHc3KkrbIVTBd-w&spc=RjZbSQyOhWWHOTXAELIQQM_YNz2i2cpv7wZViPFOuFMUxq5PIjZpG52w_FeuUn6TJnIjpGpw0t6k&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=7QSL_IrNeDv5PJLexqyGoysQ&rqh=1&gir=yes&clen=6993289&dur=210.481&lmt=1714521650337183&mt=1739852715&fvip=4&keepalive=yes&fexp=51326932&c=WEB_REMIX&sefc=1&txp=5532434&n=3ahSnpORrZLj6g&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=AGluJ3MwRQIgQmeSNbJlPyfoaAjP00d_4zKPm4J9YmhUcgrQ359icqkCIQDWrh5los8nwIeYIzvl-Lj_IqLdNn_Qn6uqiSacavJf5w%3D%3D&sig=AJfQdSswRQIhAOKtoU-PthNs51bMAyOs0f9_c1us650CcqCjKIGVYB8IAiA9-COuCgNRityyQqmZC8pMKL3hc9lBFmNrHX-7WTQc7A%3D%3D&pot=MnhKmaF7jVXAUkqln1pMIeGRByjKHuGjSdFuUZhXKLZIjyv5axN04OOEfHxzscRzY0UA24FXdkgPuatF74t73NZk78shuAbmLlg1BLjupLoQJW58gNQFo18M6U9SpOxcTB52vuEsEmHvHTM7P-X5nYpuAy9n9U5Me8o%3D&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Feb 18 13:34:31 volumio volumio[16226]: verbose: CURRENT POSITION 3 Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::syncState stateService pause Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::syncState currentStatus pause Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::pushState Feb 18 13:34:31 volumio volumio[16226]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 13:34:31 volumio volumio[16226]: info: CoreCommandRouter::volumioPushState Feb 18 13:34:31 volumio volumio[16226]: info: CoreStateMachine::stPlaybackTimer Feb 18 13:34:31 volumio volumio[16226]: info: ------------------------------ 25ms Feb 18 13:34:31 volumio volumio[16226]: info: [ASDebug] CurState: pause PrevState: play Feb 18 13:34:31 volumio volumio[16226]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 18 13:34:31 volumio volumio[16226]: info: [ASDebug] Togle GPIO: OFF Feb 18 13:34:34 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:34:34 volumio volumio[16226]: info: Listing playlists Feb 18 13:34:34 volumio volumio[16226]: info: Listing playlists Feb 18 13:34:36 volumio volumio[16226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 18 13:34:36 volumio volumio[16226]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 18 13:34:37 volumio volumio[16226]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Feb 18 13:34:43 volumio volumio[16226]: info: [ASDebug] Port: 24 Feb 18 13:34:43 volumio volumio[16226]: info: [ASDebug] Inverted: false Feb 18 13:34:43 volumio volumio[16226]: info: [ASDebug] Delay: 720 Feb 18 13:34:44 volumio volumio[16226]: info: CoreCommandRouter::volumioGetState Feb 18 13:34:48 volumio volumio[16226]: info: Starting Uninstall of plugin system_controller - ampswitch Feb 18 13:34:48 volumio volumio[16226]: info: Uninstalling plugin ampswitch Feb 18 13:34:48 volumio volumio[16226]: info: [ASDebug] Port: 24 Feb 18 13:34:48 volumio volumio[16226]: info: [ASDebug] Inverted: false Feb 18 13:34:48 volumio volumio[16226]: info: [ASDebug] Delay: 720 Feb 18 13:34:48 volumio volumio[16226]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 13:34:48 volumio volumio[16226]: Error: EBADF: bad file descriptor, close Feb 18 13:34:48 volumio volumio[16226]: at Object.closeSync (fs.js:439:3) Feb 18 13:34:48 volumio volumio[16226]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Feb 18 13:34:48 volumio volumio[16226]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Feb 18 13:34:48 volumio volumio[16226]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Feb 18 13:34:48 volumio volumio[16226]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Feb 18 13:34:48 volumio volumio[16226]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Feb 18 13:34:48 volumio volumio[16226]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Feb 18 13:34:48 volumio volumio[16226]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Feb 18 13:34:48 volumio volumio[16226]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1478:22) Feb 18 13:34:48 volumio volumio[16226]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1254:45) Feb 18 13:34:48 volumio volumio[16226]: at Socket.emit (events.js:315:20) Feb 18 13:34:48 volumio volumio[16226]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 18 13:34:48 volumio volumio[16226]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Feb 18 13:34:48 volumio volumio[16226]: errno: -9, Feb 18 13:34:48 volumio volumio[16226]: syscall: 'close', Feb 18 13:34:48 volumio volumio[16226]: code: 'EBADF' Feb 18 13:34:48 volumio volumio[16226]: } Feb 18 13:34:48 volumio volumio[16226]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 13:34:49 volumio sudo[9237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-18 13:33 Feb 18 13:34:49 volumio sudo[9237]: 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"