-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sun 2025-01-19 11:38:52 EET. -- Jan 19 11:37:02 volumio-rpi volumiologrotate[640]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 19 11:37:02 volumio-rpi volumiologrotate[640]: ls: cannot access 'RPI': No such file or directory Jan 19 11:37:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 11:37:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 11:37:24 volumio-rpi volumio[5475]: info: Discovery: Getting this device information Jan 19 11:37:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioGetState Jan 19 11:37:24 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:37:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 11:37:27 volumio-rpi volumio[5475]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 11:37:31 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 11:37:31 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 19 11:37:31 volumio-rpi volumio[5475]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 11:37:38 volumio-rpi volumio[5475]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 11:37:38 volumio-rpi volumio[5475]: info: [ASDebug] Setting UI defaults Jan 19 11:37:38 volumio-rpi volumio[5475]: info: [ASDebug] Port: 24 Jan 19 11:37:38 volumio-rpi volumio[5475]: info: [ASDebug] Inverted: false Jan 19 11:37:38 volumio-rpi volumio[5475]: info: [ASDebug] Latched: false Jan 19 11:37:38 volumio-rpi volumio[5475]: info: [ASDebug] On pulse width: 500 Jan 19 11:37:38 volumio-rpi volumio[5475]: info: [ASDebug] Off pulse width: 500 Jan 19 11:37:54 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 11:37:54 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 11:37:54 volumio-rpi volumio[5475]: info: Discovery: Getting this device information Jan 19 11:37:54 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioGetState Jan 19 11:37:54 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:37:54 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 11:38:03 volumio-rpi volumiologrotate[640]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 19 11:38:03 volumio-rpi volumiologrotate[640]: ls: cannot access 'RPI': No such file or directory Jan 19 11:38:15 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 11:38:15 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 19 11:38:20 volumio-rpi volumio[5475]: info: [ASDebug] Port: 24 Jan 19 11:38:20 volumio-rpi volumio[5475]: info: [ASDebug] Inverted: false Jan 19 11:38:20 volumio-rpi volumio[5475]: info: [ASDebug] Delay: 720 Jan 19 11:38:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 11:38:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 11:38:24 volumio-rpi volumio[5475]: info: Discovery: Getting this device information Jan 19 11:38:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioGetState Jan 19 11:38:24 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:24 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Stopping peppyspectrum service Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreStateMachine::stop Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Disabling plugin peppyspectrum Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Preparing to generate the ALSA configuration file Jan 19 11:38:33 volumio-rpi sudo[6430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Jan 19 11:38:33 volumio-rpi sudo[6430]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi volumio[5475]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 19 11:38:33 volumio-rpi volumio[5475]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Reading ALSA contributions from plugins. Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Asound.conf file written Jan 19 11:38:33 volumio-rpi sudo[6430]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:33 volumio-rpi sudo[6433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jan 19 11:38:33 volumio-rpi sudo[6433]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi sudo[6433]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:33 volumio-rpi volumio[5475]: xcb_connection_has_error() returned true Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Output device has changed, restarting MPD Jan 19 11:38:33 volumio-rpi sudo[6438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 11:38:33 volumio-rpi sudo[6438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi sudo[6438]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Output device has changed, restarting Shairport Sync Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 11:38:33 volumio-rpi sudo[6441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 11:38:33 volumio-rpi sudo[6441]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi systemd[1]: musicservicesshield.service: Succeeded. Jan 19 11:38:33 volumio-rpi systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 19 11:38:33 volumio-rpi systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 19 11:38:33 volumio-rpi systemd[1]: Stopping Music Player Daemon... Jan 19 11:38:33 volumio-rpi systemd[1]: mpd.service: Succeeded. Jan 19 11:38:33 volumio-rpi volumio[5475]: xcb_connection_has_error() returned true Jan 19 11:38:33 volumio-rpi systemd[1]: Stopped Music Player Daemon. Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 11:38:33 volumio-rpi volumio[5475]: xcb_connection_has_error() returned true Jan 19 11:38:33 volumio-rpi volumio[5475]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi vtcs[6083]: [2025-01-19 11:38:33.863] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jan 19 11:38:33 volumio-rpi vtcs[6083]: [2025-01-19 11:38:33.864] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jan 19 11:38:33 volumio-rpi volumio[5475]: info: Done. Jan 19 11:38:33 volumio-rpi systemd[1]: Starting Music Player Daemon... Jan 19 11:38:33 volumio-rpi sudo[6450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 19 11:38:33 volumio-rpi sudo[6452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 19 11:38:33 volumio-rpi sudo[6450]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi sudo[6452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 11:38:33 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 11:38:33 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 11:38:33 volumio-rpi volumio[5475]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jan 19 11:38:33 volumio-rpi volumio[5475]: info: MPD Permissions set Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:33 volumio-rpi sudo[6446]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 11:38:33 volumio-rpi sudo[6446]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:33 volumio-rpi systemd[1]: Stopping Volumio Tidal Connect Service... Jan 19 11:38:33 volumio-rpi systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jan 19 11:38:33 volumio-rpi systemd[1]: vtcs.service: Succeeded. Jan 19 11:38:33 volumio-rpi systemd[1]: Stopped Volumio Tidal Connect Service. Jan 19 11:38:33 volumio-rpi sudo[6446]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:34 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 11:38:34 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 11:38:34 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 11:38:34 volumio-rpi sudo[6450]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:34 volumio-rpi sudo[6452]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:34 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 11:38:34 volumio-rpi volumio[5475]: info: Starting Shairport Sync Jan 19 11:38:34 volumio-rpi sudo[6464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 11:38:34 volumio-rpi sudo[6464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:34 volumio-rpi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 11:38:34 volumio-rpi systemd[1]: shairport-sync.service: Succeeded. Jan 19 11:38:34 volumio-rpi systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 19 11:38:34 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 11:38:34 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jan 19 11:38:34 volumio-rpi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 11:38:34 volumio-rpi sudo[6464]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:34 volumio-rpi volumio[5475]: info: Shairport-Sync Started Jan 19 11:38:34 volumio-rpi mpd[6457]: Jan 19 11:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 11:38:34 volumio-rpi systemd[1]: Started Music Player Daemon. Jan 19 11:38:34 volumio-rpi sudo[6441]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:34 volumio-rpi volumio[5475]: error: updateQueue error: null Jan 19 11:38:34 volumio-rpi systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 19 11:38:34 volumio-rpi volumio[5475]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 19 11:38:35 volumio-rpi volumio[6471]: cset: --> shielding system active with Jan 19 11:38:35 volumio-rpi volumio[6471]: cset: "system" cpuset of CPUSPEC(0-2) with 149 tasks running Jan 19 11:38:35 volumio-rpi volumio[6471]: cset: "user" cpuset of CPUSPEC(3) with 3 tasks running Jan 19 11:38:35 volumio-rpi systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 19 11:38:37 volumio-rpi volumio[5475]: info: TidalConnect service stoped! Jan 19 11:38:37 volumio-rpi volumio[5475]: info: TidalConnect service stoped! Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 19 11:38:37 volumio-rpi sudo[6501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 19 11:38:37 volumio-rpi sudo[6501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 11:38:37 volumio-rpi systemd[1]: Started Volumio Tidal Connect Service. Jan 19 11:38:37 volumio-rpi sudo[6501]: pam_unix(sudo:session): session closed for user root Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Executing endpoint tc_getconfig Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 19 11:38:37 volumio-rpi vtcs[6503]: STARTING TidalConnect services, version: 1.4.0.34 Jan 19 11:38:37 volumio-rpi vtcs[6503]: STARTED TidalConnect services. Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Executing endpoint tc_connect Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Connecting to TidalConnect Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreCommandRouter::servicePushState Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreStateMachine::pushState Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPushState Jan 19 11:38:37 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output update for this device Jan 19 11:38:37 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreCommandRouter::servicePushState Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreStateMachine::pushState Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPushState Jan 19 11:38:37 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output update for this device Jan 19 11:38:37 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output Jan 19 11:38:37 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:37 volumio-rpi volumio[5475]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jan 19 11:38:37 volumio-rpi volumio[5475]: info: [ASDebug] CurState: stop PrevState: stop Jan 19 11:38:37 volumio-rpi volumio[5475]: info: [ASDebug] CurState: stop PrevState: stop Jan 19 11:38:40 volumio-rpi volumio[5475]: info: TidalConnect service started! Jan 19 11:38:46 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioGetState Jan 19 11:38:46 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:49 volumio-rpi volumio[5475]: info: Executing endpoint metavolumio Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 19 11:38:49 volumio-rpi volumio[5475]: info: Preload queue cleared Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::ClearQueue Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::stop Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::clearPlayQueue Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::saveQueue Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPushQueue Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::addQueueItems Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::addQueueItems Jan 19 11:38:49 volumio-rpi volumio[5475]: info: Preload queue cleared Jan 19 11:38:49 volumio-rpi volumio[5475]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1852944 Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPushQueue Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::saveQueue Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::updateTrackBlock Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrackBlock Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPlay Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::play index 0 Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::stop Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::play index undefined Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreStateMachine::startPlaybackTimer Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 11:38:49 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 19 11:38:49 volumio-rpi volumio[5475]: info: [1737279529838] ControllerWebradio::clearAddPlayTrack Jan 19 11:38:49 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand stop Jan 19 11:38:49 volumio-rpi volumio[5475]: info: sendMpdCommand stop took 5 milliseconds Jan 19 11:38:49 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand clear Jan 19 11:38:49 volumio-rpi volumio[5475]: info: Jan 19 11:38:49 volumio-rpi volumio[5475]: ---------------------------- MPD announces system playlist update Jan 19 11:38:49 volumio-rpi volumio[5475]: info: Ignoring MPD Status Update Jan 19 11:38:49 volumio-rpi volumio[5475]: info: sendMpdCommand clear took 2 milliseconds Jan 19 11:38:49 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1852944" Jan 19 11:38:49 volumio-rpi volumio[5475]: error: updateQueue error: null Jan 19 11:38:49 volumio-rpi volumio[5475]: info: ------------------------------ 4ms Jan 19 11:38:50 volumio-rpi volumio[5475]: info: Jan 19 11:38:50 volumio-rpi volumio[5475]: ---------------------------- MPD announces system playlist update Jan 19 11:38:50 volumio-rpi volumio[5475]: info: Ignoring MPD Status Update Jan 19 11:38:50 volumio-rpi volumio[5475]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1852944" took 591 milliseconds Jan 19 11:38:50 volumio-rpi volumio[5475]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 19 11:38:50 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand play Jan 19 11:38:50 volumio-rpi volumio[5475]: info: ------------------------------ 4ms Jan 19 11:38:50 volumio-rpi volumio[5475]: info: sendMpdCommand play took 2 milliseconds Jan 19 11:38:51 volumio-rpi volumio[5475]: info: Jan 19 11:38:51 volumio-rpi volumio[5475]: ---------------------------- MPD announces state update: player Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ControllerMpd::getState Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand status Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 19 11:38:51 volumio-rpi volumio[5475]: info: Jan 19 11:38:51 volumio-rpi volumio[5475]: ---------------------------- MPD announces state update: player Jan 19 11:38:51 volumio-rpi volumio[5475]: info: sendMpdCommand status took 25 milliseconds Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ControllerMpd::getState Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand status Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::parseState Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 11:38:51 volumio-rpi volumio[5475]: info: sendMpdCommand status took 2 milliseconds Jan 19 11:38:51 volumio-rpi volumio[5475]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::parseState Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::parseTrackInfo Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ControllerMpd::pushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreCommandRouter::servicePushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"smoothjazz_64a","artist":"1.FM - Bay Smooth Jazz (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/smoothjazz_64a","trackType":""} Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: CURRENT POSITION 0 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreStateMachine::syncState stateService play Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreStateMachine::syncState currentStatus stop Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ------------------------------ 35ms Jan 19 11:38:51 volumio-rpi volumio[5475]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::parseTrackInfo Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ControllerMpd::pushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreCommandRouter::servicePushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"smoothjazz_64a","artist":"1.FM - Bay Smooth Jazz (www.1.fm)","album":null,"uri":"http://185.33.21.112:80/smoothjazz_64a","trackType":""} Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: CURRENT POSITION 0 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreStateMachine::syncState stateService play Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreStateMachine::syncState currentStatus play Jan 19 11:38:51 volumio-rpi volumio[5475]: info: Received an update from plugin. extracting info from payload Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreStateMachine::pushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CorePlayQueue::getTrack 0 Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output update for this device Jan 19 11:38:51 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreStateMachine::pushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: CoreCommandRouter::volumioPushState Jan 19 11:38:51 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output update for this device Jan 19 11:38:51 volumio-rpi volumio[5475]: info: MRS: Pushing multiroomSync output Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ------------------------------ 60ms Jan 19 11:38:51 volumio-rpi volumio[5475]: info: Jan 19 11:38:51 volumio-rpi volumio[5475]: ---------------------------- MPD announces system playlist update Jan 19 11:38:51 volumio-rpi volumio[5475]: info: Ignoring MPD Status Update Jan 19 11:38:51 volumio-rpi volumio[5475]: info: Jan 19 11:38:51 volumio-rpi volumio[5475]: ---------------------------- MPD announces state update: player Jan 19 11:38:51 volumio-rpi volumio[5475]: info: ControllerMpd::getState Jan 19 11:38:51 volumio-rpi volumio[5475]: verbose: ControllerMpd::sendMpdCommand status Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 19 11:38:51 volumio-rpi volumio[5475]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 19 11:38:51 volumio-rpi volumio[5475]: info: [ASDebug] CurState: play PrevState: stop Jan 19 11:38:51 volumio-rpi volumio[5475]: info: [ASDebug] Togle GPIO: ON Jan 19 11:38:51 volumio-rpi volumio[5475]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 11:38:51 volumio-rpi volumio[5475]: Error: ESPIPE: invalid seek, write Jan 19 11:38:51 volumio-rpi volumio[5475]: at Object.writeSync (fs.js:711:3) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jan 19 11:38:51 volumio-rpi volumio[5475]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Jan 19 11:38:51 volumio-rpi volumio[5475]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Jan 19 11:38:51 volumio-rpi volumio[5475]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Jan 19 11:38:51 volumio-rpi volumio[5475]: errno: -29, Jan 19 11:38:51 volumio-rpi volumio[5475]: syscall: 'write', Jan 19 11:38:51 volumio-rpi volumio[5475]: code: 'ESPIPE' Jan 19 11:38:51 volumio-rpi volumio[5475]: } Jan 19 11:38:51 volumio-rpi volumio[5475]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 11:38:52 volumio-rpi sudo[6572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 11:37 Jan 19 11:38:52 volumio-rpi sudo[6572]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"