-- Logs begin at Fri 2025-02-07 17:25:47 PST, end at Sat 2025-02-15 10:02:49 PST. -- Feb 15 10:01:00 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:00 volumio volumio[12939]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Feb 15 10:01:00 volumio sudo[13197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 10:01:00 volumio sudo[13197]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 10:01:00 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:00 volumio sudo[13197]: pam_unix(sudo:session): session closed for user root Feb 15 10:01:00 volumio sudo[13199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 10:01:00 volumio sudo[13199]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 10:01:00 volumio sudo[13199]: pam_unix(sudo:session): session closed for user root Feb 15 10:01:00 volumio sudo[13203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 15 10:01:00 volumio sudo[13203]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 10:01:00 volumio sudo[13203]: pam_unix(sudo:session): session closed for user root Feb 15 10:01:00 volumio volumio[12939]: info: Upmpdcli Daemon Started Feb 15 10:01:01 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:01 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:02 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:02 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:02 volumio volumio[12939]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 15 10:01:03 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:04 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 15 10:01:05 volumio volumio[12939]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.779&uuid=c61532f1b079194a912304e73877f623" http://updates.volumio.org/downloader-v1/track-device Feb 15 10:01:05 volumio volumio[12939]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 10:01:05 volumio volumio[12939]: Dload Upload Total Spent Left Speed Feb 15 10:01:05 volumio volumio[12939]: [158B blob data] Feb 15 10:01:05 volumio volumio[12939]: curl: (7) Failed to connect to updates.volumio.org port 80: Connection refused Feb 15 10:01:05 volumio volumio[12939]: retrying in 5 seconds, trial 0 Feb 15 10:01:05 volumio volumio[12939]: info: Volumio Calling Home Feb 15 10:01:05 volumio volumio[12939]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds Feb 15 10:01:10 volumio volumio[12939]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Feb 15 10:01:10 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Feb 15 10:01:10 volumio volumio[12939]: info: [ASDebug] Saving Settings: Port: 24 Feb 15 10:01:10 volumio volumio[12939]: info: [ASDebug] Saving Settings: Inverted: false Feb 15 10:01:10 volumio volumio[12939]: info: [ASDebug] Saving Settings: Delay: 300000 Feb 15 10:01:10 volumio volumio[12939]: info: [ASDebug] Saving Settings: Latched: false Feb 15 10:01:10 volumio volumio[12939]: info: [ASDebug] Saving Settings: On Pulse width: 500 Feb 15 10:01:10 volumio volumio[12939]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Feb 15 10:01:10 volumio volumio[12939]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Feb 15 10:01:16 volumio volumio[12939]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.779&uuid=c61532f1b079194a912304e73877f623" http://updates.volumio.org/downloader-v1/track-device Feb 15 10:01:16 volumio volumio[12939]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 10:01:16 volumio volumio[12939]: Dload Upload Total Spent Left Speed Feb 15 10:01:16 volumio volumio[12939]: [158B blob data] Feb 15 10:01:16 volumio volumio[12939]: curl: (7) Failed to connect to updates.volumio.org port 80: Connection refused Feb 15 10:01:16 volumio volumio[12939]: retrying in 5 seconds, trial 1 Feb 15 10:01:16 volumio volumio[12939]: info: Volumio Calling Home Feb 15 10:01:16 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 10:01:17 volumio volumio[12939]: error: MyVolumio Plugin failed to start in a timely fashion Feb 15 10:01:17 volumio volumio[12939]: [Metrics] CommandRouter: 28s 996.26ms Feb 15 10:01:17 volumio volumio[12939]: info: CoreCommandRouter::volumiosetStartupVolume Feb 15 10:01:17 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 10:01:17 volumio volumio[12939]: info: VolumeController:: Setting startup Volume 5 Feb 15 10:01:17 volumio volumio[12939]: info: VolumeController::SetAlsaVolume5 Feb 15 10:01:17 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 10:01:17 volumio volumio[12939]: info: CoreCommandRouter::Close All Modals sent Feb 15 10:01:17 volumio volumio[12939]: info: CoreCommandRouter::Close All Modals sent Feb 15 10:01:17 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:01:17 volumio volumio[12939]: info: CorePlayQueue::getTrack 0 Feb 15 10:01:17 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:01:17 volumio volumio[12939]: info: [ASDebug] CurState: stop PrevState: na Feb 15 10:01:17 volumio volumio[12939]: info: [ASDebug] InitTimeout - Amp off in: 300000 ms Feb 15 10:01:17 volumio volumio[12939]: info: [GPIO_Control] Status changed: stop Feb 15 10:01:18 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 15 10:01:18 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 15 10:01:18 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 15 10:01:21 volumio volumio[12939]: info: [ASDebug] Port: 24 Feb 15 10:01:21 volumio volumio[12939]: info: [ASDebug] Inverted: false Feb 15 10:01:21 volumio volumio[12939]: info: [ASDebug] Delay: 300000 Feb 15 10:01:24 volumio volumio[12939]: info: BOOT COMPLETED Feb 15 10:01:25 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 10:01:25 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 10:01:25 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 15 10:01:26 volumio volumio[12939]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.779&uuid=c61532f1b079194a912304e73877f623" http://updates.volumio.org/downloader-v1/track-device Feb 15 10:01:26 volumio volumio[12939]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 15 10:01:26 volumio volumio[12939]: Dload Upload Total Spent Left Speed Feb 15 10:01:26 volumio volumio[12939]: [158B blob data] Feb 15 10:01:26 volumio volumio[12939]: curl: (7) Failed to connect to updates.volumio.org port 80: Connection refused Feb 15 10:01:26 volumio volumio[12939]: retrying in 5 seconds, trial 2 Feb 15 10:01:26 volumio volumio[12939]: info: Volumio Calling Home Feb 15 10:01:26 volumio ntpd[815]: Soliciting pool server 2602:f9bd:80:100::a Feb 15 10:01:28 volumio volumio[12939]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 10:01:55 volumio volumio[12939]: info: [GPIO_Control] Turning GPIO 26 Off (musicStop) Feb 15 10:02:17 volumio volumio[12939]: info: CALLMETHOD: system_hardware gpio_control saveConfig [object Object] Feb 15 10:02:17 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: gpio_control , saveConfig Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Destroying GPIO 26 Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Destroying GPIO 26 Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Destroying GPIO 26 Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Saving config Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Reading config and creating GPIOs Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] On musicPlay will set GPIO 26 to On after 2 Seconds delay Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] On musicPause will set GPIO 26 to Off after 2 Minutes delay Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] On musicStop will set GPIO 26 to Off after 2 Minutes delay Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Handling event: systemStartup Feb 15 10:02:17 volumio volumio[12939]: info: CoreCommandRouter::volumioGetState Feb 15 10:02:17 volumio volumio[12939]: info: CorePlayQueue::getTrack 0 Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Status changed: stop Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Handling event: musicStop Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] *** musicStop *** Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Delaying: 120000ms Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Handling event: repeatAllOff Feb 15 10:02:17 volumio volumio[12939]: info: [GPIO_Control] Handling event: repeatOff Feb 15 10:02:25 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 10:02:29 volumio volumio[12939]: info: CoreCommandRouter::Close All Modals sent Feb 15 10:02:33 volumio ntpd[815]: Soliciting pool server 2603:c020:0:8369:1111:1111:1111:1112 Feb 15 10:02:38 volumio volumio[12939]: info: CoreCommandRouter::volumioGetState Feb 15 10:02:38 volumio volumio[12939]: info: CorePlayQueue::getTrack 0 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPlay Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::play index 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::stop Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::play index undefined Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::startPlaybackTimer Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::clearAddPlayTracks NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand stop Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand stop took 4 milliseconds Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand clear Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces system playlist update Feb 15 10:02:48 volumio volumio[12939]: info: Ignoring MPD Status Update Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand clear took 3 milliseconds Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand add "NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav" Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces system playlist update Feb 15 10:02:48 volumio volumio[12939]: info: Ignoring MPD Status Update Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces system playlist update Feb 15 10:02:48 volumio volumio[12939]: info: Ignoring MPD Status Update Feb 15 10:02:48 volumio volumio[12939]: error: updateQueue error: null Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces system playlist update Feb 15 10:02:48 volumio volumio[12939]: info: Ignoring MPD Status Update Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 14ms Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand add "NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav" took 10 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 9ms Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 7ms Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand play Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces system playlist update Feb 15 10:02:48 volumio volumio[12939]: info: Ignoring MPD Status Update Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces system playlist update Feb 15 10:02:48 volumio volumio[12939]: info: Ignoring MPD Status Update Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 16ms Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand play took 8 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 8ms Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 5ms Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces state update: player Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::getState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand status Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces state update: player Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::getState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand status Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces state update: player Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::getState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand status Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand status took 49 milliseconds Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces state update: player Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::getState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand status Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand status took 59 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand status took 56 milliseconds Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces state update: player Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::getState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand status Feb 15 10:02:48 volumio volumio[12939]: info: Feb 15 10:02:48 volumio volumio[12939]: ---------------------------- MPD announces state update: player Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::getState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand status Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand status took 13 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand status took 8 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand status took 6 milliseconds Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseTrackInfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseTrackInfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseTrackInfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseState Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::servicePushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AI Jazz v2_Render-2.wav","artist":null,"album":null,"uri":"NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav","trackType":"wav"} Feb 15 10:02:48 volumio volumio[12939]: verbose: CURRENT POSITION 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState stateService play Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState currentStatus stop Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::servicePushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AI Jazz v2_Render-2.wav","artist":null,"album":null,"uri":"NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav","trackType":"wav"} Feb 15 10:02:48 volumio volumio[12939]: verbose: CURRENT POSITION 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState stateService play Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState currentStatus play Feb 15 10:02:48 volumio volumio[12939]: info: Received an update from plugin. extracting info from payload Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::servicePushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":139,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AI Jazz v2_Render-2.wav","artist":null,"album":null,"uri":"NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav","trackType":"wav"} Feb 15 10:02:48 volumio volumio[12939]: verbose: CURRENT POSITION 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState stateService play Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState currentStatus play Feb 15 10:02:48 volumio volumio[12939]: info: Received an update from plugin. extracting info from payload Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 128ms Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 159ms Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 157ms Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand playlistinfo took 82 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand playlistinfo took 80 milliseconds Feb 15 10:02:48 volumio volumio[12939]: info: sendMpdCommand playlistinfo took 80 milliseconds Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseTrackInfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseTrackInfo Feb 15 10:02:48 volumio volumio[12939]: verbose: ControllerMpd::parseTrackInfo Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::servicePushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":139,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AI Jazz v2_Render-2.wav","artist":null,"album":null,"uri":"NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav","trackType":"wav"} Feb 15 10:02:48 volumio volumio[12939]: verbose: CURRENT POSITION 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState stateService play Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState currentStatus play Feb 15 10:02:48 volumio volumio[12939]: info: Received an update from plugin. extracting info from payload Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::servicePushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":139,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AI Jazz v2_Render-2.wav","artist":null,"album":null,"uri":"NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav","trackType":"wav"} Feb 15 10:02:48 volumio volumio[12939]: verbose: CURRENT POSITION 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState stateService play Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState currentStatus play Feb 15 10:02:48 volumio volumio[12939]: info: Received an update from plugin. extracting info from payload Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: ControllerMpd::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::servicePushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":139,"samplerate":"44.1 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"AI Jazz v2_Render-2.wav","artist":null,"album":null,"uri":"NAS/Music/ThinkingSand/AI Jazz v2_Render-2.wav","trackType":"wav"} Feb 15 10:02:48 volumio volumio[12939]: verbose: CURRENT POSITION 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState stateService play Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::syncState currentStatus play Feb 15 10:02:48 volumio volumio[12939]: info: Received an update from plugin. extracting info from payload Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: CoreStateMachine::pushState Feb 15 10:02:48 volumio volumio[12939]: info: CorePlayQueue::getTrack 1 Feb 15 10:02:48 volumio volumio[12939]: info: CoreCommandRouter::volumioPushState Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 188ms Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 176ms Feb 15 10:02:48 volumio volumio[12939]: info: ------------------------------ 174ms Feb 15 10:02:48 volumio volumio[12939]: info: [ASDebug] CurState: play PrevState: na Feb 15 10:02:48 volumio volumio[12939]: info: [ASDebug] Togle GPIO: ON Feb 15 10:02:48 volumio volumio[12939]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 10:02:48 volumio volumio[12939]: Error: ESPIPE: invalid seek, write Feb 15 10:02:48 volumio volumio[12939]: at Object.writeSync (fs.js:711:3) Feb 15 10:02:48 volumio volumio[12939]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 15 10:02:48 volumio volumio[12939]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Feb 15 10:02:48 volumio volumio[12939]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Feb 15 10:02:48 volumio volumio[12939]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Feb 15 10:02:48 volumio volumio[12939]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Feb 15 10:02:48 volumio volumio[12939]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Feb 15 10:02:48 volumio volumio[12939]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Feb 15 10:02:48 volumio volumio[12939]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Feb 15 10:02:48 volumio volumio[12939]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Feb 15 10:02:48 volumio volumio[12939]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Feb 15 10:02:48 volumio volumio[12939]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Feb 15 10:02:48 volumio volumio[12939]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Feb 15 10:02:48 volumio volumio[12939]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Feb 15 10:02:48 volumio volumio[12939]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Feb 15 10:02:48 volumio volumio[12939]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Feb 15 10:02:48 volumio volumio[12939]: errno: -29, Feb 15 10:02:48 volumio volumio[12939]: syscall: 'write', Feb 15 10:02:48 volumio volumio[12939]: code: 'ESPIPE' Feb 15 10:02:48 volumio volumio[12939]: } Feb 15 10:02:48 volumio volumio[12939]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 10:02:49 volumio sudo[13455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 10:01 Feb 15 10:02:49 volumio sudo[13455]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"