-- Logs begin at Sat 2025-06-28 13:23:47 IST, end at Sat 2025-06-28 13:32:01 IST. -- Jun 28 13:31:00 volumio volumio[1313]: info: Starting Uninstall of plugin system_controller - ampswitch Jun 28 13:31:00 volumio volumio[1313]: info: Uninstalling plugin ampswitch Jun 28 13:31:00 volumio volumio[1313]: info: [ASDebug] Port: 24 Jun 28 13:31:00 volumio volumio[1313]: info: [ASDebug] Inverted: false Jun 28 13:31:00 volumio volumio[1313]: info: [ASDebug] Delay: 720 Jun 28 13:31:01 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 28 13:31:10 volumio volumio[1313]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 28 13:31:10 volumio volumio[1313]: info: Received Get System Version Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 28 13:31:10 volumio volumio[1313]: info: Received Get System Info Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 13:31:10 volumio volumio[1313]: info: Discovery: Getting this device information Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:10 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:10 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 13:31:11 volumio volumio[1313]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 13:31:11 volumio volumio[1313]: info: [ASDebug] Setting UI defaults Jun 28 13:31:11 volumio volumio[1313]: info: [ASDebug] Port: 24 Jun 28 13:31:11 volumio volumio[1313]: info: [ASDebug] Inverted: false Jun 28 13:31:11 volumio volumio[1313]: info: [ASDebug] Latched: false Jun 28 13:31:11 volumio volumio[1313]: info: [ASDebug] On pulse width: 500 Jun 28 13:31:11 volumio volumio[1313]: info: [ASDebug] Off pulse width: 500 Jun 28 13:31:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 13:31:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 13:31:18 volumio volumio[1313]: info: Discovery: Getting this device information Jun 28 13:31:18 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:18 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:18 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 13:31:21 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 28 13:31:21 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 28 13:31:26 volumio volumio[1313]: info: Enabling plugin ytcr Jun 28 13:31:26 volumio volumio[1313]: info: Loading plugin "ytcr"... Jun 28 13:31:26 volumio volumio[1313]: info: PLUGIN START: ytcr Jun 28 13:31:27 volumio volumio[1313]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 28 13:31:27 volumio volumio[1313]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 13:31:27 volumio volumio[1313]: info: VolumeController:: Volume=100 Mute =false Jun 28 13:31:27 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:27 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:27 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:27 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:27 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:27 volumio volumio[1313]: info: [ASDebug] CurState: play PrevState: play Jun 28 13:31:27 volumio volumio[1313]: info: Done. Jun 28 13:31:27 volumio volumio[1313]: info: [yt-cast-receiver] Player.reset() Jun 28 13:31:27 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:27 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:27 volumio volumio[1313]: info: Disabling plugin ytcr Jun 28 13:31:27 volumio volumio[1313]: info: Done. Jun 28 13:31:31 volumio volumio[1313]: info: Enabling plugin ytcr Jun 28 13:31:31 volumio volumio[1313]: info: Loading plugin "ytcr"... Jun 28 13:31:31 volumio volumio[1313]: info: PLUGIN START: ytcr Jun 28 13:31:32 volumio volumio[1313]: info: [yt-cast-receiver] DIAL server listening on port 8098 Jun 28 13:31:32 volumio volumio[1313]: info: CoreCommandRouter::volumioRetrievevolume Jun 28 13:31:32 volumio volumio[1313]: info: VolumeController:: Volume=100 Mute =false Jun 28 13:31:32 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:32 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:32 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:32 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:32 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:32 volumio volumio[1313]: info: [ASDebug] CurState: play PrevState: play Jun 28 13:31:32 volumio volumio[1313]: info: Done. Jun 28 13:31:48 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 13:31:48 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 13:31:48 volumio volumio[1313]: info: Discovery: Getting this device information Jun 28 13:31:48 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:48 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:48 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 13:31:50 volumio volumio[1313]: info: [yt-cast-receiver] Connecting sender through DIAL... Jun 28 13:31:51 volumio volumio[1313]: info: [yt-cast-receiver] (YouTube) Sender connected: ONEPLUS CPH2573 (user: Faheem) Jun 28 13:31:51 volumio volumio[1313]: info: [ytcr] ***** Sender connected ***** Jun 28 13:31:51 volumio volumio[1313]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 28 13:31:54 volumio volumio[1313]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Jun 28 13:31:57 volumio volumio[1313]: Upnp client error: Error: This socket has been ended by the other party Jun 28 13:31:58 volumio volumio[1313]: info: [yt-cast-receiver] Player.resume() Jun 28 13:31:58 volumio volumio[1313]: info: [yt-cast-receiver] Player.play(): GXjFOlvrmCM @ 0s Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioStop Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::stop Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::stPlaybackTimer Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::updateTrackBlock Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrackBlock Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::serviceStop Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::serviceStop Jun 28 13:31:58 volumio volumio[1313]: info: ControllerMpd::stop Jun 28 13:31:58 volumio volumio[1313]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 13:31:58 volumio volumio[1313]: info: [ytcr] InnertubeLoader: creating Innertube instance... Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] CurState: stop PrevState: play Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 28 13:31:58 volumio volumio[1313]: info: sendMpdCommand stop took 11 milliseconds Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:58 volumio volumio[1313]: info: Ignoring MPD Status Update Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:58 volumio volumio[1313]: info: Ignoring MPD Status Update Jun 28 13:31:58 volumio volumio[1313]: info: Ignoring MPD Status Update Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] CurState: pause PrevState: play Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] CurState: pause PrevState: play Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:58 volumio volumio[1313]: info: CorePlayQueue::getTrack 0 Jun 28 13:31:58 volumio volumio[1313]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received ytcr Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] CurState: pause PrevState: play Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioGetState Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::servicePushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreStateMachine::pushState Jun 28 13:31:58 volumio volumio[1313]: info: CoreCommandRouter::volumioPushState Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] CurState: pause PrevState: play Jun 28 13:31:58 volumio volumio[1313]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 28 13:31:58 volumio volumio[1313]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Jun 28 13:32:01 volumio volumio[1313]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Jun 28 13:32:01 volumio volumio[1313]: info: [ASDebug] Togle GPIO: OFF Jun 28 13:32:01 volumio volumio[1313]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 13:32:01 volumio volumio[1313]: Error: ESPIPE: invalid seek, write Jun 28 13:32:01 volumio volumio[1313]: at Object.writeSync (fs.js:711:3) Jun 28 13:32:01 volumio volumio[1313]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 28 13:32:01 volumio volumio[1313]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Jun 28 13:32:01 volumio volumio[1313]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jun 28 13:32:01 volumio volumio[1313]: at listOnTimeout (internal/timers.js:554:17) Jun 28 13:32:01 volumio volumio[1313]: at processTimers (internal/timers.js:497:7) { Jun 28 13:32:01 volumio volumio[1313]: errno: -29, Jun 28 13:32:01 volumio volumio[1313]: syscall: 'write', Jun 28 13:32:01 volumio volumio[1313]: code: 'ESPIPE' Jun 28 13:32:01 volumio volumio[1313]: } Jun 28 13:32:01 volumio volumio[1313]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 13:32:01 volumio sudo[3036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-28 13:31 Jun 28 13:32:01 volumio sudo[3036]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"