-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-10-02 17:35:35 CEST. -- Oct 02 17:34:02 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:02 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1445. Oct 02 17:34:02 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:02 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:02 volumio go-librespot[20262]: Librespot-go daemon starting... Oct 02 17:34:02 volumio go-librespot[20262]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:05 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 17:34:05 volumio volumio[846]: Disk list : [] Oct 02 17:34:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 17:34:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 02 17:34:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 02 17:34:05 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:05 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1446. Oct 02 17:34:05 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:05 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:05 volumio go-librespot[20290]: Librespot-go daemon starting... Oct 02 17:34:05 volumio go-librespot[20290]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:06 volumio volumio[846]: info: Oct 02 17:34:06 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 02 17:34:06 volumio volumio[846]: info: ControllerMpd::getState Oct 02 17:34:06 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:34:06 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 02 17:34:06 volumio volumio[846]: info: sendMpdCommand status took 4 milliseconds Oct 02 17:34:06 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 02 17:34:06 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:34:06 volumio volumio[846]: info: sendMpdCommand clearerror took 2 milliseconds Oct 02 17:34:06 volumio volumio[846]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:34:06 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:34:06 volumio volumio[846]: info: ControllerMpd::pushState Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::pushState Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 02 17:34:06 volumio volumio[846]: info: CorePlayQueue::getTrack 0 Oct 02 17:34:06 volumio volumio[846]: 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":"Y no amanece","artist":"Los Secretos","album":"Grandes Exitos","uri":"http://192.168.1.112:16418/qobuz/5635829.flac","trackType":"qobuz"} Oct 02 17:34:06 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::syncState stateService stop Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::pushState Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 02 17:34:06 volumio volumio[846]: info: No code Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::pushState Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 02 17:34:06 volumio volumio[846]: info: ------------------------------ 22ms Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::getQueue Oct 02 17:34:06 volumio volumio[846]: info: CorePlayQueue::getQueue Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::getQueue Oct 02 17:34:06 volumio volumio[846]: info: CorePlayQueue::getQueue Oct 02 17:34:06 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:34:06 volumio volumio[846]: info: CoreStateMachine::getQueue Oct 02 17:34:06 volumio volumio[846]: info: CorePlayQueue::getQueue Oct 02 17:34:06 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1LXgrJqOEyLFTEsEjjfLMF","service":"spop","name":"Señora azul","artist":"Canovas, Rodrigo, Adolfo Y Guzman","album":"Señora Azul (40 Aniversario)","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b2739f38df2e4f8e7a6f61105490","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:06 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2QIvd2Uow99gv5kBBbtf3y","service":"spop","name":"I'll Get Along","artist":"Michael Kiwanuka","album":"Home Again (Deluxe Version)","type":"song","duration":206,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf3cd9588ec89ab911b70780","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:06 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1QWOoqqanT2ISyPzvxZfNu","service":"spop","name":"This Ain't the Movies","artist":"Nick Piunti","album":"Trust Your Instincts","type":"song","duration":202,"albumart":"https://i.scdn.co/image/ab67616d0000b273c0e5f18c3bcc587c1094da34","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:06 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2MFMbVNkISBay7yJQwT73E","service":"spop","name":"Segundo asalto","artist":"Love of Lesbian","album":"1999 (o como generar incendios de nieve con una lupa enfocando la luna)","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b273304dbb3e13db9dae376c7a51","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:06 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:54vN2Gg2vfr69LKyQLzM5V","service":"spop","name":"We Could Be Looking For The Same Thing","artist":"Silver Jews","album":"Lookout Mountain, Lookout Sea","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b273ebca0212ed38a62f4cde5ba9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:08 volumio volumio[846]: error: Commandrouter: Cannot explode uri spotify:track:5xYK0S57hp6bEdJlMo7ozl from service spop: Error: Spotify API method getTrack failed: Error: socket hang up Oct 02 17:34:08 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5G86W5OpkogAsLYKA1A1Dp","service":"spop","name":"Lucy Doesn't Love You","artist":"Ivy","album":"Long Distance","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273144cadccff187a0894e7d3ee","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1447. Oct 02 17:34:09 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:09 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:09 volumio go-librespot[20303]: Librespot-go daemon starting... Oct 02 17:34:09 volumio go-librespot[20303]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:09 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:34:09 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:34:09 volumio volumio[846]: info: Discovery: Getting this device information Oct 02 17:34:09 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 02 17:34:09 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:34:11 volumio volumio[846]: info: Received Get System Version Oct 02 17:34:11 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 17:34:11 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5VZnXgtLWPpIpCEKufLeCh","service":"spop","name":"Follow the Lights","artist":"US Rails","album":"Heartbreak Superstar","type":"song","duration":228,"albumart":"https://i.scdn.co/image/ab67616d0000b273cae9e81454e0787f2e23f9fc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:11 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0C93FKjIBPGFe0Af1ucjyh","service":"spop","name":"Televisión (feat. Bunbury)","artist":"León Benavente","album":"Televisión (feat. Bunbury)","type":"song","duration":191,"albumart":"https://i.scdn.co/image/ab67616d0000b2733ec7e0a494134fc129c2d292","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1448. Oct 02 17:34:12 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:12 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:12 volumio go-librespot[20315]: Librespot-go daemon starting... Oct 02 17:34:12 volumio go-librespot[20315]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:14 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Z8eujGFOjz8MM7LcgAKTY","service":"spop","name":"Estamos Bien","artist":"Santero y Los Muchachos","album":"Rioflorido","type":"song","duration":331,"albumart":"https://i.scdn.co/image/ab67616d0000b2736beb74084189e32f5c6676ef","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1449. Oct 02 17:34:15 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:15 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:15 volumio go-librespot[20327]: Librespot-go daemon starting... Oct 02 17:34:15 volumio go-librespot[20327]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:16 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:23RBb8HP6TOORLAriLugdA","service":"spop","name":"Home Again","artist":"Salto","album":"Far from the Echoes","type":"song","duration":192,"albumart":"https://i.scdn.co/image/ab67616d0000b273b588e08f0555379a361033ab","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:16 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2zZWOk2X14YQ5w4JV4CDxk","service":"spop","name":"Until You Came Along","artist":"Golden Smog","album":"Weird Tales","type":"song","duration":299,"albumart":"https://i.scdn.co/image/ab67616d0000b273d56dc703060b433d5a5c910c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:16 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6DQ7nyJZIthPhPr98qcrKH","service":"spop","name":"Wildfire","artist":"The Wild Feathers","album":"Greetings from the Neon Frontier","type":"song","duration":213,"albumart":"https://i.scdn.co/image/ab67616d0000b2738d8539d3e60373f33ae39211","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:16 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5VVxCPvqbblzIcDxjDyXiO","service":"spop","name":"Hey Rusty - Extended Version","artist":"Lloyd Cole and the Commotions","album":"Collected Recordings 1983-1989","type":"song","duration":323,"albumart":"https://i.scdn.co/image/ab67616d0000b273fe4f254756212ab51d54078b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:17 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5OofNdKue7w86a8veKLU1P","service":"spop","name":"Terrible Lie","artist":"The Parson Red Heads","album":"Blurred Harmony","type":"song","duration":243,"albumart":"https://i.scdn.co/image/ab67616d0000b27300c76f92223249e11f653fd4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1450. Oct 02 17:34:18 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:18 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:18 volumio go-librespot[20341]: Librespot-go daemon starting... Oct 02 17:34:18 volumio go-librespot[20341]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:20 volumio volumio-remote-updater[652]: No test mode Oct 02 17:34:20 volumio volumio-remote-updater[652]: No alpha test mode Oct 02 17:34:21 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1sShslvyh1xU9DB9zL4cCF","service":"spop","name":"How The West Was Won","artist":"Peter Perrett","album":"How The West Was Won","type":"song","duration":273,"albumart":"https://i.scdn.co/image/ab67616d0000b273bdb633daf426e6c8a8b9fa58","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:21 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:679MnBgB7FoPKcTObDt7wa","service":"spop","name":"Crush on Her","artist":"Escondido","album":"Warning Bells","type":"song","duration":215,"albumart":"https://i.scdn.co/image/ab67616d0000b27336c286638b8c54778edcab67","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:21 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2OOp2YvEObOvFn6gNP2HAu","service":"spop","name":"The Sound","artist":"The Long Ryders","album":"Psychedelic Country Soul","type":"song","duration":238,"albumart":"https://i.scdn.co/image/ab67616d0000b2739eacc99b65b33f7992cc1cfb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1451. Oct 02 17:34:22 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:22 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:22 volumio go-librespot[20351]: Librespot-go daemon starting... Oct 02 17:34:22 volumio go-librespot[20351]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:22 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4KnhBaMVSiWc1EN6CNb33r","service":"spop","name":"Everyone Can Fly","artist":"Gigolo Aunts","album":"Minor Chords and Major Themes","type":"song","duration":260,"albumart":"https://i.scdn.co/image/ab67616d0000b273a661845e0b7b30d83321c874","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:22 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7zfzFbRc07YsbFef5xnz9h","service":"spop","name":"Talking Straight","artist":"Rolling Blackouts Coastal Fever","album":"Hope Downs","type":"song","duration":224,"albumart":"https://i.scdn.co/image/ab67616d0000b273b0b9dc8282d394f8073685bd","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:22 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0R3vFnJYuPSrUHypRexQzn","service":"spop","name":"Drank Like Hank","artist":"Brothers Osborne","album":"Port Saint Joe","type":"song","duration":189,"albumart":"https://i.scdn.co/image/ab67616d0000b27300f59dc09d8efdfce55012a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1452. Oct 02 17:34:25 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:25 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:25 volumio go-librespot[20363]: Librespot-go daemon starting... Oct 02 17:34:25 volumio go-librespot[20363]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:25 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:25 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:25 volumio volumio[846]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 02 17:34:25 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 02 17:34:26 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:21ezDXebDgeHM7J3g0uGh7","service":"spop","name":"Partner in Time","artist":"Will Courtney","album":"Crazy Love","type":"song","duration":245,"albumart":"https://i.scdn.co/image/ab67616d0000b273e0f55e3d0bc092e40e2daac4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:26 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6KsqvRBjQEF7MOMP6lRbv8","service":"spop","name":"Red Tail","artist":"Gabriel Kelley","album":"Red Tail","type":"song","duration":265,"albumart":"https://i.scdn.co/image/ab67616d0000b2730359d7d5ba7a94b27bf163ce","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:26 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:58wPp8831OMViBWtz6uwBA","service":"spop","name":"Never Look Back","artist":"Field Report","album":"Summertime Songs","type":"song","duration":220,"albumart":"https://i.scdn.co/image/ab67616d0000b2730dbce3cc150e4bf9280f0a1e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:26 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4428LG4N03CRp9CayipxA6","service":"spop","name":"Temporary High","artist":"Nick Piunti","album":"Temporary High","type":"song","duration":237,"albumart":"https://i.scdn.co/image/ab67616d0000b273e5e91c848161508cc93a37d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:26 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:12QSwxMGhfjMapy47yzVsq","service":"spop","name":"No Return","artist":"Nick Piunti","album":"Temporary High","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b273e5e91c848161508cc93a37d9","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:27 volumio volumio[846]: info: CoreCommandRouter::Close All Modals sent Oct 02 17:34:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1453. Oct 02 17:34:28 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:28 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:28 volumio go-librespot[20376]: Librespot-go daemon starting... Oct 02 17:34:28 volumio go-librespot[20376]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:31 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2c9qY9C9MNZRwUIrsTmw26","service":"spop","name":"New Job","artist":"The Yearlings","album":"Skywriting","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b2734c8b757ff97d3b09bb5a73a2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:31 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3ujiJvVypfSunc9DkhKNH0","service":"spop","name":"Not Forgetting (Just Not Remembering)","artist":"Bennett Wilson Poole","album":"Bennett Wilson Poole","type":"song","duration":336,"albumart":"https://i.scdn.co/image/ab67616d0000b2735a5ceeafd55fd8d9152bbff1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:31 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0VObj34StUed8DcQSEPmLN","service":"spop","name":"On the Way Out","artist":"Nick Piunti","album":"13 in My Head","type":"song","duration":231,"albumart":"https://i.scdn.co/image/ab67616d0000b2734a32ab6ff05622e19abcf70b","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1454. Oct 02 17:34:31 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:31 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:31 volumio go-librespot[20386]: Librespot-go daemon starting... Oct 02 17:34:31 volumio go-librespot[20386]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:32 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0dpuzK0KWOcmJpMSwA0bZY","service":"spop","name":"Arms Like Boulders","artist":"The War On Drugs","album":"Wagonwheel Blues","type":"song","duration":320,"albumart":"https://i.scdn.co/image/ab67616d0000b2735542132d5fd1ffc8494f7163","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:32 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6OovMxorc81FpB11gEKlHg","service":"spop","name":"Alabama","artist":"Tracyanne & Danny","album":"Tracyanne & Danny","type":"song","duration":293,"albumart":"https://i.scdn.co/image/ab67616d0000b2737c364c42d7e1bb408db90538","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:35 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1455. Oct 02 17:34:35 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:35 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:35 volumio go-librespot[20398]: Librespot-go daemon starting... Oct 02 17:34:35 volumio go-librespot[20398]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:35 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:35 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:36 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5otPKC5adyqRNQCf4m4Hq1","service":"spop","name":"Funny Guys","artist":"Bennett Wilson Poole","album":"Bennett Wilson Poole","type":"song","duration":249,"albumart":"https://i.scdn.co/image/ab67616d0000b2735a5ceeafd55fd8d9152bbff1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:36 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6He7ytN5dZDASJDz9Pe71s","service":"spop","name":"Window","artist":"Danny Golden","album":"Old Love","type":"song","duration":184,"albumart":"https://i.scdn.co/image/ab67616d0000b2739273f089a52543d3d2412737","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:36 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Y1UoiDOuXLDV2txNMLSCi","service":"spop","name":"Waiting Game","artist":"Levi Parham","album":"These American Blues","type":"song","duration":258,"albumart":"https://i.scdn.co/image/ab67616d0000b2732411a6a80c3f40193be5288a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:37 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2RDTg0TSYK8OTI3DckkYBi","service":"spop","name":"Bad Dancer","artist":"Sons Of Bill","album":"Love and Logic","type":"song","duration":255,"albumart":"https://i.scdn.co/image/ab67616d0000b2730867402471853091658fca6e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:38 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:38 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1456. Oct 02 17:34:38 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:38 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:38 volumio go-librespot[20411]: Librespot-go daemon starting... Oct 02 17:34:38 volumio go-librespot[20411]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:38 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:39 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:34:39 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:34:39 volumio volumio[846]: info: Discovery: Getting this device information Oct 02 17:34:39 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 02 17:34:39 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:34:40 volumio volumio[846]: verbose: New Socket.io Connection to 192.168.1.47 from 192.168.1.112 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 17:34:40 volumio volumio[846]: info: Received Get System Info Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:34:40 volumio volumio[846]: info: Discovery: Getting this device information Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::volumioGetState Oct 02 17:34:40 volumio volumio[846]: info: Listing playlists Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:34:40 volumio volumio[846]: info: CoreStateMachine::getQueue Oct 02 17:34:40 volumio volumio[846]: info: CorePlayQueue::getQueue Oct 02 17:34:40 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 17:34:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1457. Oct 02 17:34:41 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:41 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:41 volumio go-librespot[20425]: Librespot-go daemon starting... Oct 02 17:34:41 volumio go-librespot[20425]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:44 volumio volumio[846]: info: Oct 02 17:34:44 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 02 17:34:44 volumio volumio[846]: info: ControllerMpd::getState Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:34:44 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Oct 02 17:34:44 volumio volumio[846]: info: Oct 02 17:34:44 volumio volumio[846]: ---------------------------- MPD announces state update: player Oct 02 17:34:44 volumio volumio[846]: info: sendMpdCommand status took 16 milliseconds Oct 02 17:34:44 volumio volumio[846]: info: ControllerMpd::getState Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:34:44 volumio volumio[846]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:34:44 volumio volumio[846]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::parseState Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:34:44 volumio volumio[846]: info: ControllerMpd::pushState Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 02 17:34:44 volumio volumio[846]: verbose: In UPNP mode Oct 02 17:34:44 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Y no amanece","artist":"Los Secretos","album":"Grandes Exitos","uri":"http://192.168.1.112:16418/qobuz/5635829.flac","trackType":"qobuz"} Oct 02 17:34:44 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:34:44 volumio volumio[846]: info: ------------------------------ 22ms Oct 02 17:34:44 volumio volumio[846]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:34:44 volumio volumio[846]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:34:44 volumio volumio[846]: info: ControllerMpd::pushState Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::servicePushState Oct 02 17:34:44 volumio volumio[846]: verbose: In UPNP mode Oct 02 17:34:44 volumio volumio[846]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":285,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Y no amanece","artist":"Los Secretos","album":"Grandes Exitos","uri":"http://192.168.1.112:16418/qobuz/5635829.flac","trackType":"qobuz"} Oct 02 17:34:44 volumio volumio[846]: verbose: CURRENT POSITION 0 Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::syncState stateService play Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:34:44 volumio volumio[846]: info: Received an update from plugin. extracting info from payload Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::pushState Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::pushState Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::volumioPushState Oct 02 17:34:44 volumio volumio[846]: info: ------------------------------ 23ms Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::getQueue Oct 02 17:34:44 volumio volumio[846]: info: CorePlayQueue::getQueue Oct 02 17:34:44 volumio volumio[846]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:34:44 volumio volumio[846]: info: CoreStateMachine::getQueue Oct 02 17:34:44 volumio volumio[846]: info: CorePlayQueue::getQueue Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3AW8RH8wosGLxd6ZojTGBe","service":"spop","name":"Hard to Handle","artist":"Otis Redding","album":"Soul Manifesto: 1964-1970","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b273bdb341668071c768328a3c47","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:05MKetePjoyLEzVRKAaCUo","service":"spop","name":"City Girls","artist":"J.J. Cale","album":"Grasshopper","type":"song","duration":171,"albumart":"https://i.scdn.co/image/ab67616d0000b2733d9c0173f1b02cb66f82b3ac","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3nQRkDwPwC5pnGdGXfiWTY","service":"spop","name":"Devil In Disguise","artist":"J.J. Cale","album":"Classic J.J. Cale - The Universal Masters Collection","type":"song","duration":121,"albumart":"https://i.scdn.co/image/ab67616d0000b27309493e1b05ec0bf6aee40dc2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5bkN6Gb3KsupCAydAA3QKP","service":"spop","name":"Midnight Highway","artist":"Quinn Sullivan","album":"Midnight Highway","type":"song","duration":355,"albumart":"https://i.scdn.co/image/ab67616d0000b273a331bcea3fb41f284d5903c3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1458. Oct 02 17:34:44 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:66I8jjhxd9vJ0EkNw3RDZo","service":"spop","name":"Ride On the Train","artist":"Hollis Brown","album":"Ride On the Train","type":"song","duration":279,"albumart":"https://i.scdn.co/image/ab67616d0000b2738d81a7aa37e06732ecce9da2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:01UZLNxrAbsxYSFacsWqsY","service":"spop","name":"Fool's Gonna Fly","artist":"Will Hoge","album":"Number Seven (Deluxe)","type":"song","duration":154,"albumart":"https://i.scdn.co/image/ab67616d0000b27324edd3cfd660ef12ac123170","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:63MQI2oo2ati03P5UPFMzb","service":"spop","name":"Comes A Time","artist":"Neil Young","album":"Comes A Time","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b273c14ab83e8b2911fcedc573fa","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:44 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:44 volumio go-librespot[20477]: Librespot-go daemon starting... Oct 02 17:34:44 volumio go-librespot[20477]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:48 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:48 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1459. Oct 02 17:34:48 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:48 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:48 volumio go-librespot[20487]: Librespot-go daemon starting... Oct 02 17:34:48 volumio go-librespot[20487]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:48 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:48 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:49 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 17:34:49 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 02 17:34:49 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6mlvHiKJNLW4v6FCZIvnBt","service":"spop","name":"Tequila Sunrise - 2013 Remaster","artist":"Eagles","album":"Selected Works 1972-1999","type":"song","duration":173,"albumart":"https://i.scdn.co/image/ab67616d0000b273fba8aaf58371d7e6160dea2f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:49 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2aGUbnhSeOZJmASEhAiC0j","service":"spop","name":"Sometimes I Just Have To Say Your Name","artist":"Del Amitri","album":"Change Everything (Re-Presents)","type":"song","duration":353,"albumart":"https://i.scdn.co/image/ab67616d0000b2732ae6005f22a711cb58afca77","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:50 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5yEeZBRk6ZZbLpU5JajXFA","service":"spop","name":"Sailing To Philadelphia","artist":"Mark Knopfler","album":"The Best of Dire Straits & Mark Knopfler - Private Investigations (Limited Edition)","type":"song","duration":353,"albumart":"https://i.scdn.co/image/ab67616d0000b27311cd3607f236dd71b56b1029","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:51 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:51 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1460. Oct 02 17:34:51 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:51 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:51 volumio go-librespot[20497]: Librespot-go daemon starting... Oct 02 17:34:51 volumio go-librespot[20497]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:51 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:51 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:54 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4wQZDfB1ilPyZORq7QRLJu","service":"spop","name":"Handshake Drugs","artist":"Wilco","album":"The Complete Studio Albums","type":"song","duration":363,"albumart":"https://i.scdn.co/image/ab67616d0000b2734489d5f980321ee082e8fb26","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:54 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3y8VvtBlMtIDRLeeb2cnKY","service":"spop","name":"Heavy Metal Drummer","artist":"Wilco","album":"The Complete Studio Albums","type":"song","duration":188,"albumart":"https://i.scdn.co/image/ab67616d0000b2734489d5f980321ee082e8fb26","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:54 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:54 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1461. Oct 02 17:34:54 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:54 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2QcH83c31qIEF8Vw4RG1ny","service":"spop","name":"Impossible Germany","artist":"Wilco","album":"The Complete Studio Albums","type":"song","duration":357,"albumart":"https://i.scdn.co/image/ab67616d0000b2734489d5f980321ee082e8fb26","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:54 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:54 volumio go-librespot[20512]: Librespot-go daemon starting... Oct 02 17:34:54 volumio go-librespot[20512]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:54 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:54 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:54 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3E58i0pivkFs5bl4r9kGVW","service":"spop","name":"Box Full of Letters","artist":"Wilco","album":"The Complete Studio Albums","type":"song","duration":185,"albumart":"https://i.scdn.co/image/ab67616d0000b2734489d5f980321ee082e8fb26","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:57 volumio volumio[846]: info: Retrieving Cloud Streaming UI Oct 02 17:34:57 volumio volumio[846]: info: Getting Tidal Cloud Configuration Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:57 volumio volumio[846]: info: Getting Qobuz Cloud Configuration Oct 02 17:34:57 volumio volumio[846]: info: Asking plugin for UI Config Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:57 volumio volumio[846]: info: Getting Spotify Cloud Configuration Oct 02 17:34:57 volumio volumio[846]: info: Asking plugin for UI Config Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:57 volumio volumio[846]: info: Saving Spotify Acccount Oct 02 17:34:57 volumio volumio[846]: info: Got Tidal Cloud Configuration Oct 02 17:34:57 volumio volumio[846]: info: Got it Oct 02 17:34:57 volumio volumio[846]: info: Got it Oct 02 17:34:57 volumio volumio[846]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 17:34:57 volumio volumio[846]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 17:34:57 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 17:34:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:34:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1462. Oct 02 17:34:57 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:34:57 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:34:57 volumio go-librespot[20528]: Librespot-go daemon starting... Oct 02 17:34:57 volumio go-librespot[20528]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:34:57 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:34:57 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:34:59 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5nWbbQ0sIEabqPxAdqmh66","service":"spop","name":"Sweet Disposition","artist":"The Temper Trap","album":"(500) Days of Summer (Music from the Motion Picture)","type":"song","duration":234,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c2d226f555a702986a85bc0","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:34:59 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qNtUAdUTRc2gV0GC0pNlZ","service":"spop","name":"Breakin' Down the Walls of Heartache","artist":"Johnny Johnson & The Bandwagon","album":"The Best Of The Northern Soul Story","type":"song","duration":157,"albumart":"https://i.scdn.co/image/ab67616d0000b273ded6dea3605b8d4e711774f5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:00 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5BMlybh9H76vEXPPjE6X41","service":"spop","name":"Hate It Here","artist":"Wilco","album":"The Complete Studio Albums","type":"song","duration":270,"albumart":"https://i.scdn.co/image/ab67616d0000b2734489d5f980321ee082e8fb26","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:01 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:01 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1463. Oct 02 17:35:01 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:01 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:01 volumio go-librespot[20536]: Librespot-go daemon starting... Oct 02 17:35:01 volumio go-librespot[20536]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:01 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:01 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:01 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 17:35:03 volumio volumio[846]: info: Enabling MyMusic plugin upnp_browser Oct 02 17:35:03 volumio volumio[846]: info: Enabling plugin upnp_browser Oct 02 17:35:03 volumio volumio[846]: info: Loading plugin "upnp_browser"... Oct 02 17:35:03 volumio volumio[846]: info: PLUGIN START: upnp_browser Oct 02 17:35:03 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:35:03 volumio volumio[846]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 17:35:03 volumio volumio[846]: info: [1727883303293] CoreMusicLibrary::Adding element Media Servers Oct 02 17:35:03 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:35:03 volumio volumio[846]: Cannot find translation for source Spotify Oct 02 17:35:03 volumio volumio[846]: info: Done. Oct 02 17:35:04 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:04 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1464. Oct 02 17:35:04 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:04 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:04 volumio go-librespot[20545]: Librespot-go daemon starting... Oct 02 17:35:04 volumio go-librespot[20545]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:04 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5Jr2oz2ssbTjD63OH1W6di","service":"spop","name":"I Don't Want to Discuss It","artist":"Little Richard","album":"The Best Of The Northern Soul Story","type":"song","duration":138,"albumart":"https://i.scdn.co/image/ab67616d0000b273ded6dea3605b8d4e711774f5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:04 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:03S1gRIixTPwBw4qsNOct1","service":"spop","name":"More Today Than Yesterday","artist":"The Spiral Starecase","album":"The Best Of The Northern Soul Story","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b273ded6dea3605b8d4e711774f5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:04 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4U3QZUpIlONJNz0n3g7Cig","service":"spop","name":"If It Makes You Happy","artist":"Sheryl Crow","album":"Sheryl Crow - Hits & Rarities (International Version)","type":"song","duration":319,"albumart":"https://i.scdn.co/image/ab67616d0000b273a85c86bfb5d74ebfc36a3ce7","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:05 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 17:35:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1465. Oct 02 17:35:07 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:07 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:07 volumio go-librespot[20558]: Librespot-go daemon starting... Oct 02 17:35:07 volumio go-librespot[20558]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:07 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:07 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:07 volumio volumio[846]: info: Disabling MyMusic plugin upnp Oct 02 17:35:07 volumio sudo[20562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 02 17:35:07 volumio sudo[20562]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:07 volumio volumio[846]: error: Upnp client error: Error: This socket has been ended by the other party Oct 02 17:35:07 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 02 17:35:08 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4EHNYvNsd2MCT58Ernb0gm","service":"spop","name":"Just Can't Wait","artist":"X-Rated Cowboys","album":"Saddest Day Of The Year","type":"song","duration":242,"albumart":"https://i.scdn.co/image/ab67616d0000b273d08cee0984114708196fb1b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:08 volumio volumio[846]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5gSHlT2SI0dtjeHrjj96A1","service":"spop","name":"Here's Where the Story Ends","artist":"The Sundays","album":"Reading Writing And Arithmetic","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b27329c94727f5e032d76352de6c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Oct 02 17:35:08 volumio volumio[846]: info: Enabling MyMusic plugin upnp Oct 02 17:35:08 volumio volumio[846]: info: Enabling plugin upnp Oct 02 17:35:08 volumio volumio[846]: info: Loading plugin "upnp"... Oct 02 17:35:08 volumio volumio[846]: info: [1727883308726] Starting Upmpd Daemon Oct 02 17:35:08 volumio volumio[846]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 17:35:08 volumio volumio[846]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:35:08 volumio volumio[846]: Error: listen EADDRINUSE: address already in use :::6599 Oct 02 17:35:08 volumio volumio[846]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Oct 02 17:35:08 volumio volumio[846]: at listenInCluster (net.js:1366:12) Oct 02 17:35:08 volumio volumio[846]: at Server.listen (net.js:1452:7) Oct 02 17:35:08 volumio volumio[846]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 02 17:35:08 volumio volumio[846]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 02 17:35:08 volumio volumio[846]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 02 17:35:08 volumio volumio[846]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 02 17:35:08 volumio volumio[846]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Oct 02 17:35:08 volumio volumio[846]: code: 'EADDRINUSE', Oct 02 17:35:08 volumio volumio[846]: errno: -98, Oct 02 17:35:08 volumio volumio[846]: syscall: 'listen', Oct 02 17:35:08 volumio volumio[846]: address: '::', Oct 02 17:35:08 volumio volumio[846]: port: 6599 Oct 02 17:35:08 volumio volumio[846]: } Oct 02 17:35:08 volumio volumio[846]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:35:09 volumio sudo[20576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-02 17:34 Oct 02 17:35:09 volumio sudo[20576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:09 volumio sudo[20576]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:10 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:10 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1466. Oct 02 17:35:10 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:10 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:10 volumio go-librespot[20583]: Librespot-go daemon starting... Oct 02 17:35:10 volumio go-librespot[20583]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:14 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:14 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1467. Oct 02 17:35:14 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:14 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:14 volumio go-librespot[20589]: Librespot-go daemon starting... Oct 02 17:35:14 volumio go-librespot[20589]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:14 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:14 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:17 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:17 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1468. Oct 02 17:35:17 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:17 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:17 volumio go-librespot[20595]: Librespot-go daemon starting... Oct 02 17:35:17 volumio go-librespot[20595]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:17 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:17 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:20 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:20 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1469. Oct 02 17:35:20 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:20 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:20 volumio go-librespot[20600]: Librespot-go daemon starting... Oct 02 17:35:20 volumio go-librespot[20600]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:20 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:20 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:23 volumio volumio-remote-updater[652]: [2024-10-02 17:35:23] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Oct 02 17:35:23 volumio volumio-remote-updater[652]: [2024-10-02 17:35:23] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Oct 02 17:35:23 volumio volumio-remote-updater[652]: [2024-10-02 17:35:23] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Oct 02 17:35:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 02 17:35:23 volumio ntfs-3g[899]: Unmounting /dev/sda1 (Caco) Oct 02 17:35:23 volumio systemd[1]: media-Caco.mount: Succeeded. Oct 02 17:35:23 volumio sudo[20562]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:23 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 02 17:35:23 volumio systemd[1]: Started dynamicswap service. Oct 02 17:35:23 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 02 17:35:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 02 17:35:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 02 17:35:23 volumio systemd[1]: Stopped Volumio Backend Module. Oct 02 17:35:23 volumio systemd[1]: Started Volumio Backend Module. Oct 02 17:35:23 volumio systemd[1]: Started dynamicswap service. Oct 02 17:35:23 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 02 17:35:23 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:23 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1470. Oct 02 17:35:23 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:23 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:23 volumio go-librespot[20625]: Librespot-go daemon starting... Oct 02 17:35:23 volumio go-librespot[20625]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:24 volumio systemd[1]: upmpdcli.service: Succeeded. Oct 02 17:35:24 volumio systemd[1]: Stopped UPnP Renderer front-end to MPD. Oct 02 17:35:24 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:24 volumio volumio[20613]: info: ----- Volumio3 ---- Oct 02 17:35:24 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:24 volumio volumio[20613]: info: ----- System startup ---- Oct 02 17:35:24 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:25 volumio volumio[20613]: info: MYVOLUMIO Environment detected Oct 02 17:35:25 volumio volumio[20613]: info: Plugin folders cleanup Oct 02 17:35:25 volumio volumio[20613]: info: Scanning into folder /volumio/app/plugins/ Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category audio_interface Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category miscellanea Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category music_service Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category plugins.json Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category system_controller Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category user_interface Oct 02 17:35:25 volumio volumio[20613]: info: Scanning into folder /data/plugins/ Oct 02 17:35:25 volumio volumio[20613]: info: Scanning category music_service Oct 02 17:35:25 volumio volumio[20613]: info: Plugin folders cleanup completed Oct 02 17:35:25 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:25 volumio volumio[20613]: info: ----- Core plugins startup ---- Oct 02 17:35:25 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugins from folder /volumio/app/plugins/ Oct 02 17:35:25 volumio volumio[20613]: info: Adding plugin upnp to MyMusic Plugins Oct 02 17:35:25 volumio volumio[20613]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 02 17:35:25 volumio volumio[20613]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugins from folder /data/plugins/ Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "system"... Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "appearance"... Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "network"... Oct 02 17:35:25 volumio volumio[20613]: info: Refreshing Cached IP Addresses Oct 02 17:35:25 volumio sudo[20643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 17:35:25 volumio sudo[20643]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:25 volumio sudo[20643]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:25 volumio sudo[20645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "services"... Oct 02 17:35:25 volumio sudo[20645]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:25 volumio sudo[20645]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "alsa_controller"... Oct 02 17:35:25 volumio sudo[20648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 02 17:35:25 volumio sudo[20648]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:25 volumio sudo[20648]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:25 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "wizard"... Oct 02 17:35:25 volumio volumio[20613]: info: Loading plugin "networkfs"... Oct 02 17:35:25 volumio volumio[20613]: info: Starting Udev Watcher for removable devices Oct 02 17:35:26 volumio volumio[20613]: info: Ignoring mount for partition: boot Oct 02 17:35:26 volumio volumio[20613]: info: Ignoring mount for partition: volumio Oct 02 17:35:26 volumio volumio[20613]: info: Ignoring mount for partition: volumio_data Oct 02 17:35:26 volumio volumio[20613]: info: Mounting Device Caco Oct 02 17:35:26 volumio sudo[20659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/Caco -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 02 17:35:26 volumio sudo[20659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:26 volumio ntfs-3g[20662]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 02 17:35:26 volumio ntfs-3g[20662]: Mounted /dev/sda1 (Read-Write, label "Caco", NTFS 3.1) Oct 02 17:35:26 volumio ntfs-3g[20662]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 02 17:35:26 volumio ntfs-3g[20662]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Oct 02 17:35:26 volumio ntfs-3g[20662]: Global ownership and permissions enforced, configuration type 7 Oct 02 17:35:26 volumio sudo[20659]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:26 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 17:35:26 volumio volumio[20613]: info: Loading plugin "volumio_command_line_client"... Oct 02 17:35:26 volumio volumio[20613]: info: Loading plugin "upnp"... Oct 02 17:35:26 volumio volumio[20613]: info: [1727883326684] Starting Upmpd Daemon Oct 02 17:35:26 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 17:35:26 volumio volumio[20613]: info: Loading plugin "my_music"... Oct 02 17:35:26 volumio volumio[20613]: info: Loading plugin "mpd"... Oct 02 17:35:26 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:26 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1471. Oct 02 17:35:26 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:26 volumio volumio[20613]: info: Loading plugin "upnp_browser"... Oct 02 17:35:26 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:26 volumio go-librespot[20663]: Librespot-go daemon starting... Oct 02 17:35:26 volumio go-librespot[20663]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "alarm-clock"... Oct 02 17:35:27 volumio volumio[20613]: info: Plugin airplay_emulation is not enabled Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "last_100"... Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "webradio"... Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "i2s_dacs"... Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "volumiodiscovery"... Oct 02 17:35:27 volumio volumio[20613]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 17:35:27 volumio volumio[20613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 17:35:27 volumio node[20613]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 17:35:27 volumio volumio[20613]: *** WARNING *** For more information see Oct 02 17:35:27 volumio volumio[20613]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 17:35:27 volumio volumio[20613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 17:35:27 volumio volumio[20613]: *** WARNING *** For more information see Oct 02 17:35:27 volumio node[20613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 17:35:27 volumio node[20613]: *** WARNING *** For more information see Oct 02 17:35:27 volumio node[20613]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 17:35:27 volumio node[20613]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 17:35:27 volumio node[20613]: *** WARNING *** For more information see Oct 02 17:35:27 volumio volumio[20613]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 02 17:35:27 volumio volumio[20613]: info: Discovery: Started advertising with name: Volumio Oct 02 17:35:27 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "spop"... Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "outputs"... Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "albumart"... Oct 02 17:35:27 volumio volumio[20613]: info: Plugin example_plugin is not enabled Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "inputs"... Oct 02 17:35:27 volumio volumio[20613]: info: Loading plugin "updater_comm"... Oct 02 17:35:28 volumio volumio[20613]: info: Plugin mpdemulation is not enabled Oct 02 17:35:28 volumio volumio[20613]: info: Loading plugin "rest_api"... Oct 02 17:35:28 volumio volumio[20613]: info: Loading plugin "websocket"... Oct 02 17:35:28 volumio volumio[20613]: info: Starting Socket.io Server version 2.3.0 Oct 02 17:35:28 volumio volumio[20613]: info: Loading i18n strings for locale es Oct 02 17:35:28 volumio volumio[20613]: Updating browse sources language Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:35:28 volumio volumio[20613]: Forking 3 albumart workers Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::initPlayerControls Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: Express server listening on port 3000 Oct 02 17:35:28 volumio volumio[20613]: [Metrics] WebUI: 3s 848.27ms Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::resetVolumioState Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::getcurrentVolume Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 17:35:28 volumio volumio[20613]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Oct 02 17:35:28 volumio volumio[20613]: wlan0 Interface doesn't support scanning : Network is down Oct 02 17:35:28 volumio volumio[20613]: info: Cannot use regular scanning, forcing with ap-force Oct 02 17:35:28 volumio volumio-remote-updater[652]: [2024-10-02 17:35:28] [connect] Successful connection Oct 02 17:35:28 volumio sudo[20701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Oct 02 17:35:28 volumio sudo[20701]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:28 volumio sudo[20701]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:28 volumio volumio[20613]: command failed: Network is down (-100) Oct 02 17:35:28 volumio volumio[20613]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Oct 02 17:35:28 volumio volumio[20613]: command failed: Network is down (-100) Oct 02 17:35:28 volumio volumio[20613]: verbose: New Socket.io Connection to 192.168.1.47 from 192.168.1.112 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Oct 02 17:35:28 volumio volumio[20613]: verbose: New Socket.io Connection to 192.168.1.47 from 192.168.1.112 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Oct 02 17:35:28 volumio volumio-remote-updater[652]: [2024-10-02 17:35:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727883328 101 Oct 02 17:35:28 volumio volumio[20613]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::setRepeat null single undefined Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::pushState Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioPushState Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::setRandom null Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::pushState Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioPushState Oct 02 17:35:28 volumio volumio[20613]: info: Setting Device type: Raspberry PI Oct 02 17:35:28 volumio volumio[20613]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Oct 02 17:35:28 volumio volumio[20613]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03114 Oct 02 17:35:28 volumio volumio[20613]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Oct 02 17:35:28 volumio volumio[20613]: info: VolumeController:: Volume=75 Mute =false Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::pushState Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioPushState Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::updateTrackBlock Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrackBlock Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 17:35:28 volumio volumio[20613]: Starting albumart workers Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioGetState Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 17:35:28 volumio volumio[20613]: info: Received Get System Info Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:35:28 volumio volumio[20613]: info: Discovery: Getting this device information Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioGetState Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:35:28 volumio volumio[20613]: Starting albumart workers Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioGetState Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:28 volumio volumio[20613]: Starting albumart workers Oct 02 17:35:28 volumio volumio[20613]: info: Listing playlists Oct 02 17:35:28 volumio volumio[20613]: info: CoreCommandRouter::volumioGetQueue Oct 02 17:35:28 volumio volumio[20613]: info: CoreStateMachine::getQueue Oct 02 17:35:28 volumio volumio[20613]: info: CorePlayQueue::getQueue Oct 02 17:35:28 volumio volumio[20613]: info: Completed loading Core Plugins Oct 02 17:35:28 volumio volumio[20613]: info: Preparing to generate the ALSA configuration file Oct 02 17:35:29 volumio volumio[20613]: info: Asound.conf file unchanged, so no further update is needed Oct 02 17:35:29 volumio volumio[20613]: info: Output device has changed, restarting MPD Oct 02 17:35:29 volumio sudo[20721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 17:35:29 volumio volumio[20613]: info: ___________ START PLUGINS ___________ Oct 02 17:35:29 volumio volumio[20613]: info: ControllerMpd::onStart: Initializing MPD Oct 02 17:35:29 volumio sudo[20721]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:29 volumio volumio[20613]: info: Creating MPD Configuration file Oct 02 17:35:29 volumio sudo[20726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 17:35:29 volumio sudo[20726]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:29 volumio sudo[20721]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:29 volumio systemd[1]: Stopping Music Player Daemon... Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 17:35:29 volumio volumio[20613]: info: [1727883329347] CoreMusicLibrary::Adding element Media Servers Oct 02 17:35:29 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Oct 02 17:35:29 volumio systemd[1]: mpd.service: Failed with result 'signal'. Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:35:29 volumio systemd[1]: Stopped Music Player Daemon. Oct 02 17:35:29 volumio sudo[20739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 17:35:29 volumio sudo[20739]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 17:35:29 volumio volumio[20613]: info: [1727883329383] CoreMusicLibrary::Adding element Last_100 Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 17:35:29 volumio sudo[20741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 17:35:29 volumio systemd[1]: Starting Music Player Daemon... Oct 02 17:35:29 volumio sudo[20741]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:29 volumio sudo[20739]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:29 volumio volumio[20613]: info: [1727883329388] CoreMusicLibrary::Adding element Webradio Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 17:35:29 volumio volumio[20613]: info: Initializing BBC Radios Oct 02 17:35:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 02 17:35:29 volumio systemd[1]: mpd.service: Succeeded. Oct 02 17:35:29 volumio systemd[1]: Stopped Music Player Daemon. Oct 02 17:35:29 volumio systemd[1]: Starting Music Player Daemon... Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:35:29 volumio sudo[20748]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 17:35:29 volumio sudo[20748]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:29 volumio volumio[20613]: info: Creating Spotify config file Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio sudo[20748]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:29 volumio volumio[20613]: info: Volumio Calling Home Oct 02 17:35:29 volumio volumio[20613]: info: Discovery: adding 0fb3a268-334b-46a0-8394-131f65928898 Oct 02 17:35:29 volumio volumio[20613]: info: Discovery: Found device Volumio Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::volumioGetState Oct 02 17:35:29 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 17:35:29 volumio volumio[20613]: info: MPD Permissions set Oct 02 17:35:29 volumio volumio[20613]: info: MPD Permissions set Oct 02 17:35:29 volumio volumio[20613]: info: VolumeController:: Volume=75 Mute =false Oct 02 17:35:29 volumio volumio[20613]: info: CoreStateMachine::pushState Oct 02 17:35:29 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::volumioPushState Oct 02 17:35:29 volumio volumio[20613]: info: Spotify config file written Oct 02 17:35:29 volumio volumio[20613]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio sudo[20765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 02 17:35:29 volumio sudo[20765]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 17:35:29 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 17:35:29 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:29 volumio go-librespot[20768]: Librespot-go daemon starting... Oct 02 17:35:29 volumio go-librespot[20768]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:29 volumio sudo[20765]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:30 volumio mpd[20760]: Oct 02 17:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 17:35:30 volumio systemd[1]: Started Music Player Daemon. Oct 02 17:35:30 volumio sudo[20741]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:30 volumio sudo[20726]: pam_unix(sudo:session): session closed for user root Oct 02 17:35:30 volumio volumio[20613]: info: Completed starting Core Plugins Oct 02 17:35:30 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:30 volumio volumio[20613]: info: ----- MyVolumio plugins startup ---- Oct 02 17:35:30 volumio volumio[20613]: info: ------------------------------------------- Oct 02 17:35:30 volumio volumio[20613]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 02 17:35:30 volumio volumio[20613]: error: MPD error: The expression evaluated to a falsy value: Oct 02 17:35:30 volumio volumio[20613]: assert.ok(self.idling) Oct 02 17:35:30 volumio volumio[20613]: error: The expression evaluated to a falsy value: Oct 02 17:35:30 volumio volumio[20613]: assert.ok(self.idling) Oct 02 17:35:30 volumio volumio[20613]: error: updateQueue error: null Oct 02 17:35:30 volumio volumio[20613]: info: MPD running with PID20760 Oct 02 17:35:30 volumio volumio[20613]: ,establishing connection Oct 02 17:35:30 volumio volumio[20613]: info: Volumio called home Oct 02 17:35:30 volumio volumio[20613]: error: updateQueue error: null Oct 02 17:35:32 volumio volumio[20613]: info: go-librespot daemon successfully initialized Oct 02 17:35:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 02 17:35:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1472. Oct 02 17:35:32 volumio systemd[1]: Stopped go-librespot Daemon. Oct 02 17:35:32 volumio systemd[1]: Started go-librespot Daemon. Oct 02 17:35:32 volumio go-librespot[20781]: Librespot-go daemon starting... Oct 02 17:35:32 volumio go-librespot[20781]: /bin/start-go-liberspot.sh: 11: /bin/start-go-liberspot.sh: /usr/bin/go-librespot: not found Oct 02 17:35:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=127/n/a Oct 02 17:35:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 02 17:35:33 volumio volumio[20613]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 17:35:34 volumio volumio[20613]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 02 17:35:34 volumio volumio[20613]: SPOTIFY: BQB5H-bRcVb5kSNpKEb32D1c9dZKKYoj5QKQs8TXUPvoH-hWn1BOHSZZbdweYAgCEOa07BsYRZViqH3a0oSAHLmen4n5lRusHpvv29DX5Fjrdl8bWPK13T1Q-uzTaM74ynSpYNupeZP_Gpv5ABJ5jGsKBRnP83E0CTGt1rM-cStlFftU6rX3VWUHegD8iAxuw58Ob0ZFdS9zOI3tPZt1_vQ-dFf6GUlf9wkPmQngguOkw3dNZYPOxmbhq5Sx_M6WrHwbYA Oct 02 17:35:34 volumio volumio[20613]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 02 17:35:34 volumio volumio[20613]: info: New Spotify access token = BQB5H-bRcVb5kSNpKEb32D1c9dZKKYoj5QKQs8TXUPvoH-hWn1BOHSZZbdweYAgCEOa07BsYRZViqH3a0oSAHLmen4n5lRusHpvv29DX5Fjrdl8bWPK13T1Q-uzTaM74ynSpYNupeZP_Gpv5ABJ5jGsKBRnP83E0CTGt1rM-cStlFftU6rX3VWUHegD8iAxuw58Ob0ZFdS9zOI3tPZt1_vQ-dFf6GUlf9wkPmQngguOkw3dNZYPOxmbhq5Sx_M6WrHwbYA Oct 02 17:35:34 volumio volumio[20613]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 02 17:35:35 volumio volumio[20613]: info: CoreCommandRouter::volumioGetState Oct 02 17:35:35 volumio volumio[20613]: info: CorePlayQueue::getTrack 0 Oct 02 17:35:35 volumio volumio[20613]: info: Enabling MyMusic plugin smart_inputs Oct 02 17:35:35 volumio volumio[20613]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service smart_inputs Oct 02 17:35:35 volumio volumio[20613]: info: [MyVolumio PluginManager] Plugin music_service smart_inputs not in user plan, not enabling Oct 02 17:35:35 volumio volumio[20613]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:35:35 volumio volumio[20613]: TypeError: Cannot read property 'toUpperCase' of undefined Oct 02 17:35:35 volumio volumio[20613]: at MyVolumioPluginManager.pushMyVolumioRequiredAccountModal (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:19516) Oct 02 17:35:35 volumio volumio[20613]: at MyVolumioPluginManager.enablePlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:18418) Oct 02 17:35:35 volumio volumio[20613]: at MyVolumioPluginManager.enableAndStartPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:17039) Oct 02 17:35:35 volumio volumio[20613]: at PluginManager.enableDisableMyMusicPlugin (/volumio/app/pluginmanager.js:2024:48) Oct 02 17:35:35 volumio volumio[20613]: at CoreCommandRouter.enableDisableMyMusicPlugin (/volumio/app/index.js:2088:29) Oct 02 17:35:35 volumio volumio[20613]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1921:59) Oct 02 17:35:35 volumio volumio[20613]: at Socket.emit (events.js:315:20) Oct 02 17:35:35 volumio volumio[20613]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 02 17:35:35 volumio volumio[20613]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 02 17:35:35 volumio volumio[20613]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:35:35 volumio sudo[20793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-02 17:34 Oct 02 17:35:35 volumio sudo[20793]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"