-- Logs begin at Thu 2019-02-14 12:11:59 EET, end at Sat 2024-09-07 12:35:35 EEST. -- Sep 07 12:34:00 minidsp-shd pulseaudio[1173]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 07 12:34:00 minidsp-shd pulseaudio[1173]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 07 12:34:00 minidsp-shd volumiobt[1149]: Applying permissions Sep 07 12:34:00 minidsp-shd volumiobt[1149]: Setting BT discoverable and pairable Sep 07 12:34:00 minidsp-shd pulseaudio[1173]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [38B blob data] Sep 07 12:34:00 minidsp-shd volumiobt[1149]: Invalid command Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [bluetooth]# agent on Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [bluetooth]# discoverable on Sep 07 12:34:00 minidsp-shd volumiobt[1149]: No default controller available Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [bluetooth]# pairable on Sep 07 12:34:00 minidsp-shd volumiobt[1149]: No default controller available Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [bluetooth]# agent NoInputNoOutput Sep 07 12:34:00 minidsp-shd volumiobt[1149]: Failed to register agent object Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [bluetooth]# default-agent Sep 07 12:34:00 minidsp-shd volumiobt[1149]: No agent is registered Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [bluetooth]# quit Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [61B blob data] Sep 07 12:34:00 minidsp-shd volumiobt[1149]: [62B blob data] Sep 07 12:34:01 minidsp-shd volumio[635]: info: TidalConnect service started! Sep 07 12:34:01 minidsp-shd mpd[1050]: Sep 07 12:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 07 12:34:01 minidsp-shd systemd[1]: Started Music Player Daemon. Sep 07 12:34:01 minidsp-shd volumio[635]: Upnp client error: Error: This socket has been ended by the other party Sep 07 12:34:01 minidsp-shd sudo[1046]: pam_unix(sudo:session): session closed for user root Sep 07 12:34:01 minidsp-shd sudo[1035]: pam_unix(sudo:session): session closed for user root Sep 07 12:34:01 minidsp-shd volumio[635]: info: Completed starting Core Plugins Sep 07 12:34:01 minidsp-shd volumio[635]: info: ------------------------------------------- Sep 07 12:34:01 minidsp-shd volumio[635]: info: ----- MyVolumio plugins startup ---- Sep 07 12:34:01 minidsp-shd volumio[635]: info: ------------------------------------------- Sep 07 12:34:01 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 07 12:34:01 minidsp-shd volumio[635]: error: MPD error: The expression evaluated to a falsy value: Sep 07 12:34:01 minidsp-shd volumio[635]: assert.ok(self.idling) Sep 07 12:34:01 minidsp-shd volumio[635]: error: The expression evaluated to a falsy value: Sep 07 12:34:01 minidsp-shd volumio[635]: assert.ok(self.idling) Sep 07 12:34:01 minidsp-shd volumio[635]: info: MPD running with PID1050 Sep 07 12:34:01 minidsp-shd volumio[635]: ,establishing connection Sep 07 12:34:01 minidsp-shd volumio[635]: error: updateQueue error: null Sep 07 12:34:02 minidsp-shd volumio[635]: error: updateQueue error: null Sep 07 12:34:02 minidsp-shd volumio[635]: info: Initializing connection to go-librespot Websocket Sep 07 12:34:02 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:02+03:00" level=debug msg="new websocket client" Sep 07 12:34:02 minidsp-shd volumio[635]: info: Connection to go-librespot Websocket established Sep 07 12:34:03 minidsp-shd volumio[1033]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 07 12:34:03 minidsp-shd volumio[1033]: ..++++ Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 12:34:04 minidsp-shd volumio[635]: info: Not Reporting Auto name since its the default one Sep 07 12:34:04 minidsp-shd volumio[635]: info: RAAT Overriding default device vendor model Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 07 12:34:04 minidsp-shd volumio[635]: xcb_connection_has_error() returned true Sep 07 12:34:04 minidsp-shd sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 07 12:34:04 minidsp-shd sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:34:04 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Sep 07 12:34:04 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Sep 07 12:34:04 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Sep 07 12:34:04 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Sep 07 12:34:04 minidsp-shd volumio[635]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 07 12:34:04 minidsp-shd systemd[1]: Started RAAT DAEMON. Sep 07 12:34:04 minidsp-shd volumio[635]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 07 12:34:04 minidsp-shd sudo[1227]: pam_unix(sudo:session): session closed for user root Sep 07 12:34:04 minidsp-shd volumio[635]: info: Raat Daemon started successfully Sep 07 12:34:04 minidsp-shd volumio[635]: info: Executing endpoint restartRAATSocket Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Sep 07 12:34:04 minidsp-shd volumio[635]: info: updateDSP function in raat called! Sep 07 12:34:04 minidsp-shd volumio[635]: info: Updating RAAT Signal Path Sep 07 12:34:04 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Sep 07 12:34:04 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:34:05 minidsp-shd volumio[635]: info: Getting Spotify volume Sep 07 12:34:05 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Sep 07 12:34:05 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:34:05 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 07 12:34:05 minidsp-shd volumio[635]: SPOTIFY: SPOTIFY VOLUME undefined Sep 07 12:34:05 minidsp-shd volumio[635]: SPOTIFY: VOLUMIO VOLUME 78 Sep 07 12:34:05 minidsp-shd volumio[635]: info: Aligning Spotify Volume to Volumio Volume Sep 07 12:34:05 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:34:05 minidsp-shd volumio[635]: info: Setting Spotify Volume from Volumio: 78 Sep 07 12:34:06 minidsp-shd volumio[635]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 07 12:34:07 minidsp-shd volumio[635]: SPOTIFY: SETTING SPOTIFY VOLUME 78 Sep 07 12:34:07 minidsp-shd volumio[635]: info: Sending Spotify command with payload to local API: /player/volume Sep 07 12:34:07 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:07+03:00" level=debug msg="obtained new client token: AADEI/MqUqqQLiiyLYFjPsUNN9GaXT8HR07r8S57r16r5FcQfZvfYnuoKRIjyEia6HFsO7bCLlOFqILNT8qhg3+SD+HLaSkKUnIJbxGynLZ/A8tLVQ7D8pWICoojjD4ltV7+qptvd6x7/OIk3JiCribD55/N+xfm+1b9i/OomurBxu7zxTyc1H9vs40qqfBNpQa3ZDLHaEsq4oY6HQQnT2+a8qXRupnTYuc3DhW5cp6gxFj9sk7TCzXQ2i6J5pCE" Sep 07 12:34:07 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:07+03:00" level=debug msg="completed keyexchange" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="completed challenge" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="authenticated as 316ixp265ri2ogsrsyb2ao4fls4y" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="authenticated as 316ixp265ri2ogsrsyb2ao4fls4y" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="dealer connection opened" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=info msg="accepted zeroconf user 316ixp265ri2ogsrsyb2ao4fls4y from iPhone" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="received connection id: OWRlYjIwZDYtMmE5Zi00ZGUyLWI0YmMtMjg0YTg4ZmMzNWEwK2RlYWxlcit0Y3A6Ly8wYWNhNDAwNy5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMjA5OTI3RDdGOTEzRDUwREY1RjJFMzk1NzA5MkNGRkIyQ0I0RjUzNTdCQzYwQzI5QzA5QUM5N0IxQkI4MkZFOQ==" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="put connect state because NEW_DEVICE" Sep 07 12:34:08 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:08+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:09 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:09+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:09 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:09+03: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:3tipil3yEe5xpg0oFNHG10?salt=988627&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,7FXuCkl1kfxwS5icDjTwbM,4qYHnP5AmKzXbJhciPV8si,6gJnxMZphJt3sk2JJXTkoJ,047Ip9c6dijAJfuwloV3NF,0vlCOzte4bru0gK74lfUIJ,7v6GgmyNIsNwLBPsp1pzdG,2txRs0wxjGE1uzOhcBxN7L,2FbLpNNlImB5mqCHgVIG1c,1a2iF9XymafjRk56q7oCxo,6G7MHIvSDR3ehXPkuDFAlM,2kKNeapwCHUUvMhmAOGce6,4fQMGlCawbTkH9yPPZ49kP,2dp14VWbIxOVNmaWKkVB1r,3CBV8LLmD6YACpUR6P85Di,0nFZ2HQRnIxiwraCm2X6Uq,46oxhVXWECsaPytsDW6GKb,62JvDbmEtwIU3m4LhiG6Ah,35mKWzraWktvQcTGfuAHbV,5etSCHyQ7oBksPksGxnLwL,4xu0LW34dFEoHG1x65tQPx,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,6iQ0OIxisPxhzEICEXtaWS,6nleD42Dimnc5fHnpA2T2R,2kaCrDduHQk88sOfzW4qzJ,4KMXlzvtC8xjLseDqDjpeU,58PSYdY0GFg0LFb2PxYk4T,53ri7XzOM6KHb7SZnHxEMU,6726edIl6xRsfTuyrtwHRi,1lzaRptS63unC0qgT6hm7T,31lG2URaPvdi7atAM8Tu4V,7sKHpszyRFBIAT378e6Tkr,7Efb2PkxBWN6b0fY8PiAzU,0Pig681y0YWdUClrmDgIBr,1YM0WEEhit7WL15fkLkwMI,4zuHizF9ydEXDaAtwU2eAK,1KQEzCaNkk8ioBMbBeSYLw,2Vo7n5XmHOS4djkr6QjHEG,69AMgoqKt39WvDRFIX6WQw,5bC6ONDsL88snGN6QasjZH,18n0zch7bRo453Iwq98xYf,0yJNGGGZ9eQj4vmvmeornZ,3WfjYZbJiVpdjPdeduMLFX,7dkUdSqTKjkTOemqLSPgeG,4Ra0IrmzAPudI6QMvNF8cg,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN&minimal=true: 502" Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 07 12:34:10 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 07 12:34:12 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 07 12:34:12 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 07 12:34:12 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:12 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:12 minidsp-shd volumio[635]: info: Starting MyVolumio Remote Streaming Endpoints Sep 07 12:34:12 minidsp-shd volumio[635]: info: MyVolumio login type: Token Sep 07 12:34:12 minidsp-shd volumio[635]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 07 12:34:12 minidsp-shd volumio[635]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Sep 07 12:34:12 minidsp-shd volumio[1033]: ....................................................................................................................++++ Sep 07 12:34:12 minidsp-shd volumio[1033]: e is 65537 (0x010001) Sep 07 12:34:12 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:12+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:12 minidsp-shd volumio[1033]: writing RSA key Sep 07 12:34:12 minidsp-shd volumio[635]: error: MyVolumio Custom Token format not valid, refreshing it Sep 07 12:34:12 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:12+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:13 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:13+03: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:3tipil3yEe5xpg0oFNHG10?salt=908112&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,2txRs0wxjGE1uzOhcBxN7L,2FbLpNNlImB5mqCHgVIG1c,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,2dp14VWbIxOVNmaWKkVB1r,4xu0LW34dFEoHG1x65tQPx,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,35mKWzraWktvQcTGfuAHbV,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,4qYHnP5AmKzXbJhciPV8si,2kKNeapwCHUUvMhmAOGce6,6iQ0OIxisPxhzEICEXtaWS,6726edIl6xRsfTuyrtwHRi,4zuHizF9ydEXDaAtwU2eAK,1KQEzCaNkk8ioBMbBeSYLw,58PSYdY0GFg0LFb2PxYk4T,53ri7XzOM6KHb7SZnHxEMU,62JvDbmEtwIU3m4LhiG6Ah,0Pig681y0YWdUClrmDgIBr,31lG2URaPvdi7atAM8Tu4V,3WfjYZbJiVpdjPdeduMLFX,7Efb2PkxBWN6b0fY8PiAzU,5bC6ONDsL88snGN6QasjZH,1lzaRptS63unC0qgT6hm7T,0yJNGGGZ9eQj4vmvmeornZ,2fizsCfngbjfArn0y8MlfJ,69AMgoqKt39WvDRFIX6WQw,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,1qNL3vQCtN3bi3aB31iIjF,3BnnWKoUg5BKIflQJ7dyAz,4Ra0IrmzAPudI6QMvNF8cg,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,6JjDGQoxsbVBlzRjT6eFlg&minimal=true: 502" Sep 07 12:34:13 minidsp-shd volumio[635]: info: MyVolumio login type: Token Sep 07 12:34:14 minidsp-shd volumio[635]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 07 12:34:14 minidsp-shd volumio[635]: info: MyVolumio token set successfully Sep 07 12:34:14 minidsp-shd volumio[635]: info: MYVOLUMIO: Adding device Sep 07 12:34:14 minidsp-shd volumio[635]: info: MYVOLUMIO: Evaluating Server Sep 07 12:34:15 minidsp-shd volumio[635]: info: MyVolumio status changed Sep 07 12:34:15 minidsp-shd volumio[635]: info: Streaming services startup Sep 07 12:34:15 minidsp-shd volumio[635]: info: Starting Streaming Daemon Sep 07 12:34:15 minidsp-shd volumio[635]: info: Removing browser output: myVolumio user plan is not superstar Sep 07 12:34:15 minidsp-shd volumio[635]: info: Removing audio output: Sep 07 12:34:15 minidsp-shd volumio[635]: info: Stoppping Tunnel 1 Sep 07 12:34:15 minidsp-shd sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 07 12:34:15 minidsp-shd sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:34:15 minidsp-shd sudo[1336]: pam_unix(sudo:session): session closed for user root Sep 07 12:34:15 minidsp-shd sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 07 12:34:15 minidsp-shd sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:34:15 minidsp-shd volumio[635]: error: Cannot start Volumio Streaming Daemon Sep 07 12:34:15 minidsp-shd volumio[635]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 07 12:34:15 minidsp-shd volumio[635]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 07 12:34:15 minidsp-shd sudo[1339]: pam_unix(sudo:session): session closed for user root Sep 07 12:34:15 minidsp-shd volumio[635]: info: Remote SSH Stopped Sep 07 12:34:15 minidsp-shd volumio[635]: info: Setting Geolocation for MyVolumio to eu6 Sep 07 12:34:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:15 minidsp-shd volumio[635]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 07 12:34:16 minidsp-shd volumio[635]: info: Updating MyVolumio device info Sep 07 12:34:16 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:16 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:17 minidsp-shd volumio[635]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 12:34:19 minidsp-shd volumio[635]: Cannot find translation for source Presets Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioStop Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::stop Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::serviceStop Sep 07 12:34:19 minidsp-shd volumio[635]: info: Received STOP, but no service to execute it Sep 07 12:34:19 minidsp-shd volumio[635]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"SPDIF","disableUiControls":true,"albumart":"/albumart"} Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 12:34:19 minidsp-shd volumio[635]: Cannot find translation for source Presets Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioStop Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::stop Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::serviceStop Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::serviceStop Sep 07 12:34:19 minidsp-shd volumio[635]: info: [1725701659623] inputs::stop Sep 07 12:34:19 minidsp-shd volumio[635]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"AES-EBU","disableUiControls":true,"albumart":"/albumart"} Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 12:34:19 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Sep 07 12:34:19 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Sep 07 12:34:19 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Sep 07 12:34:19 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 07 12:34:19 minidsp-shd volumio[635]: info: MYVOLUMIO: Adding device Sep 07 12:34:19 minidsp-shd volumio[635]: info: MYVOLUMIO: Evaluating Server Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Sep 07 12:34:20 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Sep 07 12:34:20 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Sep 07 12:34:20 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 12:34:20 minidsp-shd volumio[635]: Cannot find translation for source Presets Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioStop Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::stop Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::serviceStop Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::serviceStop Sep 07 12:34:20 minidsp-shd volumio[635]: info: [1725701660741] inputs::stop Sep 07 12:34:20 minidsp-shd volumio[635]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"RCA","disableUiControls":true,"albumart":"/albumart"} Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 12:34:20 minidsp-shd volumio[635]: Cannot find translation for source Presets Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioStop Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::stop Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::serviceStop Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::serviceStop Sep 07 12:34:20 minidsp-shd volumio[635]: info: [1725701660769] inputs::stop Sep 07 12:34:20 minidsp-shd volumio[635]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"XLR","disableUiControls":true,"albumart":"/albumart"} Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 12:34:20 minidsp-shd volumio[635]: info: Setting Geolocation for MyVolumio to eu6 Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:20 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Sep 07 12:34:21 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Sep 07 12:34:21 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Sep 07 12:34:21 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 12:34:21 minidsp-shd volumio[635]: Cannot find translation for source Presets Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioStop Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreStateMachine::stop Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreStateMachine::serviceStop Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::serviceStop Sep 07 12:34:21 minidsp-shd volumio[635]: info: [1725701661242] inputs::stop Sep 07 12:34:21 minidsp-shd volumio[635]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"USB","disableUiControls":true,"albumart":"/albumart"} Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 07 12:34:21 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 07 12:34:21 minidsp-shd volumio[635]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::servicePushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreStateMachine::pushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioPushState Sep 07 12:34:21 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output update for this device Sep 07 12:34:21 minidsp-shd volumio[635]: info: MRS: Pushing multiroomSync output Sep 07 12:34:21 minidsp-shd volumio[635]: SPOTIFY: RECEIVED VOLUMIO VOLUME 78 Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetSourceActiveno-source Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 12:34:21 minidsp-shd volumio[635]: Cannot find translation for source Presets Sep 07 12:34:21 minidsp-shd volumio[635]: error: MyVolumio Plugin failed to start in a timely fashion Sep 07 12:34:21 minidsp-shd volumio[635]: info: BOOT COMPLETED Sep 07 12:34:21 minidsp-shd volumio[635]: [Metrics] CommandRouter: 65s 255.98ms Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::volumiosetStartupVolume Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::Close All Modals sent Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::Close All Modals sent Sep 07 12:34:21 minidsp-shd volumio[635]: info: Updating MyVolumio device info Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:21 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:22 minidsp-shd volumio[635]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 07 12:34:22 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 07 12:34:22 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 07 12:34:22 minidsp-shd volumio[635]: xcb_connection_has_error() returned true Sep 07 12:34:23 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 07 12:34:24 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 12:34:24 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 12:34:24 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 07 12:34:25 minidsp-shd volumio-remote-updater[465]: No test mode Sep 07 12:34:25 minidsp-shd volumio-remote-updater[465]: No alpha test mode Sep 07 12:34:25 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 07 12:34:25 minidsp-shd systemd[1]: systemd-hostnamed.service: Succeeded. Sep 07 12:34:25 minidsp-shd volumio[635]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 07 12:34:25 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 07 12:34:27 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 07 12:34:27 minidsp-shd volumio[635]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Sep 07 12:34:27 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:34:27 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:27+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Sep 07 12:34:27 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:27+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 337" Sep 07 12:34:27 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:27+03:00" level=debug msg="put connect state inactive" Sep 07 12:34:27 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:27+03:00" level=trace msg="emitting websocket event: inactive" Sep 07 12:34:27 minidsp-shd volumio[635]: SPOTIFY: received: {"type":"inactive","data":null} Sep 07 12:34:27 minidsp-shd volumio[635]: error: Failed to decode event: inactive Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetBrowseSources Sep 07 12:34:32 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 12:34:33 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:33+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:33 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:33+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:33 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:33+03: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:3tipil3yEe5xpg0oFNHG10?salt=242312&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,2miIOgni3EEut8pvYcxeYn,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,6gJnxMZphJt3sk2JJXTkoJ,7v6GgmyNIsNwLBPsp1pzdG,0vlCOzte4bru0gK74lfUIJ,4YDMaSvtnw0yxNSJcDylMd,3CBV8LLmD6YACpUR6P85Di,2FbLpNNlImB5mqCHgVIG1c,1a2iF9XymafjRk56q7oCxo,6G7MHIvSDR3ehXPkuDFAlM,4fQMGlCawbTkH9yPPZ49kP,46oxhVXWECsaPytsDW6GKb,2dp14VWbIxOVNmaWKkVB1r,4xu0LW34dFEoHG1x65tQPx,0nFZ2HQRnIxiwraCm2X6Uq,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,35mKWzraWktvQcTGfuAHbV,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,4qYHnP5AmKzXbJhciPV8si,2kKNeapwCHUUvMhmAOGce6,6iQ0OIxisPxhzEICEXtaWS,6726edIl6xRsfTuyrtwHRi,1KQEzCaNkk8ioBMbBeSYLw,3WfjYZbJiVpdjPdeduMLFX,58PSYdY0GFg0LFb2PxYk4T,53ri7XzOM6KHb7SZnHxEMU,62JvDbmEtwIU3m4LhiG6Ah,0Pig681y0YWdUClrmDgIBr,31lG2URaPvdi7atAM8Tu4V,2fizsCfngbjfArn0y8MlfJ,7Efb2PkxBWN6b0fY8PiAzU,5bC6ONDsL88snGN6QasjZH,1lzaRptS63unC0qgT6hm7T,0yJNGGGZ9eQj4vmvmeornZ,3BnnWKoUg5BKIflQJ7dyAz,69AMgoqKt39WvDRFIX6WQw,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,1qNL3vQCtN3bi3aB31iIjF,4Ra0IrmzAPudI6QMvNF8cg,6xat13LRDXtGdved0zKZGd,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,6JjDGQoxsbVBlzRjT6eFlg&minimal=true: 502" Sep 07 12:34:36 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:36+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:37 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:37+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:37 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:37+03: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:3tipil3yEe5xpg0oFNHG10?salt=789234&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,3CBV8LLmD6YACpUR6P85Di,2dp14VWbIxOVNmaWKkVB1r,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,4xu0LW34dFEoHG1x65tQPx,2kaCrDduHQk88sOfzW4qzJ,6iQ0OIxisPxhzEICEXtaWS,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,5etSCHyQ7oBksPksGxnLwL,6726edIl6xRsfTuyrtwHRi,35mKWzraWktvQcTGfuAHbV,4qYHnP5AmKzXbJhciPV8si,2kKNeapwCHUUvMhmAOGce6,58PSYdY0GFg0LFb2PxYk4T,1KQEzCaNkk8ioBMbBeSYLw,4KGdZpFNRhTLQXVyvp1tbE,0Pig681y0YWdUClrmDgIBr,5bC6ONDsL88snGN6QasjZH,62JvDbmEtwIU3m4LhiG6Ah,0yJNGGGZ9eQj4vmvmeornZ,3WfjYZbJiVpdjPdeduMLFX,53ri7XzOM6KHb7SZnHxEMU,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN,1lzaRptS63unC0qgT6hm7T,3KSchPNSklO5McIqRH3qYX,3BnnWKoUg5BKIflQJ7dyAz,7Efb2PkxBWN6b0fY8PiAzU,1qNL3vQCtN3bi3aB31iIjF,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,6JjDGQoxsbVBlzRjT6eFlg,6xat13LRDXtGdved0zKZGd,3o3YRMEJNhKr2bqo4aiOss,69AMgoqKt39WvDRFIX6WQw,38DAUnVJ0zqOdRuW7RG3sl,6lqxlJWNaykLli5cIXMf7l&minimal=true: 502" Sep 07 12:34:41 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:41+03:00" level=debug msg="put connect state inactive" Sep 07 12:34:41 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:41+03:00" level=trace msg="emitting websocket event: inactive" Sep 07 12:34:41 minidsp-shd volumio[635]: SPOTIFY: received: {"type":"inactive","data":null} Sep 07 12:34:41 minidsp-shd volumio[635]: error: Failed to decode event: inactive Sep 07 12:34:43 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:43+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:43 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:43+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:43 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:43+03: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:3tipil3yEe5xpg0oFNHG10?salt=667800&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,2miIOgni3EEut8pvYcxeYn,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,1a2iF9XymafjRk56q7oCxo,0vlCOzte4bru0gK74lfUIJ,4YDMaSvtnw0yxNSJcDylMd,3CBV8LLmD6YACpUR6P85Di,2FbLpNNlImB5mqCHgVIG1c,6G7MHIvSDR3ehXPkuDFAlM,0nFZ2HQRnIxiwraCm2X6Uq,4fQMGlCawbTkH9yPPZ49kP,46oxhVXWECsaPytsDW6GKb,2dp14VWbIxOVNmaWKkVB1r,4xu0LW34dFEoHG1x65tQPx,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,4qYHnP5AmKzXbJhciPV8si,35mKWzraWktvQcTGfuAHbV,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,2kKNeapwCHUUvMhmAOGce6,58PSYdY0GFg0LFb2PxYk4T,6iQ0OIxisPxhzEICEXtaWS,6726edIl6xRsfTuyrtwHRi,1KQEzCaNkk8ioBMbBeSYLw,3WfjYZbJiVpdjPdeduMLFX,62JvDbmEtwIU3m4LhiG6Ah,53ri7XzOM6KHb7SZnHxEMU,0Pig681y0YWdUClrmDgIBr,5bC6ONDsL88snGN6QasjZH,31lG2URaPvdi7atAM8Tu4V,3BnnWKoUg5BKIflQJ7dyAz,7Efb2PkxBWN6b0fY8PiAzU,1lzaRptS63unC0qgT6hm7T,0yJNGGGZ9eQj4vmvmeornZ,2uT3aswFopLTgJ4QtixmcJ,6xat13LRDXtGdved0zKZGd,69AMgoqKt39WvDRFIX6WQw,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,1qNL3vQCtN3bi3aB31iIjF,7j02rdE5RVtNcNMuLGY5SS,4Ra0IrmzAPudI6QMvNF8cg,3o3YRMEJNhKr2bqo4aiOss,1KywyxekfDEp3Ym7YP7S25,6JjDGQoxsbVBlzRjT6eFlg,38DAUnVJ0zqOdRuW7RG3sl&minimal=true: 502" Sep 07 12:34:47 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:47+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:47 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:47+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:47 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:47+03: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:3tipil3yEe5xpg0oFNHG10?salt=399003&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,3CBV8LLmD6YACpUR6P85Di,2dp14VWbIxOVNmaWKkVB1r,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,4xu0LW34dFEoHG1x65tQPx,6iQ0OIxisPxhzEICEXtaWS,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,4qYHnP5AmKzXbJhciPV8si,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,35mKWzraWktvQcTGfuAHbV,2kKNeapwCHUUvMhmAOGce6,58PSYdY0GFg0LFb2PxYk4T,6726edIl6xRsfTuyrtwHRi,1KQEzCaNkk8ioBMbBeSYLw,4KGdZpFNRhTLQXVyvp1tbE,0Pig681y0YWdUClrmDgIBr,62JvDbmEtwIU3m4LhiG6Ah,5bC6ONDsL88snGN6QasjZH,0yJNGGGZ9eQj4vmvmeornZ,3WfjYZbJiVpdjPdeduMLFX,53ri7XzOM6KHb7SZnHxEMU,2uT3aswFopLTgJ4QtixmcJ,1lzaRptS63unC0qgT6hm7T,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,7Efb2PkxBWN6b0fY8PiAzU,6xat13LRDXtGdved0zKZGd,1qNL3vQCtN3bi3aB31iIjF,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,69AMgoqKt39WvDRFIX6WQw,3o3YRMEJNhKr2bqo4aiOss,6JjDGQoxsbVBlzRjT6eFlg,38DAUnVJ0zqOdRuW7RG3sl,6lqxlJWNaykLli5cIXMf7l,4Ra0IrmzAPudI6QMvNF8cg&minimal=true: 502" Sep 07 12:34:57 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:57+03:00" level=debug msg="put connect state inactive" Sep 07 12:34:57 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:57+03:00" level=trace msg="emitting websocket event: inactive" Sep 07 12:34:57 minidsp-shd volumio[635]: SPOTIFY: received: {"type":"inactive","data":null} Sep 07 12:34:57 minidsp-shd volumio[635]: error: Failed to decode event: inactive Sep 07 12:34:58 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:58+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:34:59 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:59+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:34:59 minidsp-shd go-librespot[1098]: time="2024-09-07T12:34:59+03: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:3tipil3yEe5xpg0oFNHG10?salt=180094&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,3CBV8LLmD6YACpUR6P85Di,2dp14VWbIxOVNmaWKkVB1r,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,4xu0LW34dFEoHG1x65tQPx,2kaCrDduHQk88sOfzW4qzJ,6iQ0OIxisPxhzEICEXtaWS,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,5etSCHyQ7oBksPksGxnLwL,6726edIl6xRsfTuyrtwHRi,35mKWzraWktvQcTGfuAHbV,4qYHnP5AmKzXbJhciPV8si,2kKNeapwCHUUvMhmAOGce6,58PSYdY0GFg0LFb2PxYk4T,1KQEzCaNkk8ioBMbBeSYLw,4KGdZpFNRhTLQXVyvp1tbE,0Pig681y0YWdUClrmDgIBr,5bC6ONDsL88snGN6QasjZH,62JvDbmEtwIU3m4LhiG6Ah,0yJNGGGZ9eQj4vmvmeornZ,3WfjYZbJiVpdjPdeduMLFX,53ri7XzOM6KHb7SZnHxEMU,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN,1lzaRptS63unC0qgT6hm7T,3KSchPNSklO5McIqRH3qYX,7Efb2PkxBWN6b0fY8PiAzU,6xat13LRDXtGdved0zKZGd,1qNL3vQCtN3bi3aB31iIjF,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,69AMgoqKt39WvDRFIX6WQw,3o3YRMEJNhKr2bqo4aiOss,6JjDGQoxsbVBlzRjT6eFlg,38DAUnVJ0zqOdRuW7RG3sl,6lqxlJWNaykLli5cIXMf7l,4Ra0IrmzAPudI6QMvNF8cg&minimal=true: 502" Sep 07 12:35:02 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:02+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:35:02 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:02+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:35:02 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:02+03: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:3tipil3yEe5xpg0oFNHG10?salt=421701&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,3CBV8LLmD6YACpUR6P85Di,2FbLpNNlImB5mqCHgVIG1c,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,2dp14VWbIxOVNmaWKkVB1r,4xu0LW34dFEoHG1x65tQPx,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,35mKWzraWktvQcTGfuAHbV,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,4qYHnP5AmKzXbJhciPV8si,2kKNeapwCHUUvMhmAOGce6,6iQ0OIxisPxhzEICEXtaWS,6726edIl6xRsfTuyrtwHRi,1KQEzCaNkk8ioBMbBeSYLw,3WfjYZbJiVpdjPdeduMLFX,58PSYdY0GFg0LFb2PxYk4T,53ri7XzOM6KHb7SZnHxEMU,62JvDbmEtwIU3m4LhiG6Ah,0Pig681y0YWdUClrmDgIBr,31lG2URaPvdi7atAM8Tu4V,6xat13LRDXtGdved0zKZGd,7Efb2PkxBWN6b0fY8PiAzU,5bC6ONDsL88snGN6QasjZH,1lzaRptS63unC0qgT6hm7T,0yJNGGGZ9eQj4vmvmeornZ,3o3YRMEJNhKr2bqo4aiOss,69AMgoqKt39WvDRFIX6WQw,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,1qNL3vQCtN3bi3aB31iIjF,4Ra0IrmzAPudI6QMvNF8cg,7j02rdE5RVtNcNMuLGY5SS,6JjDGQoxsbVBlzRjT6eFlg,1KywyxekfDEp3Ym7YP7S25,38DAUnVJ0zqOdRuW7RG3sl&minimal=true: 502" Sep 07 12:35:05 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:05+03:00" level=debug msg="put connect state inactive" Sep 07 12:35:05 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:05+03:00" level=trace msg="emitting websocket event: inactive" Sep 07 12:35:05 minidsp-shd volumio[635]: SPOTIFY: received: {"type":"inactive","data":null} Sep 07 12:35:05 minidsp-shd volumio[635]: error: Failed to decode event: inactive Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 12:35:11 minidsp-shd volumio[635]: info: Discovery: Getting this device information Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 12:35:11 minidsp-shd volumio[635]: verbose: New Socket.io Connection to 192.168.68.108:3000 from 192.168.68.103 UA: Dart/3.4 (dart:io) Total Clients: 7 Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 07 12:35:11 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 07 12:35:14 minidsp-shd sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 12:35:14 minidsp-shd sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:35:14 minidsp-shd sudo[1404]: pam_unix(sudo:session): session closed for user root Sep 07 12:35:14 minidsp-shd sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 12:35:14 minidsp-shd sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:35:14 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 07 12:35:14 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 07 12:35:14 minidsp-shd volumio[635]: info: Discovery: Getting this device information Sep 07 12:35:14 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:35:14 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 07 12:35:14 minidsp-shd sudo[1407]: pam_unix(sudo:session): session closed for user root Sep 07 12:35:14 minidsp-shd volumio[635]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8 Sep 07 12:35:15 minidsp-shd sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 12:35:15 minidsp-shd sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:35:15 minidsp-shd sudo[1412]: pam_unix(sudo:session): session closed for user root Sep 07 12:35:15 minidsp-shd sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 12:35:15 minidsp-shd sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 12:35:15 minidsp-shd sudo[1415]: pam_unix(sudo:session): session closed for user root Sep 07 12:35:15 minidsp-shd volumio[635]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 Sep 07 12:35:15 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:35:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 12:35:15 minidsp-shd volumio[635]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 12:35:15 minidsp-shd volumio[635]: info: Listing playlists Sep 07 12:35:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 07 12:35:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 07 12:35:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 07 12:35:15 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 07 12:35:17 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 12:35:19 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 07 12:35:21 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:21+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:35:22 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:22+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:35:22 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:22+03: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:3tipil3yEe5xpg0oFNHG10?salt=403575&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,3CBV8LLmD6YACpUR6P85Di,2dp14VWbIxOVNmaWKkVB1r,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,4xu0LW34dFEoHG1x65tQPx,6iQ0OIxisPxhzEICEXtaWS,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,4qYHnP5AmKzXbJhciPV8si,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,35mKWzraWktvQcTGfuAHbV,2kKNeapwCHUUvMhmAOGce6,58PSYdY0GFg0LFb2PxYk4T,6726edIl6xRsfTuyrtwHRi,1KQEzCaNkk8ioBMbBeSYLw,4KGdZpFNRhTLQXVyvp1tbE,0Pig681y0YWdUClrmDgIBr,62JvDbmEtwIU3m4LhiG6Ah,5bC6ONDsL88snGN6QasjZH,0yJNGGGZ9eQj4vmvmeornZ,3WfjYZbJiVpdjPdeduMLFX,53ri7XzOM6KHb7SZnHxEMU,2uT3aswFopLTgJ4QtixmcJ,1lzaRptS63unC0qgT6hm7T,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,2fizsCfngbjfArn0y8MlfJ,7Efb2PkxBWN6b0fY8PiAzU,1qNL3vQCtN3bi3aB31iIjF,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,6JjDGQoxsbVBlzRjT6eFlg,3BnnWKoUg5BKIflQJ7dyAz,69AMgoqKt39WvDRFIX6WQw,38DAUnVJ0zqOdRuW7RG3sl,6lqxlJWNaykLli5cIXMf7l,5Oc0vLGWdEWeCqIU8zyELt&minimal=true: 502" Sep 07 12:35:25 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:25+03:00" level=debug msg="handling transfer player command from 9590444a990a04191885555d268d4802745969de" Sep 07 12:35:25 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:25+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Sep 07 12:35:25 minidsp-shd go-librespot[1098]: time="2024-09-07T12:35:25+03: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:3tipil3yEe5xpg0oFNHG10?salt=163481&autoplay=false&count=50&isVideo=false&prev_tracks=3tipil3yEe5xpg0oFNHG10,6nleD42Dimnc5fHnpA2T2R,7FXuCkl1kfxwS5icDjTwbM,047Ip9c6dijAJfuwloV3NF,7v6GgmyNIsNwLBPsp1pzdG,6gJnxMZphJt3sk2JJXTkoJ,2miIOgni3EEut8pvYcxeYn,4YDMaSvtnw0yxNSJcDylMd,1a2iF9XymafjRk56q7oCxo,3CBV8LLmD6YACpUR6P85Di,2FbLpNNlImB5mqCHgVIG1c,6G7MHIvSDR3ehXPkuDFAlM,0vlCOzte4bru0gK74lfUIJ,4fQMGlCawbTkH9yPPZ49kP,0nFZ2HQRnIxiwraCm2X6Uq,2dp14VWbIxOVNmaWKkVB1r,4xu0LW34dFEoHG1x65tQPx,46oxhVXWECsaPytsDW6GKb,2HUZVffVPXvqnrml0gXggp,4NQfrmGs9iQXVQI9IpRhjM,35mKWzraWktvQcTGfuAHbV,5etSCHyQ7oBksPksGxnLwL,2kaCrDduHQk88sOfzW4qzJ,4qYHnP5AmKzXbJhciPV8si,2kKNeapwCHUUvMhmAOGce6,6iQ0OIxisPxhzEICEXtaWS,6726edIl6xRsfTuyrtwHRi,1KQEzCaNkk8ioBMbBeSYLw,3WfjYZbJiVpdjPdeduMLFX,58PSYdY0GFg0LFb2PxYk4T,53ri7XzOM6KHb7SZnHxEMU,62JvDbmEtwIU3m4LhiG6Ah,0Pig681y0YWdUClrmDgIBr,31lG2URaPvdi7atAM8Tu4V,2fizsCfngbjfArn0y8MlfJ,7Efb2PkxBWN6b0fY8PiAzU,5bC6ONDsL88snGN6QasjZH,1lzaRptS63unC0qgT6hm7T,0yJNGGGZ9eQj4vmvmeornZ,3BnnWKoUg5BKIflQJ7dyAz,69AMgoqKt39WvDRFIX6WQw,2uT3aswFopLTgJ4QtixmcJ,4gRA0i5sxx3jAhHaVjPnUN,3KSchPNSklO5McIqRH3qYX,1qNL3vQCtN3bi3aB31iIjF,4Ra0IrmzAPudI6QMvNF8cg,6xat13LRDXtGdved0zKZGd,7j02rdE5RVtNcNMuLGY5SS,1KywyxekfDEp3Ym7YP7S25,6JjDGQoxsbVBlzRjT6eFlg&minimal=true: 502" Sep 07 12:35:30 minidsp-shd volumio[635]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 Sep 07 12:35:30 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioGetState Sep 07 12:35:30 minidsp-shd volumio[635]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 07 12:35:30 minidsp-shd volumio[635]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 07 12:35:30 minidsp-shd volumio[635]: info: Listing playlists Sep 07 12:35:34 minidsp-shd volumio[635]: info: CoreCommandRouter::volumioSeek Sep 07 12:35:34 minidsp-shd volumio[635]: info: CoreStateMachine::seek Sep 07 12:35:34 minidsp-shd volumio[635]: info: [1725701734996] inputs::seek to 0 Sep 07 12:35:35 minidsp-shd volumio[635]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 12:35:35 minidsp-shd volumio[635]: TypeError: this.sendSpopCommand is not a function Sep 07 12:35:35 minidsp-shd volumio[635]: at inputs.seek (/volumio/app/plugins/music_service/inputs/inputs_real:1:2486) Sep 07 12:35:35 minidsp-shd volumio[635]: at CoreStateMachine.seek (/volumio/app/statemachine.js:1084:20) Sep 07 12:35:35 minidsp-shd volumio[635]: at CoreCommandRouter.volumioSeek (/volumio/app/index.js:1421:28) Sep 07 12:35:35 minidsp-shd volumio[635]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:153:33) Sep 07 12:35:35 minidsp-shd volumio[635]: at Socket.emit (events.js:400:28) Sep 07 12:35:35 minidsp-shd volumio[635]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 07 12:35:35 minidsp-shd volumio[635]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 07 12:35:35 minidsp-shd volumio[635]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 12:35:35 minidsp-shd sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 12:34 Sep 07 12:35:35 minidsp-shd sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"