-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sun 2024-11-24 16:33:09 CET. -- Nov 24 16:32:03 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:07 volumio systemd[1]: Starting Cleanup of Temporary Directories... Nov 24 16:32:07 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Nov 24 16:32:07 volumio systemd[1]: Started Cleanup of Temporary Directories. Nov 24 16:32:13 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:13 volumio volumio[962]: info: Listing playlists Nov 24 16:32:13 volumio volumio[962]: info: Listing playlists Nov 24 16:32:14 volumio volumio[962]: info: Nov 24 16:32:14 volumio volumio[962]: ---------------------------- MPD announces system playlist update Nov 24 16:32:14 volumio volumio[962]: info: Ignoring MPD Status Update Nov 24 16:32:14 volumio volumio[962]: info: Nov 24 16:32:14 volumio volumio[962]: ---------------------------- MPD announces state update: player Nov 24 16:32:14 volumio volumio[962]: info: ControllerMpd::getState Nov 24 16:32:14 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Nov 24 16:32:14 volumio volumio[962]: info: ------------------------------ 4ms Nov 24 16:32:14 volumio volumio[962]: info: sendMpdCommand status took 2 milliseconds Nov 24 16:32:14 volumio volumio[962]: verbose: ControllerMpd::parseState Nov 24 16:32:14 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 16:32:14 volumio volumio[962]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 24 16:32:14 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Nov 24 16:32:14 volumio volumio[962]: info: ControllerMpd::pushState Nov 24 16:32:14 volumio volumio[962]: info: CoreCommandRouter::servicePushState Nov 24 16:32:14 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Nov 24 16:32:14 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142735,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SWR3 EASY Die Sonntagsshow","artist":"SWR3 MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr3/?aggregator=tunein","trackType":""} Nov 24 16:32:14 volumio volumio[962]: verbose: CURRENT POSITION 0 Nov 24 16:32:14 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Nov 24 16:32:14 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Nov 24 16:32:14 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Nov 24 16:32:14 volumio volumio[962]: info: CoreStateMachine::pushState Nov 24 16:32:14 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 16:32:14 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Nov 24 16:32:14 volumio volumio[962]: info: CoreStateMachine::pushState Nov 24 16:32:14 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Nov 24 16:32:14 volumio volumio[962]: info: ------------------------------ 30ms Nov 24 16:32:14 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Nov 24 16:32:14 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Nov 24 16:32:20 volumio volumio[962]: info: Nov 24 16:32:20 volumio volumio[962]: ---------------------------- MPD announces system playlist update Nov 24 16:32:20 volumio volumio[962]: info: Ignoring MPD Status Update Nov 24 16:32:20 volumio volumio[962]: info: Nov 24 16:32:20 volumio volumio[962]: ---------------------------- MPD announces state update: player Nov 24 16:32:20 volumio volumio[962]: info: ControllerMpd::getState Nov 24 16:32:20 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Nov 24 16:32:20 volumio volumio[962]: info: ------------------------------ 4ms Nov 24 16:32:20 volumio volumio[962]: info: sendMpdCommand status took 1 milliseconds Nov 24 16:32:20 volumio volumio[962]: verbose: ControllerMpd::parseState Nov 24 16:32:20 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 16:32:20 volumio volumio[962]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 24 16:32:20 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Nov 24 16:32:20 volumio volumio[962]: info: ControllerMpd::pushState Nov 24 16:32:20 volumio volumio[962]: info: CoreCommandRouter::servicePushState Nov 24 16:32:20 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Nov 24 16:32:20 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":148858,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"SWR3 Verkehrszentrum - Stau-Infos telefonisch unter 07221-9282","artist":"SWR3 MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr3/?aggregator=tunein","trackType":""} Nov 24 16:32:20 volumio volumio[962]: verbose: CURRENT POSITION 0 Nov 24 16:32:20 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Nov 24 16:32:20 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Nov 24 16:32:20 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Nov 24 16:32:20 volumio volumio[962]: info: CoreStateMachine::pushState Nov 24 16:32:20 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 16:32:20 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Nov 24 16:32:20 volumio volumio[962]: info: CoreStateMachine::pushState Nov 24 16:32:20 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Nov 24 16:32:20 volumio volumio[962]: info: ------------------------------ 31ms Nov 24 16:32:20 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Nov 24 16:32:20 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Nov 24 16:32:23 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:26 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 16:32:33 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:33 volumio volumio[962]: info: Listing playlists Nov 24 16:32:33 volumio volumio[962]: info: Listing playlists Nov 24 16:32:33 volumio volumio[962]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/ampswitch/0.2.1/volumio/buster/armhf Nov 24 16:32:35 volumio volumio[962]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/ampswitch/0.2.1/volumio/buster/armhf Nov 24 16:32:35 volumio volumio[962]: info: Folder /tmp/plugins removed Nov 24 16:32:36 volumio volumio[962]: info: Check plugin dependencies Nov 24 16:32:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 16:32:36 volumio volumio[962]: info: Checking if plugin already exists Nov 24 16:32:36 volumio volumio[962]: info: Rename folder Nov 24 16:32:36 volumio volumio[962]: info: Folder /tmp/downloaded_plugin.zip removed Nov 24 16:32:36 volumio volumio[962]: info: Move to category Nov 24 16:32:36 volumio volumio[962]: info: Checking if install.sh is present Nov 24 16:32:36 volumio volumio[962]: info: Executing install.sh Nov 24 16:32:36 volumio sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/ampswitch/install.sh Nov 24 16:32:36 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 16:32:36 volumio sudo[4130]: pam_unix(sudo:session): session closed for user root Nov 24 16:32:36 volumio volumio[962]: Plugin install end detected on script Nov 24 16:32:36 volumio volumio[962]: info: Install script completed Nov 24 16:32:36 volumio volumio[962]: info: Adding reference to registry Nov 24 16:32:36 volumio volumio[962]: info: Done installing plugin. Nov 24 16:32:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 16:32:36 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 16:32:36 volumio volumio[962]: info: Folder /tmp/plugins removed Nov 24 16:32:36 volumio volumio[962]: info: Folder /tmp/downloaded_plugin.zip removed Nov 24 16:32:36 volumio volumio[962]: info: Folder /data/temp removed Nov 24 16:32:38 volumio volumio[962]: info: Enabling plugin ampswitch Nov 24 16:32:38 volumio volumio[962]: info: Loading plugin "ampswitch"... Nov 24 16:32:38 volumio volumio[962]: info: PLUGIN START: ampswitch Nov 24 16:32:38 volumio volumio[962]: info: Done. Nov 24 16:32:38 volumio volumio[962]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 24 16:32:39 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:39 volumio volumio[962]: info: [ASDebug] CurState: play PrevState: na Nov 24 16:32:39 volumio volumio[962]: info: [ASDebug] Togle GPIO: ON Nov 24 16:32:39 volumio volumio[962]: info: [ASDebug] CurState: play PrevState: play Nov 24 16:32:43 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:44 volumio volumio[962]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 16:32:44 volumio volumio[962]: info: [ASDebug] Setting UI defaults Nov 24 16:32:44 volumio volumio[962]: info: [ASDebug] Port: 24 Nov 24 16:32:44 volumio volumio[962]: info: [ASDebug] Inverted: false Nov 24 16:32:44 volumio volumio[962]: info: [ASDebug] Latched: false Nov 24 16:32:44 volumio volumio[962]: info: [ASDebug] On pulse width: 500 Nov 24 16:32:44 volumio volumio[962]: info: [ASDebug] Off pulse width: 500 Nov 24 16:32:51 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 16:32:51 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 16:32:53 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:32:53 volumio volumio[962]: info: Listing playlists Nov 24 16:32:53 volumio volumio[962]: info: Listing playlists Nov 24 16:32:56 volumio volumio[962]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 24 16:32:56 volumio volumio[962]: info: Uninstalling plugin ampswitch Nov 24 16:32:56 volumio volumio[962]: info: [ASDebug] Port: 24 Nov 24 16:32:56 volumio volumio[962]: info: [ASDebug] Inverted: false Nov 24 16:32:56 volumio volumio[962]: info: [ASDebug] Delay: 720 Nov 24 16:32:56 volumio volumio[962]: info: Nov 24 16:32:56 volumio volumio[962]: ---------------------------- MPD announces system playlist update Nov 24 16:32:56 volumio volumio[962]: info: Ignoring MPD Status Update Nov 24 16:32:56 volumio volumio[962]: info: Nov 24 16:32:56 volumio volumio[962]: ---------------------------- MPD announces state update: player Nov 24 16:32:56 volumio volumio[962]: info: ControllerMpd::getState Nov 24 16:32:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Nov 24 16:32:56 volumio volumio[962]: info: ------------------------------ 4ms Nov 24 16:32:56 volumio volumio[962]: info: sendMpdCommand status took 3 milliseconds Nov 24 16:32:56 volumio volumio[962]: verbose: ControllerMpd::parseState Nov 24 16:32:56 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 16:32:56 volumio volumio[962]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 24 16:32:56 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Nov 24 16:32:56 volumio volumio[962]: info: ControllerMpd::pushState Nov 24 16:32:56 volumio volumio[962]: info: CoreCommandRouter::servicePushState Nov 24 16:32:56 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Nov 24 16:32:56 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":184853,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"EASY mit Michael Spleth","artist":"SWR3 MP3 128","album":null,"uri":"https://liveradio.swr.de/sw282p3/swr3/?aggregator=tunein","trackType":""} Nov 24 16:32:56 volumio volumio[962]: verbose: CURRENT POSITION 0 Nov 24 16:32:56 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Nov 24 16:32:56 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Nov 24 16:32:56 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Nov 24 16:32:56 volumio volumio[962]: info: CoreStateMachine::pushState Nov 24 16:32:56 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 16:32:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Nov 24 16:32:56 volumio volumio[962]: info: CoreStateMachine::pushState Nov 24 16:32:56 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Nov 24 16:32:56 volumio volumio[962]: info: ------------------------------ 38ms Nov 24 16:32:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Nov 24 16:32:56 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Nov 24 16:32:56 volumio volumio[962]: info: [ASDebug] CurState: play PrevState: play Nov 24 16:32:56 volumio volumio[962]: info: [ASDebug] CurState: play PrevState: play Nov 24 16:33:03 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Nov 24 16:33:08 volumio volumio[962]: info: [ASDebug] Port: 24 Nov 24 16:33:08 volumio volumio[962]: info: [ASDebug] Inverted: false Nov 24 16:33:08 volumio volumio[962]: info: [ASDebug] Delay: 720 Nov 24 16:33:08 volumio volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 16:33:08 volumio volumio[962]: Error: EBADF: bad file descriptor, close Nov 24 16:33:08 volumio volumio[962]: at Object.closeSync (fs.js:439:3) Nov 24 16:33:08 volumio volumio[962]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 24 16:33:08 volumio volumio[962]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 24 16:33:08 volumio volumio[962]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 24 16:33:08 volumio volumio[962]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 24 16:33:08 volumio volumio[962]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 24 16:33:08 volumio volumio[962]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 24 16:33:08 volumio volumio[962]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 24 16:33:08 volumio volumio[962]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1551:29) Nov 24 16:33:08 volumio volumio[962]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Nov 24 16:33:08 volumio volumio[962]: at Socket.emit (events.js:315:20) Nov 24 16:33:08 volumio volumio[962]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 24 16:33:08 volumio volumio[962]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 24 16:33:08 volumio volumio[962]: errno: -9, Nov 24 16:33:08 volumio volumio[962]: syscall: 'close', Nov 24 16:33:08 volumio volumio[962]: code: 'EBADF' Nov 24 16:33:08 volumio volumio[962]: } Nov 24 16:33:08 volumio volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 16:33:09 volumio sudo[4210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 16:32 Nov 24 16:33:09 volumio sudo[4210]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"