-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Sun 2025-03-09 03:03:24 +04. -- Mar 09 03:02:02 volumio1 ntpd[823]: Soliciting pool server 2001:ac8:81:65:0:2:0:2 Mar 09 03:02:27 volumio1 go-librespot[26934]: time="2025-03-09T03:02:27+04:00" level=trace msg="sent dealer ping" Mar 09 03:02:28 volumio1 go-librespot[26934]: time="2025-03-09T03:02:28+04:00" level=trace msg="received dealer pong" Mar 09 03:02:35 volumio1 volumio[13801]: info: [yt-cast-receiver] Connecting sender through DIAL... Mar 09 03:02:37 volumio1 volumio[13801]: info: [yt-cast-receiver] (YouTube) Sender connected: iPhone (user: Manuelito) Mar 09 03:02:37 volumio1 volumio[13801]: info: [ytcr] ***** Sender connected ***** Mar 09 03:02:37 volumio1 volumio[13801]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 09 03:02:48 volumio1 volumio[13801]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Mar 09 03:02:48 volumio1 volumio[13801]: info: CoreCommandRouter::volumioGetState Mar 09 03:02:48 volumio1 volumio[13801]: info: CorePlayQueue::getTrack 0 Mar 09 03:02:51 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.play(): GFCZHlQoz9g @ 0s Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioGetState Mar 09 03:02:51 volumio1 volumio[13801]: info: CorePlayQueue::getTrack 0 Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioGetState Mar 09 03:02:51 volumio1 volumio[13801]: info: CorePlayQueue::getTrack 0 Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioStop Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreStateMachine::stop Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 03:02:51 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: creating Innertube instance... Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioGetState Mar 09 03:02:51 volumio1 volumio[13801]: info: CorePlayQueue::getTrack 0 Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::servicePushState Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreStateMachine::pushState Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioPushState Mar 09 03:02:51 volumio1 volumio[13801]: info: MRS: Pushing multiroomSync output update for this device Mar 09 03:02:51 volumio1 volumio[13801]: info: MRS: Pushing multiroomSync output Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::servicePushState Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreStateMachine::pushState Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioPushState Mar 09 03:02:51 volumio1 volumio[13801]: info: MRS: Pushing multiroomSync output update for this device Mar 09 03:02:51 volumio1 volumio[13801]: info: MRS: Pushing multiroomSync output Mar 09 03:02:51 volumio1 volumio[13801]: info: CoreCommandRouter::volumioGetState Mar 09 03:02:51 volumio1 volumio[13801]: info: PeppyMeterBasic ---peppymeterbasic status pause Mar 09 03:02:51 volumio1 volumio[13801]: info: PeppyMeterBasic ---peppymeterbasic status pause Mar 09 03:02:51 volumio1 sudo[31403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 09 03:02:51 volumio1 sudo[31403]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 03:02:51 volumio1 sudo[31403]: pam_unix(sudo:session): session closed for user root Mar 09 03:02:51 volumio1 volumio[13801]: info: [GPIO_Control] Status changed: pause Mar 09 03:02:51 volumio1 volumio[13801]: info: [GPIO_Control] Handling event: musicPause Mar 09 03:02:51 volumio1 volumio[13801]: info: [GPIO_Control] Status changed: pause Mar 09 03:02:51 volumio1 volumio[13801]: info: [ASDebug] CurState: pause PrevState: stop Mar 09 03:02:51 volumio1 volumio[13801]: info: [ASDebug] CurState: pause PrevState: stop Mar 09 03:02:51 volumio1 volumio[13801]: info: [ASDebug] CurState: pause PrevState: stop Mar 09 03:02:51 volumio1 volumio[13801]: info: [ASDebug] CurState: pause PrevState: stop Mar 09 03:02:51 volumio1 volumio[13801]: info: PeppySpectrum ---peppyspectrum status pause Mar 09 03:02:51 volumio1 sudo[31406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Mar 09 03:02:51 volumio1 sudo[31406]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 03:02:51 volumio1 volumio[13801]: info: PeppySpectrum ---peppyspectrum status pause Mar 09 03:02:51 volumio1 sudo[31406]: pam_unix(sudo:session): session closed for user root Mar 09 03:02:51 volumio1 sudo[31409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 09 03:02:51 volumio1 sudo[31409]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 03:02:51 volumio1 sudo[31409]: pam_unix(sudo:session): session closed for user root Mar 09 03:02:51 volumio1 volumio[13801]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.63f932a53348f7734bab84082e43b4c9.state.track' Mar 09 03:02:51 volumio1 volumio[13801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Mar 09 03:02:51 volumio1 volumio[13801]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Mar 09 03:02:51 volumio1 sudo[31412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 09 03:02:51 volumio1 sudo[31412]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 03:02:51 volumio1 volumio[13801]: info: peppyspectrum Daemon Stop Mar 09 03:02:51 volumio1 volumio[13801]: info: peppymeterbasic Daemon Stop Mar 09 03:02:51 volumio1 volumio[13801]: info: peppymeterbasic Daemon Stop Mar 09 03:02:51 volumio1 sudo[31412]: pam_unix(sudo:session): session closed for user root Mar 09 03:02:51 volumio1 volumio[13801]: info: peppyspectrum Daemon Stop Mar 09 03:02:57 volumio1 go-librespot[26934]: time="2025-03-09T03:02:57+04:00" level=trace msg="sent dealer ping" Mar 09 03:02:58 volumio1 go-librespot[26934]: time="2025-03-09T03:02:58+04:00" level=trace msg="received dealer pong" Mar 09 03:02:59 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Mar 09 03:02:59 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:02:59 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:03:00 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:03:00 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:03:00 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: obtained po_token (expires in 43200 seconds) Mar 09 03:03:00 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: re-create Innertube instance with po_token Mar 09 03:03:00 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: creating Innertube instance with po_token... Mar 09 03:03:00 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:03:01 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:03:01 volumio1 volumio[13801]: info: [yt-cast-receiver] Player.pause() Mar 09 03:03:06 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: going to refresh po_token in 43100 seconds Mar 09 03:03:06 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: creating Innertube instance... Mar 09 03:03:07 volumio1 ntpd[823]: Soliciting pool server 2001:ac8:81:65:0:2:0:3 Mar 09 03:03:12 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: obtaining po_token by visitorData... Mar 09 03:03:14 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: obtained po_token (expires in 43200 seconds) Mar 09 03:03:14 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: re-create Innertube instance with po_token Mar 09 03:03:14 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: creating Innertube instance with po_token... Mar 09 03:03:19 volumio1 volumio[13801]: info: [ytcr] InnertubeLoader: going to refresh po_token in 43100 seconds Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:21 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:22 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:22 volumio1 volumio[13801]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Mar 09 03:03:22 volumio1 volumio[13801]: info: FusionDsp - ---- read samplerate from file: 192000 Mar 09 03:03:22 volumio1 volumio[13801]: info: camilladsp stopping service pid 15052... Mar 09 03:03:22 volumio1 kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Mar 09 03:03:22 volumio1 volumio[13801]: info: camilladsp service terminated, instance 1 Mar 09 03:03:22 volumio1 volumio[13801]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 09 03:03:22 volumio1 volumio[13801]: info: FusionDsp - else 1 -2.5 Mar 09 03:03:22 volumio1 volumio[13801]: info: camilladsp service started and running in background, instance 1 Mar 09 03:03:22 volumio1 volumio[13801]: info: Ignoring MPD Status Update Mar 09 03:03:22 volumio1 volumio[13801]: info: camilladsp spawned new process with pid 31474, instance 1, run: true Mar 09 03:03:22 volumio1 volumio[13801]: info: CoreCommandRouter::volumioGetState Mar 09 03:03:22 volumio1 volumio[13801]: info: CoreCommandRouter::servicePushState Mar 09 03:03:22 volumio1 volumio[13801]: info: CoreStateMachine::pushState Mar 09 03:03:22 volumio1 volumio[13801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 09 03:03:22 volumio1 volumio[13801]: info: CoreCommandRouter::volumioPushState Mar 09 03:03:22 volumio1 volumio[13801]: info: MRS: Pushing multiroomSync output update for this device Mar 09 03:03:22 volumio1 volumio[13801]: info: MRS: Pushing multiroomSync output Mar 09 03:03:22 volumio1 volumio[13801]: info: PeppyMeterBasic ---peppymeterbasic status play Mar 09 03:03:23 volumio1 volumio[13801]: info: [LastFM] Current track has sufficient metadata: title (Lydia Bayaton: Sinalikway) and artist (Old Visayan Songs) passed on explicitly Mar 09 03:03:23 volumio1 volumio[13801]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Mar 09 03:03:23 volumio1 volumio[13801]: info: [GPIO_Control] Status changed: play Mar 09 03:03:23 volumio1 volumio[13801]: info: [GPIO_Control] Handling event: musicPlay Mar 09 03:03:23 volumio1 volumio[13801]: info: [ASDebug] CurState: play PrevState: stop Mar 09 03:03:23 volumio1 volumio[13801]: info: [ASDebug] Togle GPIO: ON Mar 09 03:03:23 volumio1 volumio[13801]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 03:03:23 volumio1 volumio[13801]: Error: ESPIPE: invalid seek, write Mar 09 03:03:23 volumio1 volumio[13801]: at Object.writeSync (fs.js:711:3) Mar 09 03:03:23 volumio1 volumio[13801]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Mar 09 03:03:23 volumio1 volumio[13801]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Mar 09 03:03:23 volumio1 volumio[13801]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Mar 09 03:03:23 volumio1 volumio[13801]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Mar 09 03:03:23 volumio1 volumio[13801]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Mar 09 03:03:23 volumio1 volumio[13801]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Mar 09 03:03:23 volumio1 volumio[13801]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Mar 09 03:03:23 volumio1 volumio[13801]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Mar 09 03:03:23 volumio1 volumio[13801]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Mar 09 03:03:23 volumio1 volumio[13801]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Mar 09 03:03:23 volumio1 volumio[13801]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Mar 09 03:03:23 volumio1 volumio[13801]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Mar 09 03:03:23 volumio1 volumio[13801]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Mar 09 03:03:23 volumio1 volumio[13801]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Mar 09 03:03:23 volumio1 volumio[13801]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Mar 09 03:03:23 volumio1 volumio[13801]: errno: -29, Mar 09 03:03:23 volumio1 volumio[13801]: syscall: 'write', Mar 09 03:03:23 volumio1 volumio[13801]: code: 'ESPIPE' Mar 09 03:03:23 volumio1 volumio[13801]: } Mar 09 03:03:23 volumio1 volumio[13801]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 09 03:03:23 volumio1 sudo[31483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Mar 09 03:03:23 volumio1 sudo[31483]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 09 03:03:23 volumio1 systemd[1]: Started peppymeterbasic Daemon. Mar 09 03:03:23 volumio1 sudo[31483]: pam_unix(sudo:session): session closed for user root Mar 09 03:03:24 volumio1 sudo[31496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-09 03:02 Mar 09 03:03:24 volumio1 sudo[31496]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"