-- Logs begin at Thu 2019-02-14 15:41:58 IST, end at Sun 2025-06-01 09:10:05 IST. -- Jun 01 09:09:00 volumio volumio[1014]: 0+0 records in Jun 01 09:09:00 volumio volumio[1014]: 0+0 records out Jun 01 09:09:00 volumio volumio[1014]: 0 bytes copied, 0.000100369 s, 0.0 kB/s Jun 01 09:09:01 volumio volumio[1014]: 0+0 records in Jun 01 09:09:01 volumio volumio[1014]: 0+0 records out Jun 01 09:09:01 volumio volumio[1014]: 0 bytes copied, 9.7906e-05 s, 0.0 kB/s Jun 01 09:09:02 volumio volumio[1014]: 0+0 records in Jun 01 09:09:02 volumio volumio[1014]: 0+0 records out Jun 01 09:09:02 volumio volumio[1014]: 0 bytes copied, 9.8499e-05 s, 0.0 kB/s Jun 01 09:09:03 volumio volumio[1014]: 0+0 records in Jun 01 09:09:03 volumio volumio[1014]: 0+0 records out Jun 01 09:09:03 volumio volumio[1014]: 0 bytes copied, 0.000115591 s, 0.0 kB/s Jun 01 09:09:04 volumio volumio[1014]: 0+0 records in Jun 01 09:09:04 volumio volumio[1014]: 0+0 records out Jun 01 09:09:04 volumio volumio[1014]: 0 bytes copied, 0.000100924 s, 0.0 kB/s Jun 01 09:09:06 volumio volumio[1014]: 0+0 records in Jun 01 09:09:06 volumio volumio[1014]: 0+0 records out Jun 01 09:09:06 volumio volumio[1014]: 0 bytes copied, 0.00011435 s, 0.0 kB/s Jun 01 09:09:07 volumio volumio[1014]: 0+0 records in Jun 01 09:09:07 volumio volumio[1014]: 0+0 records out Jun 01 09:09:07 volumio volumio[1014]: 0 bytes copied, 9.9035e-05 s, 0.0 kB/s Jun 01 09:09:08 volumio volumio[1014]: 0+0 records in Jun 01 09:09:08 volumio volumio[1014]: 0+0 records out Jun 01 09:09:08 volumio volumio[1014]: 0 bytes copied, 0.000100147 s, 0.0 kB/s Jun 01 09:09:09 volumio volumio[1014]: 0+0 records in Jun 01 09:09:09 volumio volumio[1014]: 0+0 records out Jun 01 09:09:09 volumio volumio[1014]: 0 bytes copied, 9.7777e-05 s, 0.0 kB/s Jun 01 09:09:10 volumio volumio[1014]: 0+0 records in Jun 01 09:09:10 volumio volumio[1014]: 0+0 records out Jun 01 09:09:10 volumio volumio[1014]: 0 bytes copied, 9.898e-05 s, 0.0 kB/s Jun 01 09:09:11 volumio volumio[1014]: 0+0 records in Jun 01 09:09:11 volumio volumio[1014]: 0+0 records out Jun 01 09:09:11 volumio volumio[1014]: 0 bytes copied, 0.000100072 s, 0.0 kB/s Jun 01 09:09:12 volumio volumio[1014]: 0+0 records in Jun 01 09:09:12 volumio volumio[1014]: 0+0 records out Jun 01 09:09:12 volumio volumio[1014]: 0 bytes copied, 0.000100536 s, 0.0 kB/s Jun 01 09:09:13 volumio volumio[1014]: 0+0 records in Jun 01 09:09:13 volumio volumio[1014]: 0+0 records out Jun 01 09:09:13 volumio volumio[1014]: 0 bytes copied, 0.000114054 s, 0.0 kB/s Jun 01 09:09:14 volumio volumio[1014]: 0+0 records in Jun 01 09:09:14 volumio volumio[1014]: 0+0 records out Jun 01 09:09:14 volumio volumio[1014]: 0 bytes copied, 0.000100684 s, 0.0 kB/s Jun 01 09:09:15 volumio volumio[1014]: 0+0 records in Jun 01 09:09:15 volumio volumio[1014]: 0+0 records out Jun 01 09:09:15 volumio volumio[1014]: 0 bytes copied, 9.8424e-05 s, 0.0 kB/s Jun 01 09:09:16 volumio volumio[1014]: 0+0 records in Jun 01 09:09:16 volumio volumio[1014]: 0+0 records out Jun 01 09:09:16 volumio volumio[1014]: 0 bytes copied, 9.8981e-05 s, 0.0 kB/s Jun 01 09:09:17 volumio volumio[1014]: 0+0 records in Jun 01 09:09:17 volumio volumio[1014]: 0+0 records out Jun 01 09:09:17 volumio volumio[1014]: 0 bytes copied, 9.9147e-05 s, 0.0 kB/s Jun 01 09:09:18 volumio volumio[1014]: 0+0 records in Jun 01 09:09:18 volumio volumio[1014]: 0+0 records out Jun 01 09:09:18 volumio volumio[1014]: 0 bytes copied, 9.9906e-05 s, 0.0 kB/s Jun 01 09:09:19 volumio volumio[1014]: 0+0 records in Jun 01 09:09:19 volumio volumio[1014]: 0+0 records out Jun 01 09:09:19 volumio volumio[1014]: 0 bytes copied, 9.9406e-05 s, 0.0 kB/s Jun 01 09:09:20 volumio volumio[1014]: 0+0 records in Jun 01 09:09:20 volumio volumio[1014]: 0+0 records out Jun 01 09:09:20 volumio volumio[1014]: 0 bytes copied, 9.9017e-05 s, 0.0 kB/s Jun 01 09:09:21 volumio volumio[1014]: 0+0 records in Jun 01 09:09:21 volumio volumio[1014]: 0+0 records out Jun 01 09:09:21 volumio volumio[1014]: 0 bytes copied, 9.8369e-05 s, 0.0 kB/s Jun 01 09:09:22 volumio volumio[1014]: 0+0 records in Jun 01 09:09:22 volumio volumio[1014]: 0+0 records out Jun 01 09:09:22 volumio volumio[1014]: 0 bytes copied, 9.948e-05 s, 0.0 kB/s Jun 01 09:09:23 volumio volumio[1014]: 0+0 records in Jun 01 09:09:23 volumio volumio[1014]: 0+0 records out Jun 01 09:09:23 volumio volumio[1014]: 0 bytes copied, 0.000114832 s, 0.0 kB/s Jun 01 09:09:24 volumio volumio[1014]: 0+0 records in Jun 01 09:09:24 volumio volumio[1014]: 0+0 records out Jun 01 09:09:24 volumio volumio[1014]: 0 bytes copied, 9.7924e-05 s, 0.0 kB/s Jun 01 09:09:25 volumio volumio[1014]: 0+0 records in Jun 01 09:09:25 volumio volumio[1014]: 0+0 records out Jun 01 09:09:25 volumio volumio[1014]: 0 bytes copied, 0.000100628 s, 0.0 kB/s Jun 01 09:09:26 volumio volumio[1014]: 0+0 records in Jun 01 09:09:26 volumio volumio[1014]: 0+0 records out Jun 01 09:09:26 volumio volumio[1014]: 0 bytes copied, 0.000100665 s, 0.0 kB/s Jun 01 09:09:27 volumio volumio[1014]: 0+0 records in Jun 01 09:09:27 volumio volumio[1014]: 0+0 records out Jun 01 09:09:27 volumio volumio[1014]: 0 bytes copied, 0.000116036 s, 0.0 kB/s Jun 01 09:09:28 volumio volumio[1014]: 0+0 records in Jun 01 09:09:28 volumio volumio[1014]: 0+0 records out Jun 01 09:09:28 volumio volumio[1014]: 0 bytes copied, 0.000116331 s, 0.0 kB/s Jun 01 09:09:29 volumio volumio[1014]: 0+0 records in Jun 01 09:09:29 volumio volumio[1014]: 0+0 records out Jun 01 09:09:29 volumio volumio[1014]: 0 bytes copied, 9.8961e-05 s, 0.0 kB/s Jun 01 09:09:30 volumio volumio[1014]: 0+0 records in Jun 01 09:09:30 volumio volumio[1014]: 0+0 records out Jun 01 09:09:30 volumio volumio[1014]: 0 bytes copied, 0.000100313 s, 0.0 kB/s Jun 01 09:09:31 volumio volumio[1014]: 0+0 records in Jun 01 09:09:31 volumio volumio[1014]: 0+0 records out Jun 01 09:09:31 volumio volumio[1014]: 0 bytes copied, 9.8369e-05 s, 0.0 kB/s Jun 01 09:09:32 volumio volumio[1014]: 0+0 records in Jun 01 09:09:32 volumio volumio[1014]: 0+0 records out Jun 01 09:09:32 volumio volumio[1014]: 0 bytes copied, 9.9351e-05 s, 0.0 kB/s Jun 01 09:09:33 volumio volumio[1014]: info: Clearing queue after UPNP request Jun 01 09:09:33 volumio volumio[1014]: verbose: UNSET VOLATILE: Service: airplay_emulation Jun 01 09:09:33 volumio volumio[1014]: info: Stopping Airplay Playback and sending pause command to client via USR2 Jun 01 09:09:33 volumio sudo[13551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 5847 Jun 01 09:09:33 volumio sudo[13551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:09:33 volumio sudo[13551]: pam_unix(sudo:session): session closed for user root Jun 01 09:09:33 volumio volumio[1014]: info: Shairport-Sync paused with USR2 Jun 01 09:09:33 volumio volumio[1014]: info: CoreStateMachine::ClearQueue Jun 01 09:09:33 volumio volumio[1014]: info: CoreStateMachine::stop Jun 01 09:09:33 volumio volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 09:09:33 volumio volumio[1014]: info: CorePlayQueue::clearPlayQueue Jun 01 09:09:33 volumio volumio[1014]: info: CorePlayQueue::saveQueue Jun 01 09:09:33 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 01 09:09:33 volumio volumio[1014]: info: CoreCommandRouter::volumioPushQueue Jun 01 09:09:33 volumio volumio[1014]: error: Upnp client error: Error: This socket has been ended by the other party Jun 01 09:09:33 volumio volumio[1014]: 0+0 records in Jun 01 09:09:33 volumio volumio[1014]: 0+0 records out Jun 01 09:09:33 volumio volumio[1014]: 0 bytes copied, 9.9314e-05 s, 0.0 kB/s Jun 01 09:09:34 volumio volumio[1014]: info: Restarting Shairport-Sync after stop Jun 01 09:09:34 volumio sudo[13559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 01 09:09:34 volumio sudo[13559]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:09:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 01 09:09:34 volumio shairport-sync[5847]: {"time":1748749007980,"response":"stopAirplayPlayback Success"} Jun 01 09:09:34 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 01 09:09:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 01 09:09:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 01 09:09:34 volumio sudo[13559]: pam_unix(sudo:session): session closed for user root Jun 01 09:09:34 volumio volumio[1014]: info: Shairport-Sync retarted Jun 01 09:09:35 volumio volumio[1014]: 0+0 records in Jun 01 09:09:35 volumio volumio[1014]: 0+0 records out Jun 01 09:09:35 volumio volumio[1014]: 0 bytes copied, 0.000100554 s, 0.0 kB/s Jun 01 09:09:35 volumio volumio[1014]: info: Starting UPNP Playback Jun 01 09:09:35 volumio volumio[1014]: info: Preparing playback through UPNP Jun 01 09:09:35 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 01 09:09:35 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 01 09:09:35 volumio volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 09:09:35 volumio volumio[1014]: info: Jun 01 09:09:35 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Jun 01 09:09:35 volumio volumio[1014]: info: Ignoring MPD Status Update Jun 01 09:09:35 volumio volumio[1014]: info: Jun 01 09:09:35 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Jun 01 09:09:35 volumio volumio[1014]: info: Ignoring MPD Status Update Jun 01 09:09:35 volumio volumio[1014]: info: ------------------------------ 3ms Jun 01 09:09:35 volumio volumio[1014]: info: ------------------------------ 2ms Jun 01 09:09:35 volumio volumio[1014]: info: Jun 01 09:09:35 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Jun 01 09:09:35 volumio volumio[1014]: info: Ignoring MPD Status Update Jun 01 09:09:35 volumio volumio[1014]: info: ------------------------------ 1ms Jun 01 09:09:35 volumio volumio[1014]: info: Jun 01 09:09:35 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Jun 01 09:09:35 volumio volumio[1014]: info: Ignoring MPD Status Update Jun 01 09:09:35 volumio volumio[1014]: info: ------------------------------ 1ms Jun 01 09:09:35 volumio volumio[1014]: info: Jun 01 09:09:35 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Jun 01 09:09:35 volumio volumio[1014]: info: Ignoring MPD Status Update Jun 01 09:09:35 volumio volumio[1014]: info: ------------------------------ 1ms Jun 01 09:09:35 volumio volumio[1014]: info: Jun 01 09:09:35 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Jun 01 09:09:35 volumio volumio[1014]: info: Ignoring MPD Status Update Jun 01 09:09:35 volumio volumio[1014]: info: ------------------------------ 1ms Jun 01 09:09:36 volumio volumio[1014]: 0+0 records in Jun 01 09:09:36 volumio volumio[1014]: 0+0 records out Jun 01 09:09:36 volumio volumio[1014]: 0 bytes copied, 0.000112221 s, 0.0 kB/s Jun 01 09:09:37 volumio volumio[1014]: 0+0 records in Jun 01 09:09:37 volumio volumio[1014]: 0+0 records out Jun 01 09:09:37 volumio volumio[1014]: 0 bytes copied, 0.000115628 s, 0.0 kB/s Jun 01 09:09:38 volumio volumio[1014]: 0+0 records in Jun 01 09:09:38 volumio volumio[1014]: 0+0 records out Jun 01 09:09:38 volumio volumio[1014]: 0 bytes copied, 0.000103813 s, 0.0 kB/s Jun 01 09:09:39 volumio volumio[1014]: 0+0 records in Jun 01 09:09:39 volumio volumio[1014]: 0+0 records out Jun 01 09:09:39 volumio volumio[1014]: 0 bytes copied, 9.9406e-05 s, 0.0 kB/s Jun 01 09:09:39 volumio volumio[1014]: info: Jun 01 09:09:39 volumio volumio[1014]: ---------------------------- MPD announces state update: player Jun 01 09:09:39 volumio volumio[1014]: info: ControllerMpd::getState Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:09:39 volumio volumio[1014]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jun 01 09:09:39 volumio volumio[1014]: info: FusionDsp - ---- read samplerate from file: 44100 Jun 01 09:09:39 volumio volumio[1014]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 01 09:09:39 volumio volumio[1014]: info: Jun 01 09:09:39 volumio volumio[1014]: ---------------------------- MPD announces state update: player Jun 01 09:09:39 volumio volumio[1014]: info: sendMpdCommand status took 19 milliseconds Jun 01 09:09:39 volumio volumio[1014]: info: ControllerMpd::getState Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::parseState Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:09:39 volumio volumio[1014]: info: sendMpdCommand status took 2 milliseconds Jun 01 09:09:39 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::parseState Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:09:39 volumio volumio[1014]: info: ControllerMpd::pushState Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Jun 01 09:09:39 volumio volumio[1014]: verbose: In UPNP mode Jun 01 09:09:39 volumio volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Heard It Through The Grapevine","artist":"Gladys Knight & The Pips","album":"Everybody Needs Love","uri":"http://192.168.1.8:11135/tidal/35712410.flac","trackType":"tidal"} Jun 01 09:09:39 volumio volumio[1014]: verbose: CURRENT POSITION 0 Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Jun 01 09:09:39 volumio volumio[1014]: info: ------------------------------ 27ms Jun 01 09:09:39 volumio volumio[1014]: error: FusionDsp - WebSocket error: [object Object] Jun 01 09:09:39 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 01 09:09:39 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:09:39 volumio volumio[1014]: info: ControllerMpd::pushState Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Jun 01 09:09:39 volumio volumio[1014]: verbose: In UPNP mode Jun 01 09:09:39 volumio volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":166,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Heard It Through The Grapevine","artist":"Gladys Knight & The Pips","album":"Everybody Needs Love","uri":"http://192.168.1.8:11135/tidal/35712410.flac","trackType":"tidal"} Jun 01 09:09:39 volumio volumio[1014]: verbose: CURRENT POSITION 0 Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:09:39 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::pushState Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::pushState Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 01 09:09:39 volumio volumio[1014]: info: ------------------------------ 20ms Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::volumioGetQueue Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::getQueue Jun 01 09:09:39 volumio volumio[1014]: info: CorePlayQueue::getQueue Jun 01 09:09:39 volumio volumio[1014]: info: CoreCommandRouter::volumioGetQueue Jun 01 09:09:39 volumio volumio[1014]: info: CoreStateMachine::getQueue Jun 01 09:09:39 volumio volumio[1014]: info: CorePlayQueue::getQueue Jun 01 09:09:40 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:40 volumio volumio[1014]: 2+28 records in Jun 01 09:09:40 volumio volumio[1014]: 2+28 records out Jun 01 09:09:40 volumio volumio[1014]: 176384 bytes (176 kB, 172 KiB) copied, 0.000638399 s, 276 MB/s Jun 01 09:09:41 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:41 volumio volumio[1014]: 1+2 records in Jun 01 09:09:41 volumio volumio[1014]: 1+2 records out Jun 01 09:09:41 volumio volumio[1014]: 64576 bytes (65 kB, 63 KiB) copied, 0.000517252 s, 125 MB/s Jun 01 09:09:42 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:42 volumio volumio[1014]: 2+12 records in Jun 01 09:09:42 volumio volumio[1014]: 2+12 records out Jun 01 09:09:42 volumio volumio[1014]: 111808 bytes (112 kB, 109 KiB) copied, 0.000467068 s, 239 MB/s Jun 01 09:09:43 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:43 volumio volumio[1014]: 174+1 records in Jun 01 09:09:43 volumio volumio[1014]: 174+1 records out Jun 01 09:09:43 volumio volumio[1014]: 5730848 bytes (5.7 MB, 5.5 MiB) copied, 0.0296636 s, 193 MB/s Jun 01 09:09:44 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:44 volumio volumio[1014]: 174+1 records in Jun 01 09:09:44 volumio volumio[1014]: 174+1 records out Jun 01 09:09:44 volumio volumio[1014]: 5730816 bytes (5.7 MB, 5.5 MiB) copied, 0.0286418 s, 200 MB/s Jun 01 09:09:45 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:45 volumio volumio[1014]: 1+30 records in Jun 01 09:09:45 volumio volumio[1014]: 1+30 records out Jun 01 09:09:45 volumio volumio[1014]: 179680 bytes (180 kB, 175 KiB) copied, 0.000610806 s, 294 MB/s Jun 01 09:09:46 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:46 volumio volumio[1014]: 1+19 records in Jun 01 09:09:46 volumio volumio[1014]: 1+19 records out Jun 01 09:09:46 volumio volumio[1014]: 132288 bytes (132 kB, 129 KiB) copied, 0.000517641 s, 256 MB/s Jun 01 09:09:47 volumio volumio[1014]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Jun 01 09:09:47 volumio volumio[1014]: 1+19 records in Jun 01 09:09:47 volumio volumio[1014]: 1+19 records out Jun 01 09:09:47 volumio volumio[1014]: 132288 bytes (132 kB, 129 KiB) copied, 0.000520011 s, 254 MB/s Jun 01 09:09:47 volumio volumio[1014]: info: Jun 01 09:09:47 volumio volumio[1014]: ---------------------------- MPD announces state update: player Jun 01 09:09:47 volumio volumio[1014]: info: ControllerMpd::getState Jun 01 09:09:47 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Jun 01 09:09:47 volumio volumio[1014]: info: sendMpdCommand status took 0 milliseconds Jun 01 09:09:47 volumio volumio[1014]: verbose: ControllerMpd::parseState Jun 01 09:09:47 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 09:09:47 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 0 milliseconds Jun 01 09:09:47 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Jun 01 09:09:47 volumio volumio[1014]: info: ControllerMpd::pushState Jun 01 09:09:47 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Jun 01 09:09:47 volumio volumio[1014]: info: CoreStateMachine::pushState Jun 01 09:09:47 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 01 09:09:47 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Jun 01 09:09:47 volumio volumio[1014]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Heard It Through The Grapevine","artist":"Gladys Knight & The Pips","album":"Everybody Needs Love","uri":"http://192.168.1.8:11135/tidal/35712410.flac","trackType":"tidal"} Jun 01 09:09:47 volumio volumio[1014]: verbose: CURRENT POSITION 0 Jun 01 09:09:47 volumio volumio[1014]: info: CoreStateMachine::syncState stateService stop Jun 01 09:09:47 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Jun 01 09:09:47 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Jun 01 09:09:47 volumio volumio[1014]: info: CoreStateMachine::stPlaybackTimer Jun 01 09:09:47 volumio volumio[1014]: info: ------------------------------ 13ms Jun 01 09:09:47 volumio volumio[1014]: info: CoreCommandRouter::volumioGetQueue Jun 01 09:09:47 volumio volumio[1014]: info: CoreStateMachine::getQueue Jun 01 09:09:47 volumio volumio[1014]: info: CorePlayQueue::getQueue Jun 01 09:09:47 volumio ntpd[796]: 17.253.18.99 local addr 192.168.1.4 -> Jun 01 09:09:48 volumio volumio[1014]: 0+0 records in Jun 01 09:09:48 volumio volumio[1014]: 0+0 records out Jun 01 09:09:48 volumio volumio[1014]: 0 bytes copied, 9.898e-05 s, 0.0 kB/s Jun 01 09:09:49 volumio volumio[1014]: 0+0 records in Jun 01 09:09:49 volumio volumio[1014]: 0+0 records out Jun 01 09:09:49 volumio volumio[1014]: 0 bytes copied, 9.8739e-05 s, 0.0 kB/s Jun 01 09:09:50 volumio volumio[1014]: 0+0 records in Jun 01 09:09:50 volumio volumio[1014]: 0+0 records out Jun 01 09:09:50 volumio volumio[1014]: 0 bytes copied, 9.685e-05 s, 0.0 kB/s Jun 01 09:09:51 volumio volumio[1014]: 0+0 records in Jun 01 09:09:51 volumio volumio[1014]: 0+0 records out Jun 01 09:09:51 volumio volumio[1014]: 0 bytes copied, 9.8314e-05 s, 0.0 kB/s Jun 01 09:09:51 volumio volumio[1014]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.8 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::volumioGetVisibleSources Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 01 09:09:52 volumio volumio[1014]: info: Received Get System Info Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 01 09:09:52 volumio volumio[1014]: info: Discovery: Getting this device information Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Jun 01 09:09:52 volumio volumio[1014]: info: Listing playlists Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:52 volumio volumio[1014]: info: FusionDsp - IP adress is ---------------------------192.168.1.4 Jun 01 09:09:52 volumio volumio[1014]: info: FusionDsp - Jun 01 09:09:52 volumio volumio[1014]: info: FusionDsp - undefined Jun 01 09:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 01 09:09:52 volumio volumio[1014]: 0+0 records in Jun 01 09:09:52 volumio volumio[1014]: 0+0 records out Jun 01 09:09:52 volumio volumio[1014]: 0 bytes copied, 0.000116036 s, 0.0 kB/s Jun 01 09:09:53 volumio volumio[1014]: 0+0 records in Jun 01 09:09:53 volumio volumio[1014]: 0+0 records out Jun 01 09:09:53 volumio volumio[1014]: 0 bytes copied, 9.7017e-05 s, 0.0 kB/s Jun 01 09:09:54 volumio volumio[1014]: 0+0 records in Jun 01 09:09:54 volumio volumio[1014]: 0+0 records out Jun 01 09:09:54 volumio volumio[1014]: 0 bytes copied, 9.9073e-05 s, 0.0 kB/s Jun 01 09:09:55 volumio volumio[1014]: 0+0 records in Jun 01 09:09:55 volumio volumio[1014]: 0+0 records out Jun 01 09:09:55 volumio volumio[1014]: 0 bytes copied, 9.8073e-05 s, 0.0 kB/s Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:56 volumio volumio[1014]: info: Retrieving Cloud Streaming UI Jun 01 09:09:56 volumio volumio[1014]: info: Getting Tidal Cloud Configuration Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:56 volumio volumio[1014]: info: Getting Qobuz Cloud Configuration Jun 01 09:09:56 volumio volumio[1014]: info: Asking plugin for UI Config Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:56 volumio volumio[1014]: info: Getting Spotify Cloud Configuration Jun 01 09:09:56 volumio volumio[1014]: info: Asking plugin for UI Config Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:56 volumio volumio[1014]: info: Saving Spotify Acccount Jun 01 09:09:56 volumio volumio[1014]: info: Got Tidal Cloud Configuration Jun 01 09:09:56 volumio volumio[1014]: info: Got it Jun 01 09:09:56 volumio volumio[1014]: info: Got it Jun 01 09:09:56 volumio volumio[1014]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 01 09:09:56 volumio volumio[1014]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::volumioGetBrowseSources Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:09:56 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 01 09:09:56 volumio volumio[1014]: 0+0 records in Jun 01 09:09:56 volumio volumio[1014]: 0+0 records out Jun 01 09:09:56 volumio volumio[1014]: 0 bytes copied, 9.8813e-05 s, 0.0 kB/s Jun 01 09:09:57 volumio volumio[1014]: 0+0 records in Jun 01 09:09:57 volumio volumio[1014]: 0+0 records out Jun 01 09:09:57 volumio volumio[1014]: 0 bytes copied, 9.9239e-05 s, 0.0 kB/s Jun 01 09:09:58 volumio volumio[1014]: 0+0 records in Jun 01 09:09:58 volumio volumio[1014]: 0+0 records out Jun 01 09:09:58 volumio volumio[1014]: 0 bytes copied, 9.9332e-05 s, 0.0 kB/s Jun 01 09:09:59 volumio volumio[1014]: 0+0 records in Jun 01 09:09:59 volumio volumio[1014]: 0+0 records out Jun 01 09:09:59 volumio volumio[1014]: 0 bytes copied, 0.000151054 s, 0.0 kB/s Jun 01 09:10:00 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:10:01 volumio volumio[1014]: 0+0 records in Jun 01 09:10:01 volumio volumio[1014]: 0+0 records out Jun 01 09:10:01 volumio volumio[1014]: 0 bytes copied, 0.00010122 s, 0.0 kB/s Jun 01 09:10:01 volumio volumio[1014]: info: Disabling MyMusic plugin upnp Jun 01 09:10:01 volumio sudo[13822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 01 09:10:01 volumio sudo[13822]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 01 09:10:01 volumio volumio[1014]: error: Upnp client error: Error: This socket has been ended by the other party Jun 01 09:10:01 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 01 09:10:02 volumio volumio[1014]: 0+0 records in Jun 01 09:10:02 volumio volumio[1014]: 0+0 records out Jun 01 09:10:02 volumio volumio[1014]: 0 bytes copied, 0.000100609 s, 0.0 kB/s Jun 01 09:10:03 volumio volumio[1014]: 0+0 records in Jun 01 09:10:03 volumio volumio[1014]: 0+0 records out Jun 01 09:10:03 volumio volumio[1014]: 0 bytes copied, 0.000100573 s, 0.0 kB/s Jun 01 09:10:04 volumio volumio[1014]: 0+0 records in Jun 01 09:10:04 volumio volumio[1014]: 0+0 records out Jun 01 09:10:04 volumio volumio[1014]: 0 bytes copied, 0.000100443 s, 0.0 kB/s Jun 01 09:10:04 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 01 09:10:05 volumio volumio[1014]: info: Enabling MyMusic plugin upnp Jun 01 09:10:05 volumio volumio[1014]: info: Enabling plugin upnp Jun 01 09:10:05 volumio volumio[1014]: info: Loading plugin "upnp"... Jun 01 09:10:05 volumio volumio[1014]: info: [1748749205091] Starting Upmpd Daemon Jun 01 09:10:05 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 01 09:10:05 volumio volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 09:10:05 volumio volumio[1014]: Error: listen EADDRINUSE: address already in use :::6599 Jun 01 09:10:05 volumio volumio[1014]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jun 01 09:10:05 volumio volumio[1014]: at listenInCluster (net.js:1366:12) Jun 01 09:10:05 volumio volumio[1014]: at Server.listen (net.js:1452:7) Jun 01 09:10:05 volumio volumio[1014]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 01 09:10:05 volumio volumio[1014]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 01 09:10:05 volumio volumio[1014]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 01 09:10:05 volumio volumio[1014]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 01 09:10:05 volumio volumio[1014]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jun 01 09:10:05 volumio volumio[1014]: code: 'EADDRINUSE', Jun 01 09:10:05 volumio volumio[1014]: errno: -98, Jun 01 09:10:05 volumio volumio[1014]: syscall: 'listen', Jun 01 09:10:05 volumio volumio[1014]: address: '::', Jun 01 09:10:05 volumio volumio[1014]: port: 6599 Jun 01 09:10:05 volumio volumio[1014]: } Jun 01 09:10:05 volumio volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 09:10:05 volumio sudo[13849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-01 09:09 Jun 01 09:10:05 volumio sudo[13849]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"