-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-07-10 17:56:32 CEST. -- Jul 10 17:55:08 volumio volumio[953]: verbose: New Socket.io Connection to 10.223.223.20 from 10.223.223.17 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:08 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 17:55:08 volumio volumio[953]: info: Received Get System Info Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:55:08 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:08 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:08 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:08 volumio volumio[953]: info: Listing playlists Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 17:55:08 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 10 17:55:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 10 17:55:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 17:55:10 volumio volumio[953]: info: Received Get System Info Jul 10 17:55:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:55:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:55:10 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:55:10 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:10 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:10 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:55:11 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 17:55:11 volumio volumio[953]: info: Received Get System Info Jul 10 17:55:11 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:55:11 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:55:11 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:55:11 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:11 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:11 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:55:14 volumio go-librespot[2383]: time="2024-07-10T17:55:14+02:00" level=debug msg="obtained new client token: AAAodx5R9xpCwsb1LWXitQksPyDAAKnuOGz2L71KaC+iHREt5E/PxwZCtneSH91cOtPcB9O4ZwalALCctvOe/q+uJjzFsfwDKUs+UDmcPAjSI8hjIQc6j75sFqZfMatnd1uHXnRhJG+qZqP0gK79iobQlPq/WkuzyYt6IVT/bY4M79jHzljIUAle011AW7jjLTmHLR/LWg/1/mrYczEdv8iD6PSMJ8lzlJI04txtHnJPnfwPkK8pt6qg60gDDKoE" Jul 10 17:55:14 volumio go-librespot[2383]: time="2024-07-10T17:55:14+02:00" level=debug msg="completed keyexchange" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="completed challenge" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="authenticated as kostpiotr" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="authenticated as kostpiotr" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="dealer connection opened" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=info msg="accepted zeroconf user kostpiotr from Redmi Note 9 Pro" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="received connection id: N2I0MWIzZTAtOGE4ZS00OGFlLTkwZTYtMjQ3ZGFhMzNkNTM4K2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXQzbXQuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzEyRUY5QzQwNDlDOTE4Njg1NDgyMTk2MEMyMDM4NTIxRDc3MUU3MUQ1OUM1MThCNDk2RDRFNjExRDBDRDE4QTY=" Jul 10 17:55:15 volumio go-librespot[2383]: time="2024-07-10T17:55:15+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 10 17:55:16 volumio go-librespot[2383]: time="2024-07-10T17:55:16+02:00" level=debug msg="handling transfer player command from b274734a7e9a835a289163ad418852bad378962e" Jul 10 17:55:16 volumio go-librespot[2383]: time="2024-07-10T17:55:16+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 10 17:55:16 volumio go-librespot[2383]: time="2024-07-10T17:55:16+02:00" level=warning msg="failed handling dealer request" error="failed seeking to track: failed fetching tracks for seek: failed moving to next index 50 (page 1): failed fetching next page: invalid status code from page at radio-apollo/v3/tracks/spotify:station:track:5tlovHXo92q7COPx5DIbQb?salt=643506&autoplay=false&count=50&isVideo=false&prev_tracks=5tlovHXo92q7COPx5DIbQb,31MxgrG8q7kr8nrzp0xuJJ,6CPwgTLd4dF1sAEgNkwsdW,3mQOOsjdN2lrnYPa791oCC,3v0fVON6z5CnRlle3SJZU1,3KeqxSAY5a57hmnX2PN0P0,0Eos8DjtN7nC1p4xvcQEaL,7lpGfZeRhcLlvpcDcSKCFQ,6ARQYuejfkqO1DOcMRdga5,2y0nc5BhBjkYKQzXYqDdek,5DeeewpH31qpJzbEXnim2I,3pcolFc5pB3hp1eqPvm2TU,1CMurFTCEctHTFMjnZO4RD,6MfKJ91JuLky1UAVsqxXZ5,5wY7ZogIkopqocAjGeyzPO,2AbDY2agHP7e5slykeHPxJ,2qhSv7Qxq84xXVDadM1z1U,01VEpM38d3fcWZddfuYWOv,6dRJJMO9URXnTJaz401b4Q,4MsWJOddO4sVhq44t6MVTf,0p1MFWA2lSy3qUn7LpqpEA,4Pslf0rV8BsQVYmXjdei37,4xgr9jRX7oZRxpImAAKUJV,05hk8ivnCt0HP3aI1uWJfi,6eYIQXUtSiem6iiove22xd,4eG1ODcdjySLxlB9dtmbiv,2UMVrkI0nDnCGR0BWk2QsV,3RAii1PmgM8KXRyT5MT8W8,07xnoJyNRBq74nbf0Mp2m4,3uqRk43i7gbpqZwcC7QU7e,1A7Wbc3KOFX5QoJZbuJaDP,04JRnQxcP1YHWspb6mY9l6,52OBzbOsxfSsQWZqgvk1ff,6XJQYViXSKTe6uvI2obPgJ,4OTVr1uhkXIJbxZNMYCAcH,5u7JrYOfW3QTSGp89MguWg,5fmjPMfpqJ2h4kb5po028N,4z5r3ux9X5XjUNpwN754wN,1tbfHPbrcz0oAwwTWQ7CwB,2GCGb7SS4p8vz7MkrP0Bcf,3EMwcW0vMSOtdun4Xw5RkK,3lydXt9Zi8w22wS5KKRGSd,0sj5IrDq4607CSipqguziD,52QHnYu4yvZ8bpLpw6nVtR,5lvw0sQp2GkdyD8jdBQHZ7,0spiAJAKdsIZ6ES6UrC9NJ,6raP6x98Jp6fh3m3HISxe7,5jMEv7Z6zYMhGI2noZwAvl,1WH3sM3Hig8V2bxIu25YzP,3Sxb8qo1pC07uOJWK4D0Q9&minimal=true: 502" Jul 10 17:55:19 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 17:55:19 volumio go-librespot[2383]: time="2024-07-10T17:55:19+02:00" level=debug msg="handling transfer player command from b274734a7e9a835a289163ad418852bad378962e" Jul 10 17:55:19 volumio go-librespot[2383]: time="2024-07-10T17:55:19+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 10 17:55:20 volumio go-librespot[2383]: time="2024-07-10T17:55:20+02:00" level=warning msg="failed handling dealer request" error="failed seeking to track: failed fetching tracks for seek: failed moving to next index 50 (page 1): failed fetching next page: invalid status code from page at radio-apollo/v3/tracks/spotify:station:track:5tlovHXo92q7COPx5DIbQb?salt=191355&autoplay=false&count=50&isVideo=false&prev_tracks=5tlovHXo92q7COPx5DIbQb,31MxgrG8q7kr8nrzp0xuJJ,6CPwgTLd4dF1sAEgNkwsdW,3mQOOsjdN2lrnYPa791oCC,3v0fVON6z5CnRlle3SJZU1,3KeqxSAY5a57hmnX2PN0P0,0Eos8DjtN7nC1p4xvcQEaL,7lpGfZeRhcLlvpcDcSKCFQ,6ARQYuejfkqO1DOcMRdga5,2y0nc5BhBjkYKQzXYqDdek,5DeeewpH31qpJzbEXnim2I,3pcolFc5pB3hp1eqPvm2TU,4WxTq8GtcBtrMow05xVOEN,6MfKJ91JuLky1UAVsqxXZ5,5wY7ZogIkopqocAjGeyzPO,2AbDY2agHP7e5slykeHPxJ,4xgr9jRX7oZRxpImAAKUJV,1CMurFTCEctHTFMjnZO4RD,01VEpM38d3fcWZddfuYWOv,6dRJJMO9URXnTJaz401b4Q,4MsWJOddO4sVhq44t6MVTf,4Pslf0rV8BsQVYmXjdei37,2qhSv7Qxq84xXVDadM1z1U,05hk8ivnCt0HP3aI1uWJfi,0p1MFWA2lSy3qUn7LpqpEA,6eYIQXUtSiem6iiove22xd,4eG1ODcdjySLxlB9dtmbiv,3RAii1PmgM8KXRyT5MT8W8,07xnoJyNRBq74nbf0Mp2m4,2UMVrkI0nDnCGR0BWk2QsV,3uqRk43i7gbpqZwcC7QU7e,3x7Hf4jM1DAdUKSvQ3vhRj,1A7Wbc3KOFX5QoJZbuJaDP,6XJQYViXSKTe6uvI2obPgJ,4OTVr1uhkXIJbxZNMYCAcH,6W4XrCnviUXbJp2OV3TXP0,5u7JrYOfW3QTSGp89MguWg,04JRnQxcP1YHWspb6mY9l6,52OBzbOsxfSsQWZqgvk1ff,2GCGb7SS4p8vz7MkrP0Bcf,3EMwcW0vMSOtdun4Xw5RkK,5fmjPMfpqJ2h4kb5po028N,3lydXt9Zi8w22wS5KKRGSd,4z5r3ux9X5XjUNpwN754wN,1tbfHPbrcz0oAwwTWQ7CwB,0spiAJAKdsIZ6ES6UrC9NJ,6raP6x98Jp6fh3m3HISxe7,0sj5IrDq4607CSipqguziD,52QHnYu4yvZ8bpLpw6nVtR,5lvw0sQp2GkdyD8jdBQHZ7&minimal=true: 502" Jul 10 17:55:23 volumio go-librespot[2383]: time="2024-07-10T17:55:23+02:00" level=debug msg="put connect state inactive" Jul 10 17:55:23 volumio go-librespot[2383]: time="2024-07-10T17:55:23+02:00" level=trace msg="emitting websocket event: inactive" Jul 10 17:55:23 volumio volumio[953]: SPOTIFY: received: {"type":"inactive","data":null} Jul 10 17:55:23 volumio volumio[953]: error: Failed to decode event: inactive Jul 10 17:55:23 volumio volumio[953]: SPOTIFY: received: {"type":"inactive","data":null} Jul 10 17:55:23 volumio volumio[953]: error: Failed to decode event: inactive Jul 10 17:55:23 volumio go-librespot[2383]: time="2024-07-10T17:55:23+02:00" level=debug msg="handling transfer player command from b274734a7e9a835a289163ad418852bad378962e" Jul 10 17:55:24 volumio go-librespot[2383]: time="2024-07-10T17:55:24+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 10 17:55:24 volumio go-librespot[2383]: time="2024-07-10T17:55:24+02:00" level=warning msg="failed handling dealer request" error="failed seeking to track: failed fetching tracks for seek: failed moving to next index 50 (page 1): failed fetching next page: invalid status code from page at radio-apollo/v3/tracks/spotify:station:track:5tlovHXo92q7COPx5DIbQb?salt=264890&autoplay=false&count=50&isVideo=false&prev_tracks=5tlovHXo92q7COPx5DIbQb,6CPwgTLd4dF1sAEgNkwsdW,31MxgrG8q7kr8nrzp0xuJJ,3mQOOsjdN2lrnYPa791oCC,3v0fVON6z5CnRlle3SJZU1,3KeqxSAY5a57hmnX2PN0P0,0Eos8DjtN7nC1p4xvcQEaL,7lpGfZeRhcLlvpcDcSKCFQ,6ARQYuejfkqO1DOcMRdga5,2AbDY2agHP7e5slykeHPxJ,5wY7ZogIkopqocAjGeyzPO,3pcolFc5pB3hp1eqPvm2TU,4WxTq8GtcBtrMow05xVOEN,6MfKJ91JuLky1UAVsqxXZ5,5DeeewpH31qpJzbEXnim2I,4Pslf0rV8BsQVYmXjdei37,4xgr9jRX7oZRxpImAAKUJV,1CMurFTCEctHTFMjnZO4RD,01VEpM38d3fcWZddfuYWOv,6dRJJMO9URXnTJaz401b4Q,4MsWJOddO4sVhq44t6MVTf,2qhSv7Qxq84xXVDadM1z1U,3RAii1PmgM8KXRyT5MT8W8,05hk8ivnCt0HP3aI1uWJfi,3BNcC5diWtNGRHdYECNfPI,0p1MFWA2lSy3qUn7LpqpEA,4eG1ODcdjySLxlB9dtmbiv,07xnoJyNRBq74nbf0Mp2m4,6XJQYViXSKTe6uvI2obPgJ,6eYIQXUtSiem6iiove22xd,2UMVrkI0nDnCGR0BWk2QsV,3uqRk43i7gbpqZwcC7QU7e,1A7Wbc3KOFX5QoJZbuJaDP,4OTVr1uhkXIJbxZNMYCAcH,2GCGb7SS4p8vz7MkrP0Bcf,04JRnQxcP1YHWspb6mY9l6,52OBzbOsxfSsQWZqgvk1ff,5u7JrYOfW3QTSGp89MguWg,5fmjPMfpqJ2h4kb5po028N,2y0nc5BhBjkYKQzXYqDdek,3EMwcW0vMSOtdun4Xw5RkK,4z5r3ux9X5XjUNpwN754wN,52QHnYu4yvZ8bpLpw6nVtR,3lydXt9Zi8w22wS5KKRGSd,0sj5IrDq4607CSipqguziD,0spiAJAKdsIZ6ES6UrC9NJ,6raP6x98Jp6fh3m3HISxe7,5lvw0sQp2GkdyD8jdBQHZ7,5jMEv7Z6zYMhGI2noZwAvl,1OS1H8hJCp6B6P8f5TZJES&minimal=true: 502" Jul 10 17:55:27 volumio go-librespot[2383]: time="2024-07-10T17:55:27+02:00" level=debug msg="handling transfer player command from b274734a7e9a835a289163ad418852bad378962e" Jul 10 17:55:27 volumio go-librespot[2383]: time="2024-07-10T17:55:27+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 10 17:55:27 volumio go-librespot[2383]: time="2024-07-10T17:55:27+02:00" level=warning msg="failed handling dealer request" error="failed seeking to track: failed fetching tracks for seek: failed moving to next index 50 (page 1): failed fetching next page: invalid status code from page at radio-apollo/v3/tracks/spotify:station:track:5tlovHXo92q7COPx5DIbQb?salt=25303&autoplay=false&count=50&isVideo=false&prev_tracks=5tlovHXo92q7COPx5DIbQb,6CPwgTLd4dF1sAEgNkwsdW,31MxgrG8q7kr8nrzp0xuJJ,3mQOOsjdN2lrnYPa791oCC,3v0fVON6z5CnRlle3SJZU1,3KeqxSAY5a57hmnX2PN0P0,0Eos8DjtN7nC1p4xvcQEaL,7lpGfZeRhcLlvpcDcSKCFQ,6ARQYuejfkqO1DOcMRdga5,2AbDY2agHP7e5slykeHPxJ,5wY7ZogIkopqocAjGeyzPO,3pcolFc5pB3hp1eqPvm2TU,4WxTq8GtcBtrMow05xVOEN,6MfKJ91JuLky1UAVsqxXZ5,5DeeewpH31qpJzbEXnim2I,4Pslf0rV8BsQVYmXjdei37,4xgr9jRX7oZRxpImAAKUJV,1CMurFTCEctHTFMjnZO4RD,01VEpM38d3fcWZddfuYWOv,6dRJJMO9URXnTJaz401b4Q,4MsWJOddO4sVhq44t6MVTf,2qhSv7Qxq84xXVDadM1z1U,3RAii1PmgM8KXRyT5MT8W8,05hk8ivnCt0HP3aI1uWJfi,3BNcC5diWtNGRHdYECNfPI,0p1MFWA2lSy3qUn7LpqpEA,4eG1ODcdjySLxlB9dtmbiv,07xnoJyNRBq74nbf0Mp2m4,6XJQYViXSKTe6uvI2obPgJ,6eYIQXUtSiem6iiove22xd,2UMVrkI0nDnCGR0BWk2QsV,3uqRk43i7gbpqZwcC7QU7e,1A7Wbc3KOFX5QoJZbuJaDP,4OTVr1uhkXIJbxZNMYCAcH,2GCGb7SS4p8vz7MkrP0Bcf,04JRnQxcP1YHWspb6mY9l6,52OBzbOsxfSsQWZqgvk1ff,5u7JrYOfW3QTSGp89MguWg,5fmjPMfpqJ2h4kb5po028N,2y0nc5BhBjkYKQzXYqDdek,3EMwcW0vMSOtdun4Xw5RkK,4z5r3ux9X5XjUNpwN754wN,52QHnYu4yvZ8bpLpw6nVtR,3lydXt9Zi8w22wS5KKRGSd,0sj5IrDq4607CSipqguziD,0spiAJAKdsIZ6ES6UrC9NJ,6raP6x98Jp6fh3m3HISxe7,5lvw0sQp2GkdyD8jdBQHZ7,5jMEv7Z6zYMhGI2noZwAvl,1OS1H8hJCp6B6P8f5TZJES&minimal=true: 502" Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 17:55:28 volumio volumio[953]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 10 17:55:28 volumio volumio[953]: info: Received Get System Version Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 17:55:28 volumio volumio[953]: info: Received Get System Info Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:55:28 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:28 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:28 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:55:33 volumio volumio[953]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jul 10 17:55:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jul 10 17:55:33 volumio volumio[953]: info: Creating Spotify config file Jul 10 17:55:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 17:55:33 volumio volumio[953]: info: Spotify config file written Jul 10 17:55:33 volumio sudo[2477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 10 17:55:33 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:55:33 volumio systemd[1]: Stopping go-librespot Daemon... Jul 10 17:55:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jul 10 17:55:33 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Jul 10 17:55:33 volumio systemd[1]: Stopped go-librespot Daemon. Jul 10 17:55:33 volumio volumio[953]: info: Connection to go-librespot Websocket closed Jul 10 17:55:33 volumio volumio[953]: info: Connection to go-librespot Websocket closed Jul 10 17:55:33 volumio systemd[1]: Started go-librespot Daemon. Jul 10 17:55:33 volumio go-librespot[2479]: Librespot-go daemon starting... Jul 10 17:55:33 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root Jul 10 17:55:33 volumio go-librespot[2479]: time="2024-07-10T17:55:33+02:00" level=info msg="generated new device id: 70a04774e288e9197b817b5c95ca5c89f587dd87" Jul 10 17:55:33 volumio go-librespot[2479]: time="2024-07-10T17:55:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Jul 10 17:55:33 volumio go-librespot[2479]: time="2024-07-10T17:55:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jul 10 17:55:33 volumio go-librespot[2479]: time="2024-07-10T17:55:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jul 10 17:55:33 volumio go-librespot[2479]: time="2024-07-10T17:55:33+02:00" level=debug msg="zeroconf server listening on port 40667" Jul 10 17:55:34 volumio ntpd[952]: 91.212.242.20 local addr 10.223.223.20 -> Jul 10 17:55:36 volumio volumio[953]: info: Initializing connection to go-librespot Websocket Jul 10 17:55:36 volumio go-librespot[2479]: time="2024-07-10T17:55:36+02:00" level=debug msg="new websocket client" Jul 10 17:55:36 volumio volumio[953]: info: Connection to go-librespot Websocket established Jul 10 17:55:36 volumio volumio[953]: info: go-librespot daemon successfully initialized Jul 10 17:55:36 volumio ntpd[952]: 91.212.242.21 local addr 10.223.223.20 -> Jul 10 17:55:39 volumio volumio[953]: info: Getting Spotify volume Jul 10 17:55:39 volumio volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 10 17:55:39 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:39 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:39 volumio volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Jul 10 17:55:39 volumio volumio[953]: info: Initializing connection to go-librespot Websocket Jul 10 17:55:39 volumio go-librespot[2479]: time="2024-07-10T17:55:39+02:00" level=debug msg="new websocket client" Jul 10 17:55:39 volumio volumio[953]: info: Connection to go-librespot Websocket established Jul 10 17:55:39 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 17:55:39 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 10 17:55:42 volumio volumio[953]: info: Getting Spotify volume Jul 10 17:55:42 volumio volumio[953]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 10 17:55:42 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:55:42 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:42 volumio volumio[953]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Jul 10 17:55:46 volumio volumio[953]: info: CoreCommandRouter::volumioPlay Jul 10 17:55:46 volumio volumio[953]: info: CoreStateMachine::play index undefined Jul 10 17:55:46 volumio volumio[953]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 17:55:46 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:55:48 volumio volumio[953]: info: CoreCommandRouter::volumioGetQueue Jul 10 17:55:48 volumio volumio[953]: info: CoreStateMachine::getQueue Jul 10 17:55:48 volumio volumio[953]: info: CorePlayQueue::getQueue Jul 10 17:55:53 volumio go-librespot[2479]: time="2024-07-10T17:55:53+02:00" level=debug msg="obtained new client token: AACWkMUAzklSK+Hy9vWW+nwXM2eQaeAOg+kgbx+tHRKSuOaspn9Ug8DcvI5pPlXN+eFuskFJv38ZhNaZYOo/viW9FA9PYxl+wHz736N0KaxlDKOYdNFxBJ3qjH4ixdtYLj2M1lnyi4NPcFYmx4kxlpSi8qBuJkkRW9xy0v1VNYWB8UxORkGW42xdOszT/3v3jbkAI2xhlehYfiCpcPjUkUZ6eUFo1NP14UNQBPvQHBpjMcsl0Gp5gcx+Qh0Qeg==" Jul 10 17:55:53 volumio go-librespot[2479]: time="2024-07-10T17:55:53+02:00" level=debug msg="completed keyexchange" Jul 10 17:55:53 volumio go-librespot[2479]: time="2024-07-10T17:55:53+02:00" level=debug msg="completed challenge" Jul 10 17:55:53 volumio go-librespot[2479]: time="2024-07-10T17:55:53+02:00" level=debug msg="authenticated as kostpiotr" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="authenticated as kostpiotr" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="dealer connection opened" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=info msg="accepted zeroconf user kostpiotr from Redmi Note 9 Pro" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="received connection id: M2RjZWQ0OTgtYTgwNC00OWRkLTgzMmEtYTY2Yjg5MjUxN2FiK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXprZ3QuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwKzU5NTdDNDEzMjA2Q0RCRTI3MjM5MDM3MUE2RjBDMTY1N0MyREQ5NEFERUVCMDJBNjkyMTU4NzMzMjFDQzIyRDk=" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=debug msg="handling transfer player command from b274734a7e9a835a289163ad418852bad378962e" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 10 17:55:54 volumio go-librespot[2479]: time="2024-07-10T17:55:54+02:00" level=warning msg="failed handling dealer request" error="failed seeking to track: failed fetching tracks for seek: failed moving to next index 50 (page 1): failed fetching next page: invalid status code from page at radio-apollo/v3/tracks/spotify:station:track:5tlovHXo92q7COPx5DIbQb?salt=432270&autoplay=false&count=50&isVideo=false&prev_tracks=5tlovHXo92q7COPx5DIbQb,6CPwgTLd4dF1sAEgNkwsdW,31MxgrG8q7kr8nrzp0xuJJ,3mQOOsjdN2lrnYPa791oCC,3v0fVON6z5CnRlle3SJZU1,3KeqxSAY5a57hmnX2PN0P0,0Eos8DjtN7nC1p4xvcQEaL,7lpGfZeRhcLlvpcDcSKCFQ,6ARQYuejfkqO1DOcMRdga5,2AbDY2agHP7e5slykeHPxJ,5wY7ZogIkopqocAjGeyzPO,3pcolFc5pB3hp1eqPvm2TU,4WxTq8GtcBtrMow05xVOEN,6MfKJ91JuLky1UAVsqxXZ5,5DeeewpH31qpJzbEXnim2I,4xgr9jRX7oZRxpImAAKUJV,07xnoJyNRBq74nbf0Mp2m4,1CMurFTCEctHTFMjnZO4RD,01VEpM38d3fcWZddfuYWOv,6dRJJMO9URXnTJaz401b4Q,4MsWJOddO4sVhq44t6MVTf,2qhSv7Qxq84xXVDadM1z1U,3RAii1PmgM8KXRyT5MT8W8,05hk8ivnCt0HP3aI1uWJfi,3BNcC5diWtNGRHdYECNfPI,0p1MFWA2lSy3qUn7LpqpEA,4eG1ODcdjySLxlB9dtmbiv,6XJQYViXSKTe6uvI2obPgJ,4OTVr1uhkXIJbxZNMYCAcH,6eYIQXUtSiem6iiove22xd,2UMVrkI0nDnCGR0BWk2QsV,3uqRk43i7gbpqZwcC7QU7e,3x7Hf4jM1DAdUKSvQ3vhRj,2GCGb7SS4p8vz7MkrP0Bcf,2y0nc5BhBjkYKQzXYqDdek,1A7Wbc3KOFX5QoJZbuJaDP,04JRnQxcP1YHWspb6mY9l6,5u7JrYOfW3QTSGp89MguWg,52OBzbOsxfSsQWZqgvk1ff,3EMwcW0vMSOtdun4Xw5RkK,0spiAJAKdsIZ6ES6UrC9NJ,5fmjPMfpqJ2h4kb5po028N,4z5r3ux9X5XjUNpwN754wN,3lydXt9Zi8w22wS5KKRGSd,1tbfHPbrcz0oAwwTWQ7CwB,6raP6x98Jp6fh3m3HISxe7,2mda3zRbgCzSa8LtYOmwL7,0sj5IrDq4607CSipqguziD,52QHnYu4yvZ8bpLpw6nVtR,5lvw0sQp2GkdyD8jdBQHZ7&minimal=true: 502" Jul 10 17:55:58 volumio go-librespot[2479]: time="2024-07-10T17:55:58+02:00" level=debug msg="handling transfer player command from b274734a7e9a835a289163ad418852bad378962e" Jul 10 17:55:58 volumio go-librespot[2479]: time="2024-07-10T17:55:58+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 10 17:55:58 volumio go-librespot[2479]: time="2024-07-10T17:55:58+02:00" level=warning msg="failed handling dealer request" error="failed seeking to track: failed fetching tracks for seek: failed moving to next index 50 (page 1): failed fetching next page: invalid status code from page at radio-apollo/v3/tracks/spotify:station:track:5tlovHXo92q7COPx5DIbQb?salt=291997&autoplay=false&count=50&isVideo=false&prev_tracks=5tlovHXo92q7COPx5DIbQb,6CPwgTLd4dF1sAEgNkwsdW,31MxgrG8q7kr8nrzp0xuJJ,3mQOOsjdN2lrnYPa791oCC,3v0fVON6z5CnRlle3SJZU1,3KeqxSAY5a57hmnX2PN0P0,0Eos8DjtN7nC1p4xvcQEaL,7lpGfZeRhcLlvpcDcSKCFQ,6ARQYuejfkqO1DOcMRdga5,2AbDY2agHP7e5slykeHPxJ,5wY7ZogIkopqocAjGeyzPO,3pcolFc5pB3hp1eqPvm2TU,4WxTq8GtcBtrMow05xVOEN,6MfKJ91JuLky1UAVsqxXZ5,5DeeewpH31qpJzbEXnim2I,4xgr9jRX7oZRxpImAAKUJV,07xnoJyNRBq74nbf0Mp2m4,1CMurFTCEctHTFMjnZO4RD,01VEpM38d3fcWZddfuYWOv,6dRJJMO9URXnTJaz401b4Q,4MsWJOddO4sVhq44t6MVTf,2qhSv7Qxq84xXVDadM1z1U,3RAii1PmgM8KXRyT5MT8W8,05hk8ivnCt0HP3aI1uWJfi,3BNcC5diWtNGRHdYECNfPI,0p1MFWA2lSy3qUn7LpqpEA,4eG1ODcdjySLxlB9dtmbiv,6XJQYViXSKTe6uvI2obPgJ,4OTVr1uhkXIJbxZNMYCAcH,6eYIQXUtSiem6iiove22xd,2UMVrkI0nDnCGR0BWk2QsV,3uqRk43i7gbpqZwcC7QU7e,1A7Wbc3KOFX5QoJZbuJaDP,2GCGb7SS4p8vz7MkrP0Bcf,2y0nc5BhBjkYKQzXYqDdek,04JRnQxcP1YHWspb6mY9l6,5fmjPMfpqJ2h4kb5po028N,5u7JrYOfW3QTSGp89MguWg,1tbfHPbrcz0oAwwTWQ7CwB,3EMwcW0vMSOtdun4Xw5RkK,0spiAJAKdsIZ6ES6UrC9NJ,4z5r3ux9X5XjUNpwN754wN,0sj5IrDq4607CSipqguziD,3lydXt9Zi8w22wS5KKRGSd,5lvw0sQp2GkdyD8jdBQHZ7,6raP6x98Jp6fh3m3HISxe7,2mda3zRbgCzSa8LtYOmwL7,52QHnYu4yvZ8bpLpw6nVtR,5jMEv7Z6zYMhGI2noZwAvl,1OS1H8hJCp6B6P8f5TZJES&minimal=true: 502" Jul 10 17:55:59 volumio go-librespot[2479]: time="2024-07-10T17:55:59+02:00" level=debug msg="put connect state inactive" Jul 10 17:55:59 volumio go-librespot[2479]: time="2024-07-10T17:55:59+02:00" level=trace msg="emitting websocket event: inactive" Jul 10 17:55:59 volumio volumio[953]: SPOTIFY: received: {"type":"inactive","data":null} Jul 10 17:55:59 volumio volumio[953]: error: Failed to decode event: inactive Jul 10 17:55:59 volumio volumio[953]: SPOTIFY: received: {"type":"inactive","data":null} Jul 10 17:55:59 volumio volumio[953]: error: Failed to decode event: inactive Jul 10 17:56:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:56:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:56:02 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:56:02 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:02 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:02 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:56:05 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 17:56:05 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:56:05 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root Jul 10 17:56:05 volumio sudo[2527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 17:56:05 volumio sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:56:05 volumio sudo[2527]: pam_unix(sudo:session): session closed for user root Jul 10 17:56:05 volumio volumio[953]: verbose: New Socket.io Connection to 10.223.223.20 from 10.223.223.17 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:05 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 10 17:56:05 volumio volumio[953]: info: Received Get System Info Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:56:05 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:05 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:05 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:05 volumio volumio[953]: info: Listing playlists Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 17:56:05 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 17:56:06 volumio volumio[953]: info: Received Get System Info Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:56:06 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:06 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 10 17:56:06 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 10 17:56:15 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 17:56:17 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 17:56:17 volumio volumio[953]: info: Received Get System Info Jul 10 17:56:17 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:56:17 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:56:17 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:56:17 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:17 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:17 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 17:56:24 volumio volumio[953]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 10 17:56:24 volumio volumio[953]: info: Received Get System Version Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 17:56:24 volumio volumio[953]: info: Received Get System Info Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:56:24 volumio volumio[953]: info: Discovery: Getting this device information Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Jul 10 17:56:24 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Jul 10 17:56:24 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:56:25 volumio volumio[953]: info: Retrieving Cloud Streaming UI Jul 10 17:56:25 volumio volumio[953]: info: Getting Tidal Cloud Configuration Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:56:25 volumio volumio[953]: info: Getting Qobuz Cloud Configuration Jul 10 17:56:25 volumio volumio[953]: info: Asking plugin for UI Config Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:56:25 volumio volumio[953]: info: Getting Spotify Cloud Configuration Jul 10 17:56:25 volumio volumio[953]: info: Asking plugin for UI Config Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:56:25 volumio volumio[953]: info: Saving Spotify Acccount Jul 10 17:56:25 volumio volumio[953]: info: Got Tidal Cloud Configuration Jul 10 17:56:25 volumio volumio[953]: info: Got it Jul 10 17:56:25 volumio volumio[953]: info: Got it Jul 10 17:56:25 volumio volumio[953]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 17:56:25 volumio volumio[953]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 17:56:25 volumio volumio[953]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 17:56:26 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 17:56:26 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 10 17:56:28 volumio volumio[953]: info: Disabling MyMusic plugin upnp Jul 10 17:56:28 volumio sudo[2566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 10 17:56:28 volumio sudo[2566]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:56:28 volumio volumio[953]: error: Upnp client error: Error: This socket has been ended by the other party Jul 10 17:56:28 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 10 17:56:30 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 10 17:56:31 volumio volumio[953]: info: Enabling MyMusic plugin upnp Jul 10 17:56:31 volumio volumio[953]: info: Enabling plugin upnp Jul 10 17:56:31 volumio volumio[953]: info: Loading plugin "upnp"... Jul 10 17:56:31 volumio volumio[953]: info: [1720626991784] Starting Upmpd Daemon Jul 10 17:56:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 17:56:31 volumio volumio[953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:56:31 volumio volumio[953]: Error: listen EADDRINUSE: address already in use :::6599 Jul 10 17:56:31 volumio volumio[953]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jul 10 17:56:31 volumio volumio[953]: at listenInCluster (net.js:1366:12) Jul 10 17:56:31 volumio volumio[953]: at Server.listen (net.js:1452:7) Jul 10 17:56:31 volumio volumio[953]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 10 17:56:31 volumio volumio[953]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 10 17:56:31 volumio volumio[953]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 10 17:56:31 volumio volumio[953]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 10 17:56:31 volumio volumio[953]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 10 17:56:31 volumio volumio[953]: code: 'EADDRINUSE', Jul 10 17:56:31 volumio volumio[953]: errno: -98, Jul 10 17:56:31 volumio volumio[953]: syscall: 'listen', Jul 10 17:56:31 volumio volumio[953]: address: '::', Jul 10 17:56:31 volumio volumio[953]: port: 6599 Jul 10 17:56:31 volumio volumio[953]: } Jul 10 17:56:31 volumio volumio[953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:56:32 volumio sudo[2615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 17:55 Jul 10 17:56:32 volumio sudo[2615]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"