-- Logs begin at Sat 2024-11-09 04:57:21 AEDT, end at Fri 2024-11-29 13:24:24 AEDT. -- Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::servicePushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::servicePushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::servicePushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:00 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:00 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:00 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:00 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:00 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:00 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:01 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:01 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:01 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:02 volumio-rpi volumio[5725]: info: CoreCommandRouter::servicePushState Nov 29 13:23:02 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:02 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 13:23:02 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:03 volumio-rpi volumio[5725]: info: CoreCommandRouter::servicePushState Nov 29 13:23:03 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:03 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:03 volumio-rpi volumio[5725]: info: CoreCommandRouter::servicePushState Nov 29 13:23:03 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:03 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:03 volumio-rpi shairport-sync[6114]: {"time":1732846956904,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Nov 29 13:23:03 volumio-rpi shairport-sync[6114]: Dload Upload Total Spent Left Speed Nov 29 13:23:03 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:03 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:03 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:03 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:03 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:03 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:04 volumio-rpi volumio[5725]: info: Nov 29 13:23:04 volumio-rpi volumio[5725]: ---------------------------- Client requests Stop Airplay Playback Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Nov 29 13:23:04 volumio-rpi volumio[5725]: info: Airplay playback stop Nov 29 13:23:04 volumio-rpi volumio[5725]: info: Airplay Playback Stopped, clearing state Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreStateMachine::resetVolumioState Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreStateMachine::getcurrentVolume Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 13:23:04 volumio-rpi shairport-sync[6114]: [237B blob data] Nov 29 13:23:04 volumio-rpi volumio[5725]: info: VolumeController:: Volume=86 Mute =false Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreStateMachine::updateTrackBlock Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CorePlayQueue::getTrackBlock Nov 29 13:23:04 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioRetrievevolume Nov 29 13:23:05 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:05 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:05 volumio-rpi volumio[5725]: info: VolumeController:: Volume=86 Mute =false Nov 29 13:23:05 volumio-rpi volumio[5725]: info: CoreStateMachine::pushState Nov 29 13:23:05 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 29 13:23:05 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioPushState Nov 29 13:23:05 volumio-rpi volumio[5725]: info: [GPIO_Control] Status changed: play Nov 29 13:23:05 volumio-rpi volumio[5725]: info: [ASDebug] CurState: play PrevState: play Nov 29 13:23:06 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:11 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 13:23:11 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 29 13:23:11 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:16 volumio-rpi volumio[5725]: info: [ASDebug] Port: 3 Nov 29 13:23:16 volumio-rpi volumio[5725]: info: [ASDebug] Inverted: false Nov 29 13:23:16 volumio-rpi volumio[5725]: info: [ASDebug] Delay: 0 Nov 29 13:23:16 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:16 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:16 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:18 volumio-rpi volumio[5725]: info: [GPIO_Control] Destroying GPIO 15 Nov 29 13:23:18 volumio-rpi volumio[5725]: info: [GPIO_Control] Destroying GPIO 15 Nov 29 13:23:18 volumio-rpi volumio[5725]: info: [GPIO_Control] GPIOs destroyed Nov 29 13:23:18 volumio-rpi volumio[5725]: info: Disabling plugin gpio_control Nov 29 13:23:18 volumio-rpi volumio[5725]: info: Done. Nov 29 13:23:21 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:21 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:26 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:31 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:35 volumio-rpi volumio[5725]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 29 13:23:35 volumio-rpi volumio[5725]: info: [ASDebug] Setting UI defaults Nov 29 13:23:35 volumio-rpi volumio[5725]: info: [ASDebug] Port: 3 Nov 29 13:23:35 volumio-rpi volumio[5725]: info: [ASDebug] Inverted: false Nov 29 13:23:35 volumio-rpi volumio[5725]: info: [ASDebug] Latched: false Nov 29 13:23:35 volumio-rpi volumio[5725]: info: [ASDebug] On pulse width: 500 Nov 29 13:23:35 volumio-rpi volumio[5725]: info: [ASDebug] Off pulse width: 500 Nov 29 13:23:36 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:36 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:36 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:41 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:41 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:41 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:46 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:50 volumio-rpi volumio[5725]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Nov 29 13:23:50 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Nov 29 13:23:50 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Port: 15 Nov 29 13:23:50 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Inverted: false Nov 29 13:23:50 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Delay: 0 Nov 29 13:23:50 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Latched: false Nov 29 13:23:50 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: On Pulse width: 500 Nov 29 13:23:50 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Nov 29 13:23:50 volumio-rpi volumio[5725]: error: Failed callmethod call: Error: EBADF: bad file descriptor, close Nov 29 13:23:51 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:56 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:23:56 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:23:56 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:24:01 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:24:01 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:24:01 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:24:06 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:24:06 volumio-rpi volumio[5725]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Nov 29 13:24:06 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Nov 29 13:24:06 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Port: 15 Nov 29 13:24:06 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Inverted: true Nov 29 13:24:06 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Delay: 0 Nov 29 13:24:06 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Latched: false Nov 29 13:24:06 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: On Pulse width: 500 Nov 29 13:24:06 volumio-rpi volumio[5725]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Nov 29 13:24:06 volumio-rpi volumio[5725]: error: Failed callmethod call: Error: EBADF: bad file descriptor, close Nov 29 13:24:11 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:24:16 volumio-rpi volumio[5725]: info: CoreCommandRouter::volumioGetState Nov 29 13:24:16 volumio-rpi volumio[5725]: info: Listing playlists Nov 29 13:24:17 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 29 13:24:17 volumio-rpi volumio[5725]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 29 13:24:20 volumio-rpi volumio[5725]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 29 13:24:20 volumio-rpi volumio[5725]: info: Uninstalling plugin ampswitch Nov 29 13:24:20 volumio-rpi volumio[5725]: info: [ASDebug] Port: 15 Nov 29 13:24:20 volumio-rpi volumio[5725]: info: [ASDebug] Inverted: true Nov 29 13:24:20 volumio-rpi volumio[5725]: info: [ASDebug] Delay: 0 Nov 29 13:24:20 volumio-rpi volumio[5725]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 13:24:20 volumio-rpi volumio[5725]: Error: EBADF: bad file descriptor, close Nov 29 13:24:20 volumio-rpi volumio[5725]: at Object.closeSync (fs.js:439:3) Nov 29 13:24:20 volumio-rpi volumio[5725]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 29 13:24:20 volumio-rpi volumio[5725]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 29 13:24:20 volumio-rpi volumio[5725]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 29 13:24:20 volumio-rpi volumio[5725]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 29 13:24:20 volumio-rpi volumio[5725]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 29 13:24:20 volumio-rpi volumio[5725]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 29 13:24:20 volumio-rpi volumio[5725]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Nov 29 13:24:20 volumio-rpi volumio[5725]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1479:22) Nov 29 13:24:20 volumio-rpi volumio[5725]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1254:45) Nov 29 13:24:20 volumio-rpi volumio[5725]: at Socket.emit (events.js:315:20) Nov 29 13:24:20 volumio-rpi volumio[5725]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 29 13:24:20 volumio-rpi volumio[5725]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 29 13:24:20 volumio-rpi volumio[5725]: errno: -9, Nov 29 13:24:20 volumio-rpi volumio[5725]: syscall: 'close', Nov 29 13:24:20 volumio-rpi volumio[5725]: code: 'EBADF' Nov 29 13:24:20 volumio-rpi volumio[5725]: } Nov 29 13:24:20 volumio-rpi volumio[5725]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 29 13:24:24 volumio-rpi sudo[10094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-29 13:23 Nov 29 13:24:24 volumio-rpi sudo[10094]: 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"