-- Logs begin at Sat 2025-04-26 13:39:40 EDT, end at Sat 2025-04-26 13:58:30 EDT. -- Apr 26 13:57:00 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:00 volumio volumio[1375]: 1+1 records in Apr 26 13:57:00 volumio volumio[1375]: 1+1 records out Apr 26 13:57:00 volumio volumio[1375]: 62272 bytes (62 kB, 61 KiB) copied, 0.000183185 s, 340 MB/s Apr 26 13:57:01 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:01 volumio volumio[1375]: 1+1 records in Apr 26 13:57:01 volumio volumio[1375]: 1+1 records out Apr 26 13:57:01 volumio volumio[1375]: 64976 bytes (65 kB, 63 KiB) copied, 0.000155834 s, 417 MB/s Apr 26 13:57:02 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:02 volumio volumio[1375]: 1+1 records in Apr 26 13:57:02 volumio volumio[1375]: 1+1 records out Apr 26 13:57:02 volumio volumio[1375]: 63216 bytes (63 kB, 62 KiB) copied, 0.000143889 s, 439 MB/s Apr 26 13:57:03 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:03 volumio volumio[1375]: 1+1 records in Apr 26 13:57:03 volumio volumio[1375]: 1+1 records out Apr 26 13:57:03 volumio volumio[1375]: 64576 bytes (65 kB, 63 KiB) copied, 0.000140611 s, 459 MB/s Apr 26 13:57:04 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:04 volumio volumio[1375]: 1+1 records in Apr 26 13:57:04 volumio volumio[1375]: 1+1 records out Apr 26 13:57:04 volumio volumio[1375]: 64992 bytes (65 kB, 63 KiB) copied, 0.000140426 s, 463 MB/s Apr 26 13:57:05 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:05 volumio volumio[1375]: 1+1 records in Apr 26 13:57:05 volumio volumio[1375]: 1+1 records out Apr 26 13:57:05 volumio volumio[1375]: 63200 bytes (63 kB, 62 KiB) copied, 0.000142889 s, 442 MB/s Apr 26 13:57:06 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:06 volumio volumio[1375]: 2+0 records in Apr 26 13:57:06 volumio volumio[1375]: 2+0 records out Apr 26 13:57:06 volumio volumio[1375]: 65536 bytes (66 kB, 64 KiB) copied, 0.000137093 s, 478 MB/s Apr 26 13:57:07 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:07 volumio volumio[1375]: 1+1 records in Apr 26 13:57:07 volumio volumio[1375]: 1+1 records out Apr 26 13:57:07 volumio volumio[1375]: 62656 bytes (63 kB, 61 KiB) copied, 0.000136093 s, 460 MB/s Apr 26 13:57:08 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:08 volumio volumio[1375]: 2+0 records in Apr 26 13:57:08 volumio volumio[1375]: 2+0 records out Apr 26 13:57:08 volumio volumio[1375]: 65536 bytes (66 kB, 64 KiB) copied, 0.000138315 s, 474 MB/s Apr 26 13:57:09 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:09 volumio volumio[1375]: 1+1 records in Apr 26 13:57:09 volumio volumio[1375]: 1+1 records out Apr 26 13:57:09 volumio volumio[1375]: 59008 bytes (59 kB, 58 KiB) copied, 0.000138611 s, 426 MB/s Apr 26 13:57:10 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:10 volumio volumio[1375]: 1+1 records in Apr 26 13:57:10 volumio volumio[1375]: 1+1 records out Apr 26 13:57:10 volumio volumio[1375]: 64128 bytes (64 kB, 63 KiB) copied, 0.000139981 s, 458 MB/s Apr 26 13:57:11 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:11 volumio volumio[1375]: 1+1 records in Apr 26 13:57:11 volumio volumio[1375]: 1+1 records out Apr 26 13:57:11 volumio volumio[1375]: 63616 bytes (64 kB, 62 KiB) copied, 0.000131648 s, 483 MB/s Apr 26 13:57:12 volumio volumio[1375]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 26 13:57:12 volumio volumio[1375]: 1+1 records in Apr 26 13:57:12 volumio volumio[1375]: 1+1 records out Apr 26 13:57:12 volumio volumio[1375]: 60944 bytes (61 kB, 60 KiB) copied, 0.000151537 s, 402 MB/s Apr 26 13:57:13 volumio volumio[1375]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::volumioGetState Apr 26 13:57:13 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:13 volumio volumio[1375]: info: Restoring Previous Volume level: 47 false false Apr 26 13:57:13 volumio volumio[1375]: info: Volume configurations have been set Apr 26 13:57:13 volumio vtcs[2759]: [2025-04-26 13:57:13.366] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 26 13:57:13 volumio vtcs[2759]: [2025-04-26 13:57:13.366] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 26 13:57:13 volumio sudo[11127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 13:57:13 volumio sudo[11127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:13 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Apr 26 13:57:13 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 26 13:57:13 volumio systemd[1]: vtcs.service: Succeeded. Apr 26 13:57:13 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Apr 26 13:57:13 volumio sudo[11127]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 26 13:57:13 volumio volumio[1375]: info: Updating Volume Controller Parameters: Device: 0 Name: IQaudIO Pi-DigiAMP+ Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 26 13:57:13 volumio volumio[1375]: info: Disabling external Volume Control Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 13:57:13 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:57:13 volumio sudo[11134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 26 13:57:13 volumio sudo[11134]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:13 volumio sudo[11134]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 13:57:13 volumio volumio[1375]: info: Getting Alsa Cards List without I2S DAC Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 13:57:13 volumio volumio[1375]: info: VolumeController:: Volume=46 Mute =false Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:13 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:13 volumio volumio[1375]: info: [ASDebug] CurState: play PrevState: play Apr 26 13:57:13 volumio volumio[1375]: info: [GPIO_Control] Status changed: play Apr 26 13:57:13 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 26 13:57:13 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status play Apr 26 13:57:13 volumio sudo[11149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 26 13:57:13 volumio sudo[11149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:13 volumio sudo[11149]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:13 volumio sudo[11152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 26 13:57:13 volumio sudo[11152]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 47 Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 46 Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 26 13:57:13 volumio volumio[1375]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::volumioGetState Apr 26 13:57:13 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::volumioPause Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::pause Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::stPlaybackTimer Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::servicePause Apr 26 13:57:13 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::servicePause Apr 26 13:57:13 volumio volumio[1375]: info: [1745690233615] ControllerTidal::pause Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 13:57:13 volumio volumio[1375]: info: ControllerMpd::pause Apr 26 13:57:13 volumio volumio[1375]: verbose: ControllerMpd::sendMpdCommand pause Apr 26 13:57:13 volumio volumio[1375]: info: mpdhttpout --- Volumio set on pause Apr 26 13:57:13 volumio volumio[1375]: info: peppymeterbasic Daemon Started Apr 26 13:57:13 volumio volumio[1375]: info: Apr 26 13:57:13 volumio volumio[1375]: ---------------------------- MPD announces state update: player Apr 26 13:57:13 volumio volumio[1375]: info: sendMpdCommand pause took 2 milliseconds Apr 26 13:57:13 volumio volumio[1375]: info: ControllerMpd::getState Apr 26 13:57:13 volumio volumio[1375]: verbose: ControllerMpd::sendMpdCommand status Apr 26 13:57:13 volumio volumio[1375]: info: sendMpdCommand status took 0 milliseconds Apr 26 13:57:13 volumio volumio[1375]: verbose: ControllerMpd::parseState Apr 26 13:57:13 volumio volumio[1375]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 13:57:13 volumio volumio[1375]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 13:57:13 volumio volumio[1375]: verbose: ControllerMpd::parseTrackInfo Apr 26 13:57:13 volumio volumio[1375]: info: ControllerMpd::pushState Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::servicePushState Apr 26 13:57:13 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:13 volumio volumio[1375]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":143319,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"904 Kbps","isStreaming":false,"title":"4215873&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/4215873&soundQuality=LOSSLESS","trackType":"tidal"} Apr 26 13:57:13 volumio volumio[1375]: verbose: CURRENT POSITION 3 Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::syncState stateService pause Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::syncState currentStatus pause Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:13 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:13 volumio sudo[11152]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:13 volumio volumio[1375]: info: CoreStateMachine::stPlaybackTimer Apr 26 13:57:13 volumio volumio[1375]: info: ------------------------------ 13ms Apr 26 13:57:13 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: play Apr 26 13:57:13 volumio volumio[1375]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 26 13:57:13 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:13 volumio volumio[1375]: info: [GPIO_Control] Handling event: musicPause Apr 26 13:57:13 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:13 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:13 volumio sudo[11155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:13 volumio sudo[11155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:13 volumio systemd[1]: Stopping peppymeterbasic Daemon... Apr 26 13:57:13 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Apr 26 13:57:13 volumio sudo[11158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:13 volumio sudo[11158]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 47 Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 46 Apr 26 13:57:13 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 26 13:57:13 volumio volumio[1375]: info: peppyspectrum Daemon Started Apr 26 13:57:13 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:57:13 volumio systemd[1]: Stopping peppyspectrum Daemon... Apr 26 13:57:13 volumio systemd[1]: peppyspectrum.service: Main process exited, code=killed, status=15/TERM Apr 26 13:57:13 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:57:13 volumio volumio[1375]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 26 13:57:13 volumio sudo[11155]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:13 volumio systemd[1]: peppymeterbasic.service: Succeeded. Apr 26 13:57:13 volumio systemd[1]: Stopped peppymeterbasic Daemon. Apr 26 13:57:13 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:13 volumio systemd[1]: peppyspectrum.service: Succeeded. Apr 26 13:57:13 volumio systemd[1]: Stopped peppyspectrum Daemon. Apr 26 13:57:13 volumio sudo[11158]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:13 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:13 volumio volumio[1375]: 0+0 records in Apr 26 13:57:13 volumio volumio[1375]: 0+0 records out Apr 26 13:57:13 volumio volumio[1375]: 0 bytes copied, 3.9556e-05 s, 0.0 kB/s Apr 26 13:57:14 volumio volumio[1375]: info: [ASDebug] Togle GPIO: OFF Apr 26 13:57:14 volumio volumio[1375]: 0+0 records in Apr 26 13:57:14 volumio volumio[1375]: 0+0 records out Apr 26 13:57:14 volumio volumio[1375]: 0 bytes copied, 4.8871e-05 s, 0.0 kB/s Apr 26 13:57:15 volumio volumio[1375]: info: Executing endpoint metavolumio Apr 26 13:57:15 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 13:57:15 volumio volumio[1375]: info: Executing endpoint metavolumio Apr 26 13:57:15 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 13:57:15 volumio volumio[1375]: info: Executing endpoint metavolumio Apr 26 13:57:15 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 13:57:15 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Apr 26 13:57:15 volumio sudo[11174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 13:57:15 volumio sudo[11174]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:15 volumio systemd[1]: musicservicesshield.service: Succeeded. Apr 26 13:57:15 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 26 13:57:15 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 26 13:57:15 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 13:57:15 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:57:15 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:57:15 volumio systemd[1]: mpd.service: Succeeded. Apr 26 13:57:15 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 13:57:15 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 26 13:57:15 volumio systemd[1]: Starting Music Player Daemon... Apr 26 13:57:15 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:57:15 volumio sudo[11176]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 13:57:15 volumio sudo[11176]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:15 volumio sudo[11176]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:15 volumio volumio[1375]: 0+0 records in Apr 26 13:57:15 volumio volumio[1375]: 0+0 records out Apr 26 13:57:15 volumio volumio[1375]: 0 bytes copied, 3.8037e-05 s, 0.0 kB/s Apr 26 13:57:16 volumio mpd[11178]: Apr 26 13:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 13:57:16 volumio systemd[1]: Started Music Player Daemon. Apr 26 13:57:16 volumio sudo[11174]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:16 volumio volumio[1375]: error: updateQueue error: null Apr 26 13:57:16 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 26 13:57:16 volumio volumio[1375]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 26 13:57:16 volumio volumio[11201]: cset: --> shielding system active with Apr 26 13:57:16 volumio volumio[11201]: cset: "system" cpuset of CPUSPEC(1-3) with 288 tasks running Apr 26 13:57:16 volumio volumio[11201]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Apr 26 13:57:16 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 26 13:57:16 volumio volumio[1375]: info: TidalConnect service stoped! Apr 26 13:57:16 volumio volumio[1375]: info: TidalConnect service stoped! Apr 26 13:57:16 volumio volumio[1375]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 26 13:57:16 volumio volumio[1375]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 26 13:57:16 volumio sudo[11226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 26 13:57:16 volumio sudo[11226]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:16 volumio systemd[1]: Started Volumio Tidal Connect Service. Apr 26 13:57:16 volumio sudo[11226]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:16 volumio volumio[1375]: info: Executing endpoint tc_getconfig Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 26 13:57:16 volumio vtcs[11228]: STARTING TidalConnect services, version: 1.5.0.45 Apr 26 13:57:16 volumio vtcs[11228]: STARTED TidalConnect services. Apr 26 13:57:16 volumio volumio[1375]: info: Executing endpoint tc_connect Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 26 13:57:16 volumio volumio[1375]: info: Connecting to TidalConnect Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::servicePushState Apr 26 13:57:16 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:16 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:16 volumio volumio[1375]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::servicePushState Apr 26 13:57:16 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:16 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:16 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:16 volumio volumio[1375]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 26 13:57:16 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:16 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:16 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:16 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:16 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:16 volumio sudo[11249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:16 volumio sudo[11249]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:16 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:16 volumio sudo[11252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:16 volumio sudo[11252]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:16 volumio sudo[11249]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:16 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:16 volumio sudo[11255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:16 volumio sudo[11255]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:16 volumio sudo[11252]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 47 Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 46 Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 46 Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 47 Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 46 Apr 26 13:57:16 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: false Apr 26 13:57:16 volumio sudo[11258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:16 volumio sudo[11258]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:16 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:16 volumio sudo[11255]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:16 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:16 volumio sudo[11258]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:16 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:16 volumio volumio[1375]: 0+0 records in Apr 26 13:57:16 volumio volumio[1375]: 0+0 records out Apr 26 13:57:16 volumio volumio[1375]: 0 bytes copied, 4.2815e-05 s, 0.0 kB/s Apr 26 13:57:17 volumio volumio[1375]: info: VolumeController::SetAlsaVolume47 Apr 26 13:57:17 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:17 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:17 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:17 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:17 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:17 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:17 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:17 volumio sudo[11267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:17 volumio sudo[11267]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:17 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 47 Apr 26 13:57:17 volumio sudo[11270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:17 volumio sudo[11270]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:17 volumio sudo[11267]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:17 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:17 volumio sudo[11270]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:17 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:17 volumio volumio[1375]: 0+0 records in Apr 26 13:57:17 volumio volumio[1375]: 0+0 records out Apr 26 13:57:17 volumio volumio[1375]: 0 bytes copied, 3.6019e-05 s, 0.0 kB/s Apr 26 13:57:18 volumio volumio[1375]: 0+0 records in Apr 26 13:57:18 volumio volumio[1375]: 0+0 records out Apr 26 13:57:18 volumio volumio[1375]: 0 bytes copied, 4.7703e-05 s, 0.0 kB/s Apr 26 13:57:19 volumio volumio[1375]: info: TidalConnect service started! Apr 26 13:57:20 volumio volumio[1375]: 0+0 records in Apr 26 13:57:20 volumio volumio[1375]: 0+0 records out Apr 26 13:57:20 volumio volumio[1375]: 0 bytes copied, 4.8334e-05 s, 0.0 kB/s Apr 26 13:57:21 volumio volumio[1375]: 0+0 records in Apr 26 13:57:21 volumio volumio[1375]: 0+0 records out Apr 26 13:57:21 volumio volumio[1375]: 0 bytes copied, 4.6852e-05 s, 0.0 kB/s Apr 26 13:57:22 volumio volumio[1375]: 0+0 records in Apr 26 13:57:22 volumio volumio[1375]: 0+0 records out Apr 26 13:57:22 volumio volumio[1375]: 0 bytes copied, 4.9759e-05 s, 0.0 kB/s Apr 26 13:57:23 volumio volumio[1375]: 0+0 records in Apr 26 13:57:23 volumio volumio[1375]: 0+0 records out Apr 26 13:57:23 volumio volumio[1375]: 0 bytes copied, 4.9518e-05 s, 0.0 kB/s Apr 26 13:57:24 volumio volumio[1375]: 0+0 records in Apr 26 13:57:24 volumio volumio[1375]: 0+0 records out Apr 26 13:57:24 volumio volumio[1375]: 0 bytes copied, 4.6037e-05 s, 0.0 kB/s Apr 26 13:57:25 volumio volumio[1375]: 0+0 records in Apr 26 13:57:25 volumio volumio[1375]: 0+0 records out Apr 26 13:57:25 volumio volumio[1375]: 0 bytes copied, 4.8537e-05 s, 0.0 kB/s Apr 26 13:57:26 volumio volumio[1375]: 0+0 records in Apr 26 13:57:26 volumio volumio[1375]: 0+0 records out Apr 26 13:57:26 volumio volumio[1375]: 0 bytes copied, 4.7759e-05 s, 0.0 kB/s Apr 26 13:57:27 volumio volumio[1375]: 0+0 records in Apr 26 13:57:27 volumio volumio[1375]: 0+0 records out Apr 26 13:57:27 volumio volumio[1375]: 0 bytes copied, 4.9833e-05 s, 0.0 kB/s Apr 26 13:57:27 volumio volumio[1375]: info: VolumeController::SetAlsaVolume+ Apr 26 13:57:27 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:27 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:27 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:27 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:27 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:27 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:27 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:27 volumio sudo[11341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:27 volumio sudo[11341]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 47 Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 52 Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 26 13:57:27 volumio volumio[1375]: info: Setting Spotify Volume from Volumio: 52 Apr 26 13:57:27 volumio sudo[11344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:27 volumio sudo[11344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:27 volumio sudo[11341]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:27 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:27 volumio sudo[11344]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:27 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:27 volumio volumio[1375]: info: VolumeController::SetAlsaVolume+ Apr 26 13:57:27 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:27 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:27 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:27 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:27 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:27 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:27 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:27 volumio sudo[11348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:27 volumio sudo[11348]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 52 Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 57 Apr 26 13:57:27 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 26 13:57:27 volumio volumio[1375]: info: Setting Spotify Volume from Volumio: 57 Apr 26 13:57:27 volumio sudo[11351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:27 volumio sudo[11351]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:27 volumio sudo[11348]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:27 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:27 volumio sudo[11351]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:27 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:27 volumio volumio[1375]: info: VolumeController::SetAlsaVolume+ Apr 26 13:57:27 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:27 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:27 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:27 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:27 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:27 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:27 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:27 volumio sudo[11355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:27 volumio sudo[11355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:27 volumio sudo[11358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:27 volumio sudo[11358]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 57 Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 62 Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 26 13:57:28 volumio volumio[1375]: info: Setting Spotify Volume from Volumio: 62 Apr 26 13:57:28 volumio sudo[11355]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:28 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:28 volumio sudo[11358]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:28 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:28 volumio volumio[1375]: info: VolumeController::SetAlsaVolume+ Apr 26 13:57:28 volumio volumio[1375]: info: CoreStateMachine::pushState Apr 26 13:57:28 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 13:57:28 volumio volumio[1375]: info: CoreCommandRouter::volumioPushState Apr 26 13:57:28 volumio volumio[1375]: info: [ASDebug] CurState: pause PrevState: pause Apr 26 13:57:28 volumio volumio[1375]: info: [GPIO_Control] Status changed: pause Apr 26 13:57:28 volumio volumio[1375]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 26 13:57:28 volumio volumio[1375]: info: PeppySpectrum ---peppyspectrum status pause Apr 26 13:57:28 volumio sudo[11362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 26 13:57:28 volumio sudo[11362]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: RECEIVED VOLUMIO VOLUME 67 Apr 26 13:57:28 volumio sudo[11365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: SPOTIFY VOLUME 62 Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: VOLUMIO VOLUME 67 Apr 26 13:57:28 volumio volumio[1375]: SPOTIFY: DELTA VOLUME ENOUGH: true Apr 26 13:57:28 volumio volumio[1375]: info: Setting Spotify Volume from Volumio: 67 Apr 26 13:57:28 volumio sudo[11365]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 13:57:28 volumio sudo[11362]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:28 volumio volumio[1375]: 0+0 records in Apr 26 13:57:28 volumio volumio[1375]: 0+0 records out Apr 26 13:57:28 volumio volumio[1375]: 0 bytes copied, 4.3556e-05 s, 0.0 kB/s Apr 26 13:57:28 volumio sudo[11365]: pam_unix(sudo:session): session closed for user root Apr 26 13:57:28 volumio volumio[1375]: info: peppymeterbasic Daemon Stop Apr 26 13:57:28 volumio volumio[1375]: info: peppyspectrum Daemon Stop Apr 26 13:57:29 volumio volumio[1375]: 0+0 records in Apr 26 13:57:29 volumio volumio[1375]: 0+0 records out Apr 26 13:57:29 volumio volumio[1375]: 0 bytes copied, 5.2575e-05 s, 0.0 kB/s Apr 26 13:57:29 volumio volumio[1375]: SPOTIFY: SETTING SPOTIFY VOLUME 67 Apr 26 13:57:29 volumio volumio[1375]: info: Sending Spotify command with payload to local API: /player/volume Apr 26 13:57:30 volumio volumio[1375]: 0+0 records in Apr 26 13:57:30 volumio volumio[1375]: 0+0 records out Apr 26 13:57:30 volumio volumio[1375]: 0 bytes copied, 5.1574e-05 s, 0.0 kB/s Apr 26 13:57:31 volumio volumio[1375]: 0+0 records in Apr 26 13:57:31 volumio volumio[1375]: 0+0 records out Apr 26 13:57:31 volumio volumio[1375]: 0 bytes copied, 4.9593e-05 s, 0.0 kB/s Apr 26 13:57:32 volumio volumio[1375]: 0+0 records in Apr 26 13:57:32 volumio volumio[1375]: 0+0 records out Apr 26 13:57:32 volumio volumio[1375]: 0 bytes copied, 4.6685e-05 s, 0.0 kB/s Apr 26 13:57:33 volumio volumio[1375]: 0+0 records in Apr 26 13:57:33 volumio volumio[1375]: 0+0 records out Apr 26 13:57:33 volumio volumio[1375]: 0 bytes copied, 4.9241e-05 s, 0.0 kB/s Apr 26 13:57:33 volumio volumio[1375]: info: CoreCommandRouter::volumioPlay Apr 26 13:57:33 volumio volumio[1375]: info: CoreStateMachine::play index undefined Apr 26 13:57:33 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 13:57:33 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:33 volumio volumio[1375]: info: CoreStateMachine::startPlaybackTimer Apr 26 13:57:33 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:33 volumio volumio[1375]: info: [1745690253490] ControllerTidal::resume Apr 26 13:57:33 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 13:57:33 volumio volumio[1375]: info: ControllerMpd::resume Apr 26 13:57:33 volumio volumio[1375]: verbose: ControllerMpd::sendMpdCommand play Apr 26 13:57:33 volumio volumio[1375]: info: sendMpdCommand play took 0 milliseconds Apr 26 13:57:34 volumio volumio[1375]: 0+0 records in Apr 26 13:57:34 volumio volumio[1375]: 0+0 records out Apr 26 13:57:34 volumio volumio[1375]: 0 bytes copied, 5.3963e-05 s, 0.0 kB/s Apr 26 13:57:35 volumio volumio[1375]: 0+0 records in Apr 26 13:57:35 volumio volumio[1375]: 0+0 records out Apr 26 13:57:35 volumio volumio[1375]: 0 bytes copied, 5.4778e-05 s, 0.0 kB/s Apr 26 13:57:36 volumio volumio[1375]: 0+0 records in Apr 26 13:57:36 volumio volumio[1375]: 0+0 records out Apr 26 13:57:36 volumio volumio[1375]: 0 bytes copied, 4.613e-05 s, 0.0 kB/s Apr 26 13:57:37 volumio volumio[1375]: 0+0 records in Apr 26 13:57:37 volumio volumio[1375]: 0+0 records out Apr 26 13:57:37 volumio volumio[1375]: 0 bytes copied, 7.0648e-05 s, 0.0 kB/s Apr 26 13:57:38 volumio volumio[1375]: 0+0 records in Apr 26 13:57:38 volumio volumio[1375]: 0+0 records out Apr 26 13:57:38 volumio volumio[1375]: 0 bytes copied, 4.7259e-05 s, 0.0 kB/s Apr 26 13:57:39 volumio volumio[1375]: 0+0 records in Apr 26 13:57:39 volumio volumio[1375]: 0+0 records out Apr 26 13:57:39 volumio volumio[1375]: 0 bytes copied, 5.0259e-05 s, 0.0 kB/s Apr 26 13:57:40 volumio volumio[1375]: 0+0 records in Apr 26 13:57:40 volumio volumio[1375]: 0+0 records out Apr 26 13:57:40 volumio volumio[1375]: 0 bytes copied, 4.8e-05 s, 0.0 kB/s Apr 26 13:57:41 volumio volumio[1375]: info: CoreCommandRouter::volumioPlay Apr 26 13:57:41 volumio volumio[1375]: info: CoreStateMachine::play index undefined Apr 26 13:57:41 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 13:57:41 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:41 volumio volumio[1375]: info: CoreStateMachine::startPlaybackTimer Apr 26 13:57:41 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:57:41 volumio volumio[1375]: info: [1745690261056] ControllerTidal::resume Apr 26 13:57:41 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 13:57:41 volumio volumio[1375]: info: ControllerMpd::resume Apr 26 13:57:41 volumio volumio[1375]: verbose: ControllerMpd::sendMpdCommand play Apr 26 13:57:41 volumio volumio[1375]: info: sendMpdCommand play took 0 milliseconds Apr 26 13:57:41 volumio volumio[1375]: 0+0 records in Apr 26 13:57:41 volumio volumio[1375]: 0+0 records out Apr 26 13:57:41 volumio volumio[1375]: 0 bytes copied, 4.7018e-05 s, 0.0 kB/s Apr 26 13:57:42 volumio volumio[1375]: 0+0 records in Apr 26 13:57:42 volumio volumio[1375]: 0+0 records out Apr 26 13:57:42 volumio volumio[1375]: 0 bytes copied, 4.7704e-05 s, 0.0 kB/s Apr 26 13:57:43 volumio volumio[1375]: 0+0 records in Apr 26 13:57:43 volumio volumio[1375]: 0+0 records out Apr 26 13:57:43 volumio volumio[1375]: 0 bytes copied, 4.65e-05 s, 0.0 kB/s Apr 26 13:57:44 volumio volumio[1375]: 0+0 records in Apr 26 13:57:44 volumio volumio[1375]: 0+0 records out Apr 26 13:57:44 volumio volumio[1375]: 0 bytes copied, 4.7537e-05 s, 0.0 kB/s Apr 26 13:57:45 volumio volumio[1375]: 0+0 records in Apr 26 13:57:45 volumio volumio[1375]: 0+0 records out Apr 26 13:57:45 volumio volumio[1375]: 0 bytes copied, 5.0167e-05 s, 0.0 kB/s Apr 26 13:57:46 volumio volumio[1375]: 0+0 records in Apr 26 13:57:46 volumio volumio[1375]: 0+0 records out Apr 26 13:57:46 volumio volumio[1375]: 0 bytes copied, 4.8889e-05 s, 0.0 kB/s Apr 26 13:57:47 volumio volumio[1375]: 0+0 records in Apr 26 13:57:47 volumio volumio[1375]: 0+0 records out Apr 26 13:57:47 volumio volumio[1375]: 0 bytes copied, 4.6982e-05 s, 0.0 kB/s Apr 26 13:57:48 volumio volumio[1375]: 0+0 records in Apr 26 13:57:48 volumio volumio[1375]: 0+0 records out Apr 26 13:57:48 volumio volumio[1375]: 0 bytes copied, 5.1519e-05 s, 0.0 kB/s Apr 26 13:57:49 volumio volumio[1375]: 0+0 records in Apr 26 13:57:49 volumio volumio[1375]: 0+0 records out Apr 26 13:57:49 volumio volumio[1375]: 0 bytes copied, 4.6389e-05 s, 0.0 kB/s Apr 26 13:57:50 volumio volumio[1375]: 0+0 records in Apr 26 13:57:50 volumio volumio[1375]: 0+0 records out Apr 26 13:57:50 volumio volumio[1375]: 0 bytes copied, 4.6185e-05 s, 0.0 kB/s Apr 26 13:57:51 volumio volumio[1375]: 0+0 records in Apr 26 13:57:51 volumio volumio[1375]: 0+0 records out Apr 26 13:57:51 volumio volumio[1375]: 0 bytes copied, 4.7723e-05 s, 0.0 kB/s Apr 26 13:57:52 volumio volumio[1375]: 0+0 records in Apr 26 13:57:52 volumio volumio[1375]: 0+0 records out Apr 26 13:57:52 volumio volumio[1375]: 0 bytes copied, 5.0759e-05 s, 0.0 kB/s Apr 26 13:57:53 volumio volumio[1375]: 0+0 records in Apr 26 13:57:53 volumio volumio[1375]: 0+0 records out Apr 26 13:57:53 volumio volumio[1375]: 0 bytes copied, 4.7092e-05 s, 0.0 kB/s Apr 26 13:57:54 volumio volumio[1375]: 0+0 records in Apr 26 13:57:54 volumio volumio[1375]: 0+0 records out Apr 26 13:57:54 volumio volumio[1375]: 0 bytes copied, 4.963e-05 s, 0.0 kB/s Apr 26 13:57:55 volumio volumio[1375]: 0+0 records in Apr 26 13:57:55 volumio volumio[1375]: 0+0 records out Apr 26 13:57:55 volumio volumio[1375]: 0 bytes copied, 4.8703e-05 s, 0.0 kB/s Apr 26 13:57:56 volumio volumio[1375]: 0+0 records in Apr 26 13:57:56 volumio volumio[1375]: 0+0 records out Apr 26 13:57:56 volumio volumio[1375]: 0 bytes copied, 4.7592e-05 s, 0.0 kB/s Apr 26 13:57:57 volumio volumio[1375]: 0+0 records in Apr 26 13:57:57 volumio volumio[1375]: 0+0 records out Apr 26 13:57:57 volumio volumio[1375]: 0 bytes copied, 5.2685e-05 s, 0.0 kB/s Apr 26 13:57:58 volumio volumio[1375]: 0+0 records in Apr 26 13:57:58 volumio volumio[1375]: 0+0 records out Apr 26 13:57:58 volumio volumio[1375]: 0 bytes copied, 4.9407e-05 s, 0.0 kB/s Apr 26 13:57:59 volumio volumio[1375]: 0+0 records in Apr 26 13:57:59 volumio volumio[1375]: 0+0 records out Apr 26 13:57:59 volumio volumio[1375]: 0 bytes copied, 5.0574e-05 s, 0.0 kB/s Apr 26 13:58:01 volumio volumio[1375]: 0+0 records in Apr 26 13:58:01 volumio volumio[1375]: 0+0 records out Apr 26 13:58:01 volumio volumio[1375]: 0 bytes copied, 4.687e-05 s, 0.0 kB/s Apr 26 13:58:02 volumio volumio[1375]: 0+0 records in Apr 26 13:58:02 volumio volumio[1375]: 0+0 records out Apr 26 13:58:02 volumio volumio[1375]: 0 bytes copied, 4.9241e-05 s, 0.0 kB/s Apr 26 13:58:03 volumio volumio[1375]: 0+0 records in Apr 26 13:58:03 volumio volumio[1375]: 0+0 records out Apr 26 13:58:03 volumio volumio[1375]: 0 bytes copied, 5.2185e-05 s, 0.0 kB/s Apr 26 13:58:04 volumio volumio[1375]: 0+0 records in Apr 26 13:58:04 volumio volumio[1375]: 0+0 records out Apr 26 13:58:04 volumio volumio[1375]: 0 bytes copied, 4.8944e-05 s, 0.0 kB/s Apr 26 13:58:05 volumio volumio[1375]: 0+0 records in Apr 26 13:58:05 volumio volumio[1375]: 0+0 records out Apr 26 13:58:05 volumio volumio[1375]: 0 bytes copied, 4.9944e-05 s, 0.0 kB/s Apr 26 13:58:06 volumio volumio[1375]: 0+0 records in Apr 26 13:58:06 volumio volumio[1375]: 0+0 records out Apr 26 13:58:06 volumio volumio[1375]: 0 bytes copied, 4.4908e-05 s, 0.0 kB/s Apr 26 13:58:07 volumio volumio[1375]: 0+0 records in Apr 26 13:58:07 volumio volumio[1375]: 0+0 records out Apr 26 13:58:07 volumio volumio[1375]: 0 bytes copied, 4.8463e-05 s, 0.0 kB/s Apr 26 13:58:08 volumio volumio[1375]: 0+0 records in Apr 26 13:58:08 volumio volumio[1375]: 0+0 records out Apr 26 13:58:08 volumio volumio[1375]: 0 bytes copied, 4.7444e-05 s, 0.0 kB/s Apr 26 13:58:09 volumio volumio[1375]: 0+0 records in Apr 26 13:58:09 volumio volumio[1375]: 0+0 records out Apr 26 13:58:09 volumio volumio[1375]: 0 bytes copied, 4.6686e-05 s, 0.0 kB/s Apr 26 13:58:10 volumio volumio[1375]: 0+0 records in Apr 26 13:58:10 volumio volumio[1375]: 0+0 records out Apr 26 13:58:10 volumio volumio[1375]: 0 bytes copied, 4.8593e-05 s, 0.0 kB/s Apr 26 13:58:11 volumio volumio[1375]: 0+0 records in Apr 26 13:58:11 volumio volumio[1375]: 0+0 records out Apr 26 13:58:11 volumio volumio[1375]: 0 bytes copied, 4.7796e-05 s, 0.0 kB/s Apr 26 13:58:12 volumio volumio[1375]: 0+0 records in Apr 26 13:58:12 volumio volumio[1375]: 0+0 records out Apr 26 13:58:12 volumio volumio[1375]: 0 bytes copied, 4.8185e-05 s, 0.0 kB/s Apr 26 13:58:13 volumio volumio[1375]: 0+0 records in Apr 26 13:58:13 volumio volumio[1375]: 0+0 records out Apr 26 13:58:13 volumio volumio[1375]: 0 bytes copied, 4.7759e-05 s, 0.0 kB/s Apr 26 13:58:14 volumio volumio[1375]: 0+0 records in Apr 26 13:58:14 volumio volumio[1375]: 0+0 records out Apr 26 13:58:14 volumio volumio[1375]: 0 bytes copied, 4.9037e-05 s, 0.0 kB/s Apr 26 13:58:15 volumio volumio[1375]: 0+0 records in Apr 26 13:58:15 volumio volumio[1375]: 0+0 records out Apr 26 13:58:15 volumio volumio[1375]: 0 bytes copied, 4.5408e-05 s, 0.0 kB/s Apr 26 13:58:16 volumio volumio[1375]: 0+0 records in Apr 26 13:58:16 volumio volumio[1375]: 0+0 records out Apr 26 13:58:16 volumio volumio[1375]: 0 bytes copied, 4.8093e-05 s, 0.0 kB/s Apr 26 13:58:17 volumio volumio[1375]: info: CoreCommandRouter::volumioPlay Apr 26 13:58:17 volumio volumio[1375]: info: CoreStateMachine::play index undefined Apr 26 13:58:17 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 13:58:17 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:58:17 volumio volumio[1375]: info: CoreStateMachine::startPlaybackTimer Apr 26 13:58:17 volumio volumio[1375]: info: CorePlayQueue::getTrack 3 Apr 26 13:58:17 volumio volumio[1375]: info: [1745690297295] ControllerTidal::resume Apr 26 13:58:17 volumio volumio[1375]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 13:58:17 volumio volumio[1375]: info: ControllerMpd::resume Apr 26 13:58:17 volumio volumio[1375]: verbose: ControllerMpd::sendMpdCommand play Apr 26 13:58:17 volumio volumio[1375]: info: sendMpdCommand play took 0 milliseconds Apr 26 13:58:17 volumio volumio[1375]: 0+0 records in Apr 26 13:58:17 volumio volumio[1375]: 0+0 records out Apr 26 13:58:17 volumio volumio[1375]: 0 bytes copied, 4.7389e-05 s, 0.0 kB/s Apr 26 13:58:18 volumio volumio[1375]: 0+0 records in Apr 26 13:58:18 volumio volumio[1375]: 0+0 records out Apr 26 13:58:18 volumio volumio[1375]: 0 bytes copied, 4.6685e-05 s, 0.0 kB/s Apr 26 13:58:19 volumio volumio[1375]: 0+0 records in Apr 26 13:58:19 volumio volumio[1375]: 0+0 records out Apr 26 13:58:19 volumio volumio[1375]: 0 bytes copied, 4.7852e-05 s, 0.0 kB/s Apr 26 13:58:20 volumio volumio[1375]: 0+0 records in Apr 26 13:58:20 volumio volumio[1375]: 0+0 records out Apr 26 13:58:20 volumio volumio[1375]: 0 bytes copied, 4.8667e-05 s, 0.0 kB/s Apr 26 13:58:21 volumio volumio[1375]: 0+0 records in Apr 26 13:58:21 volumio volumio[1375]: 0+0 records out Apr 26 13:58:21 volumio volumio[1375]: 0 bytes copied, 4.6871e-05 s, 0.0 kB/s Apr 26 13:58:22 volumio volumio[1375]: 0+0 records in Apr 26 13:58:22 volumio volumio[1375]: 0+0 records out Apr 26 13:58:22 volumio volumio[1375]: 0 bytes copied, 4.5796e-05 s, 0.0 kB/s Apr 26 13:58:23 volumio volumio[1375]: 0+0 records in Apr 26 13:58:23 volumio volumio[1375]: 0+0 records out Apr 26 13:58:23 volumio volumio[1375]: 0 bytes copied, 4.7463e-05 s, 0.0 kB/s Apr 26 13:58:24 volumio volumio[1375]: info: CoreCommandRouter::volumioGetState Apr 26 13:58:24 volumio volumio[1375]: 0+0 records in Apr 26 13:58:24 volumio volumio[1375]: 0+0 records out Apr 26 13:58:24 volumio volumio[1375]: 0 bytes copied, 4.7278e-05 s, 0.0 kB/s Apr 26 13:58:25 volumio volumio[1375]: 0+0 records in Apr 26 13:58:25 volumio volumio[1375]: 0+0 records out Apr 26 13:58:25 volumio volumio[1375]: 0 bytes copied, 4.6426e-05 s, 0.0 kB/s Apr 26 13:58:26 volumio volumio[1375]: info: Executing endpoint metavolumio Apr 26 13:58:26 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 13:58:26 volumio volumio[1375]: info: Executing endpoint metavolumio Apr 26 13:58:26 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 13:58:26 volumio volumio[1375]: info: Executing endpoint metavolumio Apr 26 13:58:26 volumio volumio[1375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 26 13:58:26 volumio volumio[1375]: 0+0 records in Apr 26 13:58:26 volumio volumio[1375]: 0+0 records out Apr 26 13:58:26 volumio volumio[1375]: 0 bytes copied, 5.2315e-05 s, 0.0 kB/s Apr 26 13:58:27 volumio volumio[1375]: 0+0 records in Apr 26 13:58:27 volumio volumio[1375]: 0+0 records out Apr 26 13:58:27 volumio volumio[1375]: 0 bytes copied, 4.6852e-05 s, 0.0 kB/s Apr 26 13:58:28 volumio volumio[1375]: 0+0 records in Apr 26 13:58:28 volumio volumio[1375]: 0+0 records out Apr 26 13:58:28 volumio volumio[1375]: 0 bytes copied, 4.9333e-05 s, 0.0 kB/s Apr 26 13:58:29 volumio volumio[1375]: 0+0 records in Apr 26 13:58:29 volumio volumio[1375]: 0+0 records out Apr 26 13:58:29 volumio volumio[1375]: 0 bytes copied, 4.8982e-05 s, 0.0 kB/s Apr 26 13:58:29 volumio volumio[1375]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Apr 26 13:58:29 volumio volumio[1375]: info: PLUGIN onShutdown : networkfs Apr 26 13:58:29 volumio volumio[1375]: info: PLUGIN onShutdown : audiophonicsonoff Apr 26 13:58:29 volumio volumio[1375]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 13:58:29 volumio volumio[1375]: TypeError: Cannot read property 'writeSync' of undefined Apr 26 13:58:29 volumio volumio[1375]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_hardware/audiophonicsonoff/index.js:48:25) Apr 26 13:58:29 volumio volumio[1375]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Apr 26 13:58:29 volumio volumio[1375]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Apr 26 13:58:29 volumio volumio[1375]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Apr 26 13:58:29 volumio volumio[1375]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Apr 26 13:58:29 volumio volumio[1375]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Apr 26 13:58:29 volumio volumio[1375]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1328:22) Apr 26 13:58:29 volumio volumio[1375]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Apr 26 13:58:29 volumio volumio[1375]: at Socket.emit (events.js:315:20) Apr 26 13:58:29 volumio volumio[1375]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 26 13:58:29 volumio volumio[1375]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Apr 26 13:58:29 volumio volumio[1375]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 13:58:30 volumio sudo[11825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 13:57 Apr 26 13:58:30 volumio sudo[11825]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"