-- Logs begin at Thu 2024-09-05 04:06:13 UTC, end at Thu 2024-09-05 04:54:51 UTC. --
Sep 05 04:53:00 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:00 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:00 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:00 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:01 kukulkan volumio[11073]: /usr/lib/startconnect.sh: line 5: 11074 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:03 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:03 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:03 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:03 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:03 kukulkan volumio[11080]: /usr/lib/startconnect.sh: line 5: 11081 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:05 kukulkan volumio[831]: [SpotifyConnect] Stopping Vollibrespot daemon
Sep 05 04:53:05 kukulkan volumio[831]: [SpotifyConnect] Closing metadata listener
Sep 05 04:53:05 kukulkan volumio[831]: info: Disabling plugin volspotconnect2
Sep 05 04:53:05 kukulkan volumio[831]: info: Done.
Sep 05 04:53:05 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:05 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:05 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:05 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:05 kukulkan sudo[11088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect2.service
Sep 05 04:53:05 kukulkan sudo[11088]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 05 04:53:05 kukulkan volumio[11089]: /usr/lib/startconnect.sh: line 5: 11091 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:05 kukulkan volumio[831]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify
Sep 05 04:53:05 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 05 04:53:05 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:05 kukulkan systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 05 04:53:05 kukulkan sudo[11088]: pam_unix(sudo:session): session closed for user root
Sep 05 04:53:05 kukulkan volumio[831]: [SpotifyConnect] Closing metadata listener
Sep 05 04:53:06 kukulkan volumio[831]: info: Disabling plugin spop
Sep 05 04:53:06 kukulkan volumio[831]: info: Done.
Sep 05 04:53:06 kukulkan volumio[831]: [SpotifyConnect] Vollibrespot Daemon service stoped!
Sep 05 04:53:06 kukulkan sudo[11101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service
Sep 05 04:53:06 kukulkan sudo[11101]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 05 04:53:06 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:06 kukulkan systemd[1]: Stopped Volspotconnect2 Daemon.
Sep 05 04:53:06 kukulkan sudo[11101]: pam_unix(sudo:session): session closed for user root
Sep 05 04:53:06 kukulkan volumio[831]: [SpotifyConnect] Vollibrespot Daemon service stoped!
Sep 05 04:53:07 kukulkan volumio[831]: info: Enabling plugin spop
Sep 05 04:53:07 kukulkan volumio[831]: info: Loading plugin "spop"...
Sep 05 04:53:07 kukulkan volumio[831]: info: PLUGIN START: spop
Sep 05 04:53:07 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 05 04:53:07 kukulkan volumio[831]: [SpotifyConnect] Creating VLS config file
Sep 05 04:53:07 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:07 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:07 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:07 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:07 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:07 kukulkan volumio[831]: [SpotifyConnect] Starting metadata listener
Sep 05 04:53:07 kukulkan sudo[11112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
Sep 05 04:53:07 kukulkan sudo[11112]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 05 04:53:07 kukulkan sudo[11112]: pam_unix(sudo:session): session closed for user root
Sep 05 04:53:07 kukulkan sudo[11116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
Sep 05 04:53:08 kukulkan sudo[11116]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 05 04:53:08 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:08 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:08 kukulkan sudo[11116]: pam_unix(sudo:session): session closed for user root
Sep 05 04:53:08 kukulkan volumio[831]: info: Enabling plugin volspotconnect2
Sep 05 04:53:08 kukulkan volumio[831]: info: Loading plugin "volspotconnect2"...
Sep 05 04:53:08 kukulkan volumio[831]: info: PLUGIN START: volspotconnect2
Sep 05 04:53:08 kukulkan volumio[831]: [SpotifyConnect] Creating VLS config file
Sep 05 04:53:08 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:08 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 05 04:53:08 kukulkan volumio[831]: [SpotifyConnect] Starting metadata listener
Sep 05 04:53:08 kukulkan volumio[11128]: /usr/lib/startconnect.sh: line 5: 11131 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:08 kukulkan volumio[831]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030
Sep 05 04:53:08 kukulkan volumio[831]: at Object._errnoException (util.js:1022:11)
Sep 05 04:53:08 kukulkan volumio[831]: at _exceptionWithHostPort (util.js:1044:20)
Sep 05 04:53:08 kukulkan volumio[831]: at _handle.lookup (dgram.js:266:18)
Sep 05 04:53:08 kukulkan volumio[831]: at _combinedTickCallback (internal/process/next_tick.js:141:11)
Sep 05 04:53:08 kukulkan volumio[831]: at process._tickCallback (internal/process/next_tick.js:180:9)
Sep 05 04:53:08 kukulkan volumio[831]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data
Sep 05 04:53:08 kukulkan volumio[831]: at Socket.onListenError (dgram.js:362:22)
Sep 05 04:53:08 kukulkan volumio[831]: at Object.onceWrapper (events.js:315:30)
Sep 05 04:53:08 kukulkan volumio[831]: at emitOne (events.js:121:20)
Sep 05 04:53:08 kukulkan volumio[831]: at Socket.emit (events.js:211:7)
Sep 05 04:53:08 kukulkan volumio[831]: at _handle.lookup (dgram.js:267:14)
Sep 05 04:53:08 kukulkan volumio[831]: at _combinedTickCallback (internal/process/next_tick.js:141:11)
Sep 05 04:53:08 kukulkan volumio[831]: at process._tickCallback (internal/process/next_tick.js:180:9)
Sep 05 04:53:08 kukulkan volumio[831]: [SpotifyConnect] Vollibrespot Daemon service started!
Sep 05 04:53:08 kukulkan volumio[831]:  [Metrics]  SpotifyConnect:  0s 358.12ms 
Sep 05 04:53:08 kukulkan volumio[831]: info: Done.
Sep 05 04:53:08 kukulkan sudo[11135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service
Sep 05 04:53:08 kukulkan sudo[11135]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 05 04:53:08 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:08 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:08 kukulkan sudo[11135]: pam_unix(sudo:session): session closed for user root
Sep 05 04:53:09 kukulkan volumio[831]: [SpotifyConnect] Vollibrespot Daemon service started!
Sep 05 04:53:09 kukulkan volumio[831]:  [Metrics]  SpotifyConnect:  0s 821.99ms 
Sep 05 04:53:09 kukulkan volumio[831]: info: Done.
Sep 05 04:53:09 kukulkan volumio[11140]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07
Sep 05 04:53:09 kukulkan volumio[11140]: Reading Config from "volspotify.toml"
Sep 05 04:53:09 kukulkan volumio[11140]: [Vollibrespot] : Using Alsa backend with device: plughw:1
Sep 05 04:53:09 kukulkan volumio[11140]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" }
Sep 05 04:53:10 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:10 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:10 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:10 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:10 kukulkan volumio[11149]: /usr/lib/startconnect.sh: line 5: 11150 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:12 kukulkan volumio[831]: info: Checking Spotify Web API
Sep 05 04:53:12 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:12 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:12 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:12 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:13 kukulkan volumio[11156]: /usr/lib/startconnect.sh: line 5: 11157 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:15 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:15 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:15 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:15 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:15 kukulkan volumio[11163]: /usr/lib/startconnect.sh: line 5: 11164 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:16 kukulkan volumio[11140]: [Vollibrespot] : couldn't parse packet from V4(192.168.0.21:5353): type 47 is invalid
Sep 05 04:53:17 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:17 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:17 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:17 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:17 kukulkan volumio[11170]: /usr/lib/startconnect.sh: line 5: 11172 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:19 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:19 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:19 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:19 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:19 kukulkan volumio[11179]: /usr/lib/startconnect.sh: line 5: 11182 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:20 kukulkan volumio[11140]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Authenticated as "rodestrella" !
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Setting up new mixer: card:hw:1 mixer:Digital index:0
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-9999999)[dB]) -- max: 207 (MilliBel(0)[dB]) HW: true
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Using alsa sink
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Country: "MX"
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Metadata pipe established
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 }
Sep 05 04:53:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 0 Volumio: 0
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Event: SessionActive { became_active_at: 1725512001623 }
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : SessionActive!
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Fetching autoplay context uri
Sep 05 04:53:21 kukulkan volumio[831]: [SpotifyConnect]  A connect session has begun
Sep 05 04:53:21 kukulkan volumio[831]: [SpotifyConnect]  Device palyback is active!
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:0HF9cKTXSdkloCYTtYteoZ">
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Resolving uri "spotify:station:playlist:0HF9cKTXSdkloCYTtYteoZ"
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQD9Zc8J0InXP6ZoM7ZjKmQ8IwOSs-ZrBHtES-8tN8NluOgv7fxrmBrD3ATSRvO29W91NUn7xvbvJRKg3aXe0Fl7uOkLcTnDKTIx6zXHkuOgxRypXA-Hp1S_gLpNrh2okRHAbeKa-09RYzm-C91ZNMnVXhoDqMmKYBiCN1QNqUSc1NGOyihLBIk_hGG4REqT3jePZKsPraTvgLI", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
Sep 05 04:53:21 kukulkan volumio[831]: info: New Spotify Access Token Received
Sep 05 04:53:21 kukulkan volumio[831]: info: Initializing Spotify Web API
Sep 05 04:53:21 kukulkan volumio[831]: info: VolumeController::SetAlsaVolume0
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:21 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:21 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:21 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:21 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:21 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:21 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:21 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:21 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:22 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track }, track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:22 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:22 kukulkan volumio[11195]: /usr/lib/startconnect.sh: line 5: 11197 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track }, track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:0HF9cKTXSdkloCYTtYteoZ">
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:22 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:22 kukulkan volumio[831]: [SpotifyConnect]  The Shadow of Your Smile - Remastered 2020
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:22 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:22 kukulkan volumio[831]: [SpotifyConnect] Setting VLS device_id: 772eb441af65c991f60b98515beab6e236c65186
Sep 05 04:53:22 kukulkan volumio[831]: [SpotifyConnect]  That Old Feeling
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track }, track_id: SpotifyId { id: 249730680154151504050747399905766841476, audio_type: Track } }
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:22 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:22 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:22 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 249730680154151504050747399905766841476, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[831]: [SpotifyConnect]  Autumn in New York
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 249730680154151504050747399905766841476, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 249730680154151504050747399905766841476, audio_type: Track }, track_id: SpotifyId { id: 323764393880447457110900436647752656901, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:23 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:23 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 323764393880447457110900436647752656901, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 323764393880447457110900436647752656901, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 323764393880447457110900436647752656901, audio_type: Track }, track_id: SpotifyId { id: 300839375601646440911495179146854487407, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:23 kukulkan volumio[831]: [SpotifyConnect]  All Of Me
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:23 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 300839375601646440911495179146854487407, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 300839375601646440911495179146854487407, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 300839375601646440911495179146854487407, audio_type: Track }, track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:23 kukulkan volumio[831]: [SpotifyConnect]  Tico Tico
Sep 05 04:53:23 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:23 kukulkan volumio[831]: info: [1725512003608] ControllerSpotify::pushState
Sep 05 04:53:23 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:23 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:23 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:23 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track }, track_id: SpotifyId { id: 197533809475471111236199476008154241179, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:23 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:23 kukulkan volumio[831]: [SpotifyConnect]  La Vie En Rose
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 197533809475471111236199476008154241179, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 197533809475471111236199476008154241179, audio_type: Track } }
Sep 05 04:53:23 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 197533809475471111236199476008154241179, audio_type: Track }, track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[831]: [SpotifyConnect]  Armando's Rhumba
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:24 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track }, track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:24 kukulkan volumio[831]: info: [1725512004146] ControllerSpotify::pushState
Sep 05 04:53:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:24 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:24 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:24 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:24 kukulkan volumio[831]: [SpotifyConnect]  Stella By Starlight
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:24 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track }, track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:24 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:24 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:24 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:24 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:24 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Loading <'Round Midnight> with Spotify URI 
Sep 05 04:53:24 kukulkan volumio[831]: [SpotifyConnect]  The Last Time I Saw Paris
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:24 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:24 kukulkan volumio[11229]: /usr/lib/startconnect.sh: line 5: 11231 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:24 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track }, track_id: SpotifyId { id: 228415954468526594523012827699788763689, audio_type: Track } }
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:24 kukulkan volumio[831]: [SpotifyConnect]  Look For The Silver Lining
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:24 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 228415954468526594523012827699788763689, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 228415954468526594523012827699788763689, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 228415954468526594523012827699788763689, audio_type: Track }, track_id: SpotifyId { id: 252226128421049448526398321508714992401, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:25 kukulkan volumio[831]: [SpotifyConnect]  Family
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 252226128421049448526398321508714992401, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 252226128421049448526398321508714992401, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 252226128421049448526398321508714992401, audio_type: Track }, track_id: SpotifyId { id: 317383267601572540581949905242669924328, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:25 kukulkan volumio[831]: [SpotifyConnect]  'Round Midnight
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 317383267601572540581949905242669924328, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 317383267601572540581949905242669924328, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 317383267601572540581949905242669924328, audio_type: Track }, track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[831]: [SpotifyConnect]  Portrait in Black and White "Zingaro"
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:25 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:25 kukulkan volumio[831]: info: [1725512005631] ControllerSpotify::pushState
Sep 05 04:53:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:25 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track }, track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track } }
Sep 05 04:53:25 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:25 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:25 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:25 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:25 kukulkan volumio[831]: [SpotifyConnect]  Penthouse Serenade (When We're Alone)
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:26 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:26 kukulkan volumio[831]: info: [1725512006069] ControllerSpotify::pushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:26 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:26 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:26 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:26 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:26 kukulkan volumio[831]: info: [1725512006434] ControllerSpotify::pushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:26 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:26 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:26 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track } }
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track } }
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track }, track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:26 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:26 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:26 kukulkan volumio[831]: info: [1725512006723] ControllerSpotify::pushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:26 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:26 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:26 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:26 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:26 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:26 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:26 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:26 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:26 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:26 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track }, track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:27 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:27 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:27 kukulkan volumio[831]: [SpotifyConnect]  On Green Dolphin Street (feat. John Coltrane, Cannonball Adderley & Bill Evans)
Sep 05 04:53:27 kukulkan volumio[11251]: /usr/lib/startconnect.sh: line 5: 11253 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:27 kukulkan volumio[831]: [SpotifyConnect]  Someday My Prince Will Come
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:27 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:27 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:27 kukulkan volumio[831]: info: [1725512007499] ControllerSpotify::pushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track }, track_id: SpotifyId { id: 323409743026230099739702206170575389012, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:27 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:27 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:27 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:27 kukulkan volumio[831]: [SpotifyConnect]  Someday My Prince Will Come
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:27 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 323409743026230099739702206170575389012, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 323409743026230099739702206170575389012, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 323409743026230099739702206170575389012, audio_type: Track }, track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track } }
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:27 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:27 kukulkan volumio[831]: info: [1725512007834] ControllerSpotify::pushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:27 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:27 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:27 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:27 kukulkan volumio[831]: info: [1725512007919] ControllerSpotify::pushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:27 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:27 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:28 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track }, track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track }, track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:28 kukulkan volumio[831]: info: [1725512008122] ControllerSpotify::pushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:28 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:28 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:28 kukulkan volumio[831]: [SpotifyConnect]  Georgia On My Mind
Sep 05 04:53:28 kukulkan volumio[831]: [SpotifyConnect]  Prelude In E Minor
Sep 05 04:53:28 kukulkan volumio[831]: [SpotifyConnect]  Shu Shu
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track }, track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[831]: [SpotifyConnect]  Blue Bossa - Remastered
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 238048789831159280925388304718260664482, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track }, track_id: SpotifyId { id: 238048789831159280925388304718260664482, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:28 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:28 kukulkan volumio[831]: [SpotifyConnect]  Skål Brothers
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:28 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:28 kukulkan volumio[831]: info: [1725512008887] ControllerSpotify::pushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:28 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 238048789831159280925388304718260664482, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 238048789831159280925388304718260664482, audio_type: Track }, track_id: SpotifyId { id: 314018773085196774312367057723216869679, audio_type: Track } }
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:28 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:29 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:29 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:29 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:29 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 314018773085196774312367057723216869679, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 314018773085196774312367057723216869679, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 314018773085196774312367057723216869679, audio_type: Track }, track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:29 kukulkan volumio[831]: [SpotifyConnect]  The Night Has a Thousand Eyes
Sep 05 04:53:29 kukulkan volumio[831]: [SpotifyConnect]  Waltz For Debby
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:29 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:29 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:29 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:29 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:29 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:29 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track }, track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:29 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:29 kukulkan volumio[11271]: /usr/lib/startconnect.sh: line 5: 11273 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:29 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:29 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track }, track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:30 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:30 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:30 kukulkan volumio[831]: info: [1725512010089] ControllerSpotify::pushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:30 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:30 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:30 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:30 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track }, track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[831]: [SpotifyConnect]  Our Spanish Love Song
Sep 05 04:53:30 kukulkan volumio[831]: [SpotifyConnect]  Our Spanish Love Song
Sep 05 04:53:30 kukulkan volumio[831]: [SpotifyConnect]  Our Spanish Love Song
Sep 05 04:53:30 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:30 kukulkan volumio[831]: info: [1725512010446] ControllerSpotify::pushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:30 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:30 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:30 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:30 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:30 kukulkan volumio[831]: info: [1725512010588] ControllerSpotify::pushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:30 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:30 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:30 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:30 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track }, track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track } }
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:30 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:30 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track } }
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track } }
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track }, track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track } }
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:31 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:31 kukulkan volumio[831]: [SpotifyConnect]  There Will Never Be Another You
Sep 05 04:53:31 kukulkan volumio[831]: [SpotifyConnect]  Lullaby of Birdland
Sep 05 04:53:31 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:31 kukulkan volumio[831]: info: [1725512011223] ControllerSpotify::pushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:31 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:31 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:31 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:31 kukulkan volumio[831]: info: [1725512011553] ControllerSpotify::pushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:31 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:31 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:31 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:31 kukulkan volumio[831]: info: [1725512011632] ControllerSpotify::pushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:31 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:31 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:31 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:31 kukulkan volumio[831]: info: [1725512011845] ControllerSpotify::pushState
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:31 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:31 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:31 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:31 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:31 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:31 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:32 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:32 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:32 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:32 kukulkan volumio[831]: info: [1725512011993] ControllerSpotify::pushState
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:32 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:32 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track }, track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:32 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:32 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:32 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track }, track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:32 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track }, track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : error audio key 0 2
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : channel error: 2 0
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Unable to load 
Sep 05 04:53:32 kukulkan volumio[11140]: Skipping to next track
Sep 05 04:53:32 kukulkan volumio[11140]: thread '' panicked at 'cannot poll MapErr twice', /build/cache/registry/src/github.com-1ecc6299db9ec823/futures-0.1.29/src/future/map_err.rs:34:33
Sep 05 04:53:32 kukulkan volumio[11140]: stack backtrace:
Sep 05 04:53:32 kukulkan volumio[11288]: /usr/lib/startconnect.sh: line 5: 11290 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:32 kukulkan volumio[831]: [SpotifyConnect]  Embraceable You
Sep 05 04:53:32 kukulkan volumio[831]: [SpotifyConnect]  Taking A Chance On Love
Sep 05 04:53:32 kukulkan volumio[831]: [SpotifyConnect]  Adagia
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 1075081275912105226471898344411340997, audio_type: Track }, track_id: SpotifyId { id: 171660489788790294063768420589519726568, audio_type: Track } }
Sep 05 04:53:32 kukulkan volumio[831]: [SpotifyConnect]  Adagia
Sep 05 04:53:32 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:32 kukulkan volumio[831]: info: [1725512012951] ControllerSpotify::pushState
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:32 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:33 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:33 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:33 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 171660489788790294063768420589519726568, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 171660489788790294063768420589519726568, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 171660489788790294063768420589519726568, audio_type: Track }, track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track }, track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track }, track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track }, track_id: SpotifyId { id: 253893375966441578552367053969553511225, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[831]: [SpotifyConnect]  Golden Earrings
Sep 05 04:53:33 kukulkan volumio[831]: [SpotifyConnect]  Trastevere
Sep 05 04:53:33 kukulkan volumio[831]: [SpotifyConnect]  Softly As In A Morning Sunrise - Rudy Van Gelder Edition, 2008 Digital Remaster
Sep 05 04:53:33 kukulkan volumio[831]: [SpotifyConnect]  Softly As In A Morning Sunrise - Rudy Van Gelder Edition, 2008 Digital Remaster
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 253893375966441578552367053969553511225, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 253893375966441578552367053969553511225, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 253893375966441578552367053969553511225, audio_type: Track }, track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[831]: [SpotifyConnect]  Estrellita - Remastered 1992
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track }, track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:53:33 kukulkan volumio[831]: [SpotifyConnect]  I Got It Bad And That Ain't Good
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track }, track_id: SpotifyId { id: 154196938538184794120944512502517915862, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 154196938538184794120944512502517915862, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 154196938538184794120944512502517915862, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 154196938538184794120944512502517915862, audio_type: Track }, track_id: SpotifyId { id: 88709702153206881617907973158557769626, audio_type: Track } }
Sep 05 04:53:34 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:34 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:34 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:34 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:34 kukulkan volumio[831]: [SpotifyConnect]  I Got It Bad And That Ain't Good
Sep 05 04:53:34 kukulkan volumio[831]: [SpotifyConnect]  And Then She Stopped
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 88709702153206881617907973158557769626, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 88709702153206881617907973158557769626, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 88709702153206881617907973158557769626, audio_type: Track }, track_id: SpotifyId { id: 338260187212207123866935273641046387355, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11297]: /usr/lib/startconnect.sh: line 5: 11298 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 338260187212207123866935273641046387355, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 338260187212207123866935273641046387355, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 338260187212207123866935273641046387355, audio_type: Track }, track_id: SpotifyId { id: 298446656190309770101317346859607004509, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: 0:   0xa8d60c - backtrace::backtrace::libunwind::trace::hf9eeeb1d52578408
Sep 05 04:53:34 kukulkan volumio[11140]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86
Sep 05 04:53:34 kukulkan volumio[11140]: 1:   0xa8d60c - backtrace::backtrace::trace_unsynchronized::h9d71662eece903ab
Sep 05 04:53:34 kukulkan volumio[11140]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66
Sep 05 04:53:34 kukulkan volumio[11140]: 2:   0xa8d60c - std::sys_common::backtrace::_print_fmt::h54695504f8601726
Sep 05 04:53:34 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:78
Sep 05 04:53:34 kukulkan volumio[11140]: 3:   0xa8d60c - ::fmt::h512ecf96415d8ce2
Sep 05 04:53:34 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:59
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 298446656190309770101317346859607004509, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 298446656190309770101317346859607004509, audio_type: Track } }
Sep 05 04:53:34 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 298446656190309770101317346859607004509, audio_type: Track }, track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[831]: [SpotifyConnect]  Inflight
Sep 05 04:53:35 kukulkan volumio[831]: [SpotifyConnect]  Give Me The Simple Life
Sep 05 04:53:35 kukulkan volumio[831]: [SpotifyConnect]  Dat Dere (Theme)
Sep 05 04:53:35 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:35 kukulkan volumio[831]: info: [1725512015157] ControllerSpotify::pushState
Sep 05 04:53:35 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track }, track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:35 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:35 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:35 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:35 kukulkan volumio[831]: info: CorePlayQueue::getTrack 0
Sep 05 04:53:35 kukulkan volumio[831]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop
Sep 05 04:53:35 kukulkan volumio[11140]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[11140]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[11140]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track }, track_id: SpotifyId { id: 314584459439690471348893806847612560455, audio_type: Track } }
Sep 05 04:53:35 kukulkan volumio[11140]: 4:   0xab2218 - core::fmt::write::h1060f85d3ee53644
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libcore/fmt/mod.rs:1076
Sep 05 04:53:35 kukulkan volumio[11140]: 5:   0xa86ee0 - std::io::Write::write_fmt::h34e2a8730bb7a876
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/io/mod.rs:1537
Sep 05 04:53:35 kukulkan volumio[11140]: 6:   0xa8fc88 - std::sys_common::backtrace::_print::h2be13b0c8dff2c9b
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:62
Sep 05 04:53:35 kukulkan volumio[11140]: 7:   0xa8fc88 - std::sys_common::backtrace::print::hd763058df6eec412
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:49
Sep 05 04:53:35 kukulkan volumio[11140]: 8:   0xa8fc88 - std::panicking::default_hook::{{closure}}::h48b9472d8fc0e6bf
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/panicking.rs:198
Sep 05 04:53:35 kukulkan volumio[11140]: 9:   0xa8f93c - std::panicking::default_hook::h0fc1c5596bab0152
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/panicking.rs:217
Sep 05 04:53:35 kukulkan volumio[11140]: 10:   0xa90368 - std::panicking::rust_panic_with_hook::hae7ba177154e198d
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/panicking.rs:526
Sep 05 04:53:35 kukulkan volumio[11140]: 11:   0xa8ff68 - rust_begin_unwind
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/panicking.rs:437
Sep 05 04:53:35 kukulkan volumio[11140]: 12:   0xaaf8f0 - core::panicking::panic_fmt::hf855e45b7cc21a3f
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libcore/panicking.rs:85
Sep 05 04:53:35 kukulkan volumio[11140]: 13:   0xaaf6cc - core::option::expect_failed::h2665e29771a01623
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libcore/option.rs:1261
Sep 05 04:53:35 kukulkan volumio[11140]: 14:   0x66ca70 -  as futures::future::Future>::poll::h7c397e316a58adfe
Sep 05 04:53:35 kukulkan volumio[11140]: 15:   0x653478 - ::poll::h7b25082eb8394281
Sep 05 04:53:35 kukulkan volumio[11140]: 16:   0x5609e8 - futures::task_impl::std::set::hacd48c21b0872381
Sep 05 04:53:35 kukulkan volumio[11140]: 17:   0x554bb4 - futures::task_impl::Spawn::poll_future_notify::hc07ecda8edefd10c
Sep 05 04:53:35 kukulkan volumio[11140]: 18:   0x56f020 - futures::future::Future::wait::h563b9af46ef24989
Sep 05 04:53:35 kukulkan volumio[11140]: 19:   0x556364 - std::sys_common::backtrace::__rust_begin_short_backtrace::hacf896b38c256c97
Sep 05 04:53:35 kukulkan volumio[11140]: 20:   0x5763a4 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h4a3bb3d99b7ce694
Sep 05 04:53:35 kukulkan volumio[11140]: 21:   0xa937c8 -  as core::ops::function::FnOnce>::call_once::h777baa267da71f26
Sep 05 04:53:35 kukulkan volumio[11140]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081
Sep 05 04:53:35 kukulkan volumio[11140]: 22:   0xa937c8 -  as core::ops::function::FnOnce>::call_once::hdf61d253c461ed51
Sep 05 04:53:35 kukulkan volumio[11140]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081
Sep 05 04:53:35 kukulkan volumio[11140]: 23:   0xa937c8 - std::sys::unix::thread::Thread::new::thread_start::h2de224da885c7bf2
Sep 05 04:53:35 kukulkan volumio[11140]: at src/libstd/sys/unix/thread.rs:87
Sep 05 04:53:35 kukulkan volumio[11140]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:232:61
Sep 05 04:53:35 kukulkan volumio[11140]: stack backtrace:
Sep 05 04:53:35 kukulkan volumio[11140]: 0:   0xa8d60c - backtrace::backtrace::libunwind::trace::hf9eeeb1d52578408
Sep 05 04:53:35 kukulkan volumio[11140]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86
Sep 05 04:53:35 kukulkan volumio[11140]: 1:   0xa8d60c - backtrace::backtrace::trace_unsynchronized::h9d71662eece903ab
Sep 05 04:53:35 kukulkan systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a
Sep 05 04:53:35 kukulkan systemd[1]: Unit volspotconnect2.service entered failed state.
Sep 05 04:53:36 kukulkan volumio[11140]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66
Sep 05 04:53:36 kukulkan volumio[11140]: 2:   0xa8d60c - std::sys_common::backtrace::_print_fmt::h54695504f8601726
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:78
Sep 05 04:53:36 kukulkan volumio[11140]: 3:   0xa8d60c - ::fmt::h512ecf96415d8ce2
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:59
Sep 05 04:53:36 kukulkan volumio[11140]: 4:   0xab2218 - core::fmt::write::h1060f85d3ee53644
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libcore/fmt/mod.rs:1076
Sep 05 04:53:36 kukulkan volumio[11140]: 5:   0xa86ee0 - std::io::Write::write_fmt::h34e2a8730bb7a876
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/io/mod.rs:1537
Sep 05 04:53:36 kukulkan volumio[11140]: 6:   0xa8fc88 - std::sys_common::backtrace::_print::h2be13b0c8dff2c9b
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:62
Sep 05 04:53:36 kukulkan volumio[11140]: 7:   0xa8fc88 - std::sys_common::backtrace::print::hd763058df6eec412
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/sys_common/backtrace.rs:49
Sep 05 04:53:36 kukulkan volumio[11140]: 8:   0xa8fc88 - std::panicking::default_hook::{{closure}}::h48b9472d8fc0e6bf
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panicking.rs:198
Sep 05 04:53:36 kukulkan volumio[11140]: 9:   0xa8f93c - std::panicking::default_hook::h0fc1c5596bab0152
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panicking.rs:217
Sep 05 04:53:36 kukulkan volumio[11140]: 10:   0xa90368 - std::panicking::rust_panic_with_hook::hae7ba177154e198d
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panicking.rs:526
Sep 05 04:53:36 kukulkan volumio[11140]: 11:   0xa8ff68 - rust_begin_unwind
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panicking.rs:437
Sep 05 04:53:36 kukulkan volumio[11140]: 12:   0xaaf8f0 - core::panicking::panic_fmt::hf855e45b7cc21a3f
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libcore/panicking.rs:85
Sep 05 04:53:36 kukulkan volumio[11140]: 13:   0xaaf748 - core::option::expect_none_failed::h4b0b008108079597
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libcore/option.rs:1269
Sep 05 04:53:36 kukulkan volumio[11140]: 14:   0x65133c - librespot_playback::player::Player::emit_volume_set_event::ha84858e5a2ee940e
Sep 05 04:53:36 kukulkan volumio[11140]: 15:   0x62a8c0 - librespot_connect::spirc::SpircTask::set_volume::hbda6f451d0a9810c
Sep 05 04:53:36 kukulkan volumio[11140]: 16:   0x625c40 - ::poll::h6201a2b9faae04af
Sep 05 04:53:36 kukulkan volumio[11140]: 17:   0x59a90c - ::poll::h2c608b7bbd5483b9
Sep 05 04:53:36 kukulkan volumio[11140]: 18:   0x561028 - futures::task_impl::std::set::he0ba9d7a117059e7
Sep 05 04:53:36 kukulkan volumio[11140]: 19:   0x575454 - std::thread::local::LocalKey::with::h69e67cf00223a2ba
Sep 05 04:53:36 kukulkan volumio[11140]: 20:   0x550718 - tokio_current_thread::Entered::block_on::h2e4ff2c13a247d3c
Sep 05 04:53:36 kukulkan volumio[11140]: 21:   0x575298 - std::thread::local::LocalKey::with::h663fcb2d9f4b6c18
Sep 05 04:53:36 kukulkan volumio[11140]: 22:   0x5825d4 - tokio_reactor::with_default::h5d8f90b2b875599a
Sep 05 04:53:36 kukulkan volumio[11140]: 23:   0x554940 - tokio::runtime::current_thread::runtime::Runtime::block_on::h9505b8c1bab44fab
Sep 05 04:53:36 kukulkan volumio[11140]: 24:   0x59bf98 - vollibrespot::main::h3614a8e14a6045df
Sep 05 04:53:36 kukulkan volumio[11140]: 25:   0x5832e0 - std::rt::lang_start::{{closure}}::h0a25e89da98ec7db
Sep 05 04:53:36 kukulkan volumio[11140]: 26:   0xa9072c - std::rt::lang_start_internal::{{closure}}::h97efd249046dc8e8
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/rt.rs:52
Sep 05 04:53:36 kukulkan volumio[11140]: 27:   0xa9072c - std::panicking::try::do_call::h3d07de510dcbc7c8
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panicking.rs:348
Sep 05 04:53:36 kukulkan volumio[11140]: 28:   0xa9072c - std::panicking::try::hc25ddf66eb022203
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panicking.rs:325
Sep 05 04:53:36 kukulkan volumio[11140]: 29:   0xa9072c - std::panic::catch_unwind::h36b06962268095b4
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/panic.rs:394
Sep 05 04:53:36 kukulkan volumio[11140]: 30:   0xa9072c - std::rt::lang_start_internal::hdbd4407cfdcc64c8
Sep 05 04:53:36 kukulkan volumio[11140]: at src/libstd/rt.rs:51
Sep 05 04:53:36 kukulkan volumio[11140]: 31:   0x59ca54 - main
Sep 05 04:53:36 kukulkan volumio[11140]: 32: 0xb6c50294 - __libc_start_main
Sep 05 04:53:36 kukulkan volumio[11140]: [Vollibrespot] : Player thread panicked!
Sep 05 04:53:36 kukulkan volumio[831]: [SpotifyConnect]  You Look Good To Me
Sep 05 04:53:36 kukulkan volumio[831]: [SpotifyConnect]  You Look Good To Me
Sep 05 04:53:36 kukulkan volumio[831]: [SpotifyConnect]  September Song
Sep 05 04:53:36 kukulkan volumio[831]: [SpotifyConnect]  Sink released
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016153] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016350] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016452] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016686] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016753] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016823] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:36 kukulkan volumio[831]: info: [1725512016889] ControllerSpotify::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:36 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:36 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:36 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:36 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:36 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017019] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017124] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan volumio[11303]: /usr/lib/startconnect.sh: line 5: 11304 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017247] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017323] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017631] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017700] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:37 kukulkan volumio[831]: info: [1725512017765] ControllerSpotify::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:37 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:37 kukulkan systemd[1]: volspotconnect2.service holdoff time over, scheduling restart.
Sep 05 04:53:37 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:37 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:37 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:38 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:38 kukulkan volumio[831]: info: [1725512018007] ControllerSpotify::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:38 kukulkan volumio[11307]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07
Sep 05 04:53:38 kukulkan volumio[11307]: Reading Config from "volspotify.toml"
Sep 05 04:53:38 kukulkan volumio[11307]: [Vollibrespot] : Using Alsa backend with device: plughw:1
Sep 05 04:53:38 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:38 kukulkan volumio[831]: info: [1725512018565] ControllerSpotify::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:38 kukulkan volumio[11307]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" }
Sep 05 04:53:38 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:38 kukulkan volumio[831]: info: [1725512018665] ControllerSpotify::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:38 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:38 kukulkan volumio[831]: info: [1725512018743] ControllerSpotify::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:38 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:38 kukulkan volumio[831]: info: [1725512018840] ControllerSpotify::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:38 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019020] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019085] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019157] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019311] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019373] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:39 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:39 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:39 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Not Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019479] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[11311]: /usr/lib/startconnect.sh: line 5: 11313 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019781] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:39 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:53:39 kukulkan volumio[831]: info: [1725512019863] ControllerSpotify::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:53:39 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:53:41 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:41 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:41 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:41 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:42 kukulkan volumio[11320]: /usr/lib/startconnect.sh: line 5: 11321 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:44 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:44 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:44 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:44 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:44 kukulkan volumio[11327]: /usr/lib/startconnect.sh: line 5: 11328 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:45 kukulkan volumio[11307]: [Vollibrespot] : couldn't parse packet from V4(192.168.0.21:5353): type 47 is invalid
Sep 05 04:53:46 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:46 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:46 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:46 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:46 kukulkan volumio[11334]: /usr/lib/startconnect.sh: line 5: 11335 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:48 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:48 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:48 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:48 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:48 kukulkan volumio[11341]: /usr/lib/startconnect.sh: line 5: 11342 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:50 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:50 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:50 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:50 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:51 kukulkan volumio[11348]: /usr/lib/startconnect.sh: line 5: 11349 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:53 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:53 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:53 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:53 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:53 kukulkan volumio[11355]: /usr/lib/startconnect.sh: line 5: 11356 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:55 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:55 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:55 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:55 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:55 kukulkan volumio[11363]: /usr/lib/startconnect.sh: line 5: 11365 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:57 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:57 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:57 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:57 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:53:57 kukulkan volumio[11371]: /usr/lib/startconnect.sh: line 5: 11372 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:53:59 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:53:59 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:53:59 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:53:59 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:00 kukulkan volumio[11379]: /usr/lib/startconnect.sh: line 5: 11380 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:02 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:02 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:02 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:02 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:02 kukulkan volumio[11386]: /usr/lib/startconnect.sh: line 5: 11387 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:04 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:04 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:04 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:04 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:04 kukulkan volumio[11393]: /usr/lib/startconnect.sh: line 5: 11394 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:06 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:06 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:06 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:06 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:06 kukulkan volumio[11400]: /usr/lib/startconnect.sh: line 5: 11401 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:08 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:08 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:08 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:08 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:09 kukulkan volumio[11407]: /usr/lib/startconnect.sh: line 5: 11408 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Connecting to AP "ap2-gew4.spotify.com:443"
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Authenticated as "rodestrella" !
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Setting up new mixer: card:hw:1 mixer:Digital index:0
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-9999999)[dB]) -- max: 207 (MilliBel(0)[dB]) HW: true
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Using alsa sink
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Country: "MX"
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Metadata pipe established
Sep 05 04:54:10 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 }
Sep 05 04:54:10 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 0 Volumio: 0
Sep 05 04:54:11 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:11 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:11 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:11 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:11 kukulkan volumio[11420]: /usr/lib/startconnect.sh: line 5: 11422 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: SessionActive { became_active_at: 1725512051342 }
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : SessionActive!
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Fetching autoplay context uri
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 112589513373425325076135274981755035242, audio_type: Track } }
Sep 05 04:54:11 kukulkan volumio[831]: [SpotifyConnect]  A connect session has begun
Sep 05 04:54:11 kukulkan volumio[831]: [SpotifyConnect]  Device palyback is active!
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 112589513373425325076135274981755035242, audio_type: Track } }
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:0HF9cKTXSdkloCYTtYteoZ">
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Resolving uri "spotify:station:playlist:0HF9cKTXSdkloCYTtYteoZ"
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQALOlGQzUwuIXQCnsmj_f2TC7vP78IwkrZc3ZjMtXOVR_eRabZTNggJB9gnCfiF8Pjq7rtNxF9t-84rj9hLsqtT_k6FJ_tnBDOX380SCbxUPwmBs6LqDZ2Fu-6-d7StnPidVofvUQmVfNDXhLEN22_oGejqvduepTzWsZBv0iNHser_n23vtEd1GomyLpkaDjRiMPNHl8zxppk", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } }
Sep 05 04:54:11 kukulkan volumio[831]: info: New Spotify Access Token Received
Sep 05 04:54:11 kukulkan volumio[831]: info: Initializing Spotify Web API
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:11 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 112589513373425325076135274981755035242, audio_type: Track }, track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track } }
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:11 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:11 kukulkan volumio[831]: [SpotifyConnect] Setting VLS device_id: 772eb441af65c991f60b98515beab6e236c65186
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:11 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:11 kukulkan volumio[831]: [SpotifyConnect]  La Vie En Rose
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track } }
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track } }
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 238338824256310449468579733969833045070, audio_type: Track }, track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track } }
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:11 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:0HF9cKTXSdkloCYTtYteoZ">
Sep 05 04:54:11 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:12 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:12 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 275956589853067853164383276617105204579, audio_type: Track }, track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:12 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[831]: [SpotifyConnect]  On Green Dolphin Street (feat. John Coltrane, Cannonball Adderley & Bill Evans)
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:12 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:12 kukulkan volumio[831]: [SpotifyConnect]  Taking A Chance On Love
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 263242478143512029872146736486852771337, audio_type: Track }, track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:12 kukulkan volumio[831]: info: [1725512052706] ControllerSpotify::pushState
Sep 05 04:54:12 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:12 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:12 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:12 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:12 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[831]: [SpotifyConnect]  I Got It Bad And That Ain't Good
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track }, track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:12 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:12 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan volumio[831]: [SpotifyConnect]  I Got It Bad And That Ain't Good
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 191842300462475258375827024087590003970, audio_type: Track }, track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 52756916349703133650609881505407884488, audio_type: Track }, track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track } }
Sep 05 04:54:13 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:13 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:13 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:13 kukulkan volumio[831]: [SpotifyConnect]  Embraceable You
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 96908379766488336979492315191476108168, audio_type: Track }, track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:13 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track } }
Sep 05 04:54:13 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 54328262158808005342421775299610464993, audio_type: Track }, track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[831]: [SpotifyConnect]  Stella By Starlight
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[831]: [SpotifyConnect]  Trastevere
Sep 05 04:54:14 kukulkan volumio[11441]: /usr/lib/startconnect.sh: line 5: 11443 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 222220285129479433174766190232046564175, audio_type: Track }, track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[831]: [SpotifyConnect]  Skål Brothers
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:14 kukulkan volumio[831]: info: [1725512054294] ControllerSpotify::pushState
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 279569248101630713803167175567831738348, audio_type: Track }, track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[831]: [SpotifyConnect]  There Will Never Be Another You
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:14 kukulkan volumio[831]: info: [1725512054705] ControllerSpotify::pushState
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:14 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track }, track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:14 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:14 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:15 kukulkan volumio[831]: info: [1725512055005] ControllerSpotify::pushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:15 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:15 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : couldn't parse packet from V4(192.168.0.21:5353): type 47 is invalid
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track } }
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 234214639946667542397289094544244075949, audio_type: Track }, track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:15 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan volumio[831]: [SpotifyConnect]  Someday My Prince Will Come
Sep 05 04:54:15 kukulkan volumio[831]: [SpotifyConnect]  Someday My Prince Will Come
Sep 05 04:54:15 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:15 kukulkan volumio[831]: info: [1725512055459] ControllerSpotify::pushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:15 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:15 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track }, track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:15 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:15 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:15 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:15 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:15 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:15 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:15 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track }, track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:16 kukulkan volumio[831]: [SpotifyConnect]  Our Spanish Love Song
Sep 05 04:54:16 kukulkan volumio[831]: [SpotifyConnect]  Our Spanish Love Song
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:16 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:16 kukulkan volumio[11465]: /usr/lib/startconnect.sh: line 5: 11466 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:16 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 273249291143653943014576235072449579980, audio_type: Track }, track_id: SpotifyId { id: 189595337810151419709948510368423811276, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:16 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:16 kukulkan volumio[831]: info: [1725512056402] ControllerSpotify::pushState
Sep 05 04:54:16 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:16 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:16 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:16 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:16 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:16 kukulkan volumio[831]: [SpotifyConnect]  Our Spanish Love Song
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:16 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Loading  with Spotify URI 
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 189595337810151419709948510368423811276, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 189595337810151419709948510368423811276, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 189595337810151419709948510368423811276, audio_type: Track }, track_id: SpotifyId { id: 152373527817334149661729771740916368967, audio_type: Track } }
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : error audio key 0 2
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : channel error: 2 0
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load encrypted file.
Sep 05 04:54:16 kukulkan volumio[11307]: [Vollibrespot] : Unable to load 
Sep 05 04:54:16 kukulkan volumio[11307]: Skipping to next track
Sep 05 04:54:16 kukulkan volumio[11307]: thread '' panicked at 'cannot poll MapErr twice', /build/cache/registry/src/github.com-1ecc6299db9ec823/futures-0.1.29/src/future/map_err.rs:34:33
Sep 05 04:54:16 kukulkan volumio[11307]: stack backtrace:
Sep 05 04:54:16 kukulkan volumio[831]: [SpotifyConnect]  My Funny Valentine
Sep 05 04:54:17 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:17 kukulkan volumio[831]: info: [1725512057464] ControllerSpotify::pushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:17 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 152373527817334149661729771740916368967, audio_type: Track } }
Sep 05 04:54:17 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 152373527817334149661729771740916368967, audio_type: Track } }
Sep 05 04:54:17 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 152373527817334149661729771740916368967, audio_type: Track }, track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track } }
Sep 05 04:54:17 kukulkan volumio[831]: [SpotifyConnect]  Bye Bye Blackbird (feat. John Coltrane, Red Garland, Paul Chambers & Philly Joe Jones)
Sep 05 04:54:17 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:17 kukulkan volumio[831]: info: [1725512057915] ControllerSpotify::pushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:17 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:17 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track } }
Sep 05 04:54:17 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 280664357519840077751760907633890449318, audio_type: Track }, track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 70627931599957645923121674033799245906, audio_type: Track }, track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[831]: [SpotifyConnect]  Blue Bossa - Remastered
Sep 05 04:54:18 kukulkan volumio[831]: [SpotifyConnect]  The Last Time I Saw Paris
Sep 05 04:54:18 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:18 kukulkan volumio[831]: info: [1725512058313] ControllerSpotify::pushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:18 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:18 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:18 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 80692882300734906922301907980803172135, audio_type: Track }, track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track } }
Sep 05 04:54:18 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:18 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:18 kukulkan volumio[831]: info: [1725512058475] ControllerSpotify::pushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:18 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:18 kukulkan volumio[11476]: /usr/lib/startconnect.sh: line 5: 11477 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:18 kukulkan volumio[831]: [SpotifyConnect]  Penthouse Serenade (When We're Alone)
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 96575036836316249656457540268530356964, audio_type: Track }, track_id: SpotifyId { id: 334120380086625429957502628414655251025, audio_type: Track } }
Sep 05 04:54:18 kukulkan volumio[831]: [SpotifyConnect]  That Old Feeling
Sep 05 04:54:19 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:19 kukulkan volumio[831]: info: [1725512059021] ControllerSpotify::pushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 334120380086625429957502628414655251025, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 334120380086625429957502628414655251025, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 334120380086625429957502628414655251025, audio_type: Track }, track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:19 kukulkan volumio[831]: info: [1725512059139] ControllerSpotify::pushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:19 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 34064590077254629524369269830210153962, audio_type: Track }, track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: 0:   0xa5360c - backtrace::backtrace::libunwind::trace::hf9eeeb1d52578408
Sep 05 04:54:19 kukulkan volumio[11307]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86
Sep 05 04:54:19 kukulkan volumio[11307]: 1:   0xa5360c - backtrace::backtrace::trace_unsynchronized::h9d71662eece903ab
Sep 05 04:54:19 kukulkan volumio[11307]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66
Sep 05 04:54:19 kukulkan volumio[11307]: 2:   0xa5360c - std::sys_common::backtrace::_print_fmt::h54695504f8601726
Sep 05 04:54:19 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:78
Sep 05 04:54:19 kukulkan volumio[11307]: 3:   0xa5360c - ::fmt::h512ecf96415d8ce2
Sep 05 04:54:19 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:59
Sep 05 04:54:19 kukulkan volumio[831]: [SpotifyConnect]  Carola
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track }, track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[831]: [SpotifyConnect]  Lullaby of Birdland
Sep 05 04:54:19 kukulkan volumio[831]: [SpotifyConnect]  You Look Good To Me
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 122329437422152357887766919865308817327, audio_type: Track }, track_id: SpotifyId { id: 47711556109007874729016791528484469333, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 47711556109007874729016791528484469333, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 47711556109007874729016791528484469333, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 47711556109007874729016791528484469333, audio_type: Track }, track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:54:19 kukulkan volumio[11307]: 4:   0xa78218 - core::fmt::write::h1060f85d3ee53644
Sep 05 04:54:19 kukulkan volumio[11307]: at src/libcore/fmt/mod.rs:1076
Sep 05 04:54:19 kukulkan volumio[11307]: 5:   0xa4cee0 - std::io::Write::write_fmt::h34e2a8730bb7a876
Sep 05 04:54:19 kukulkan volumio[11307]: at src/libstd/io/mod.rs:1537
Sep 05 04:54:19 kukulkan volumio[11307]: 6:   0xa55c88 - std::sys_common::backtrace::_print::h2be13b0c8dff2c9b
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:62
Sep 05 04:54:20 kukulkan volumio[11307]: 7:   0xa55c88 - std::sys_common::backtrace::print::hd763058df6eec412
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:49
Sep 05 04:54:20 kukulkan volumio[11307]: 8:   0xa55c88 - std::panicking::default_hook::{{closure}}::h48b9472d8fc0e6bf
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/panicking.rs:198
Sep 05 04:54:20 kukulkan volumio[11307]: 9:   0xa5593c - std::panicking::default_hook::h0fc1c5596bab0152
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/panicking.rs:217
Sep 05 04:54:20 kukulkan volumio[11307]: 10:   0xa56368 - std::panicking::rust_panic_with_hook::hae7ba177154e198d
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/panicking.rs:526
Sep 05 04:54:20 kukulkan volumio[11307]: 11:   0xa55f68 - rust_begin_unwind
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/panicking.rs:437
Sep 05 04:54:20 kukulkan volumio[11307]: 12:   0xa758f0 - core::panicking::panic_fmt::hf855e45b7cc21a3f
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libcore/panicking.rs:85
Sep 05 04:54:20 kukulkan volumio[11307]: 13:   0xa756cc - core::option::expect_failed::h2665e29771a01623
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libcore/option.rs:1261
Sep 05 04:54:20 kukulkan volumio[11307]: 14:   0x632a70 -  as futures::future::Future>::poll::h7c397e316a58adfe
Sep 05 04:54:20 kukulkan volumio[11307]: 15:   0x619478 - ::poll::h7b25082eb8394281
Sep 05 04:54:20 kukulkan volumio[11307]: 16:   0x5269e8 - futures::task_impl::std::set::hacd48c21b0872381
Sep 05 04:54:20 kukulkan volumio[11307]: 17:   0x51abb4 - futures::task_impl::Spawn::poll_future_notify::hc07ecda8edefd10c
Sep 05 04:54:20 kukulkan volumio[11307]: 18:   0x535020 - futures::future::Future::wait::h563b9af46ef24989
Sep 05 04:54:20 kukulkan volumio[11307]: 19:   0x51c364 - std::sys_common::backtrace::__rust_begin_short_backtrace::hacf896b38c256c97
Sep 05 04:54:20 kukulkan volumio[11307]: 20:   0x53c3a4 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h4a3bb3d99b7ce694
Sep 05 04:54:20 kukulkan volumio[11307]: 21:   0xa597c8 -  as core::ops::function::FnOnce>::call_once::h777baa267da71f26
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track }, track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081
Sep 05 04:54:20 kukulkan volumio[11307]: 22:   0xa597c8 -  as core::ops::function::FnOnce>::call_once::hdf61d253c461ed51
Sep 05 04:54:20 kukulkan volumio[11307]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081
Sep 05 04:54:20 kukulkan volumio[11307]: 23:   0xa597c8 - std::sys::unix::thread::Thread::new::thread_start::h2de224da885c7bf2
Sep 05 04:54:20 kukulkan volumio[11307]: at src/libstd/sys/unix/thread.rs:87
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  You Look Good To Me
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  Don't Let Me Be Lonely Tonight
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  Softly As In A Morning Sunrise - Rudy Van Gelder Edition, 2008 Digital Remaster
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  Softly As In A Morning Sunrise - Rudy Van Gelder Edition, 2008 Digital Remaster
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  Prelude In E Minor
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 293048233259906828029272696608423358728, audio_type: Track }, track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 49181264879828565003960728596662513451, audio_type: Track }, track_id: SpotifyId { id: 250679762230851719131059147430216411204, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 250679762230851719131059147430216411204, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 250679762230851719131059147430216411204, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 250679762230851719131059147430216411204, audio_type: Track }, track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 320137554615216686759250144785819968999, audio_type: Track }, track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  You're Mine, You!
Sep 05 04:54:20 kukulkan volumio[831]: [SpotifyConnect]  Shu Shu
Sep 05 04:54:20 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:20 kukulkan volumio[831]: info: [1725512060718] ControllerSpotify::pushState
Sep 05 04:54:20 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:20 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:20 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 1024 }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 267183179445057639011935319034510207811, audio_type: Track }, track_id: SpotifyId { id: 153232190959875477972572413155356606076, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 153232190959875477972572413155356606076, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 153232190959875477972572413155356606076, audio_type: Track } }
Sep 05 04:54:20 kukulkan volumio[11307]: [Vollibrespot] : Event: TrackChanged { old_track_id: SpotifyId { id: 153232190959875477972572413155356606076, audio_type: Track }, track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:54:20 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:20 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:20 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:20 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 2048 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Next { track_id: SpotifyId { id: 11233856696519894459526537618772648250, audio_type: Track } }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 5120 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 9216 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 13312 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 17408 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 25600 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 28672 }
Sep 05 04:54:21 kukulkan volumio[11307]: [Vollibrespot] : Event: Volume { volume_to_mixer: 29696 }
Sep 05 04:54:21 kukulkan volumio[11483]: /usr/lib/startconnect.sh: line 5: 11484 Illegal instruction     .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect]  Look For The Silver Lining
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 1.5625238422217136 Volumio: 2
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect]  Laird Baird
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect]  The Shadow of Your Smile - Remastered 2020
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 3.125047684443427 Volumio: 3
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 7.812619211108568 Volumio: 8
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 14.062714579995422 Volumio: 14
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 20.312809948882276 Volumio: 20
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 26.562905317769133 Volumio: 27
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 39.06309605554284 Volumio: 39
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 43.75066758220798 Volumio: 44
Sep 05 04:54:21 kukulkan volumio[831]: [SpotifyConnect] Volume Spotify: 45.3131914244297 Volumio: 45
Sep 05 04:54:21 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:21 kukulkan volumio[831]: info: [1725512061465] ControllerSpotify::pushState
Sep 05 04:54:21 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:21 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:21 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:21 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:21 kukulkan volumio[831]: info: VolumeController::SetAlsaVolume45
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:22 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:22 kukulkan volumio[831]: info: [1725512062149] ControllerSpotify::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:22 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:22 kukulkan volumio[831]: info: [1725512062572] ControllerSpotify::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:22 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:22 kukulkan volumio[831]: info: [1725512062867] ControllerSpotify::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:22 kukulkan volumio[831]: info: Is Connect Playback
Sep 05 04:54:22 kukulkan volumio[831]: info: [1725512062972] ControllerSpotify::pushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState
Sep 05 04:54:22 kukulkan volumio[831]: info: CoreStateMachine::pushState
Sep 05 04:54:23 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState
Sep 05 04:54:23 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 05 04:54:23 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
Sep 05 04:54:23 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon...
Sep 05 04:54:23 kukulkan systemd[1]: Starting Volspotconnect2 Daemon...
Sep 05 04:54:23 kukulkan systemd[1]: Started Volspotconnect2 Daemon.
Sep 05 04:54:23 kukulkan volumio[11307]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:232:61
Sep 05 04:54:23 kukulkan volumio[11307]: stack backtrace:
Sep 05 04:54:23 kukulkan volumio[11307]: 0:   0xa5360c - backtrace::backtrace::libunwind::trace::hf9eeeb1d52578408
Sep 05 04:54:23 kukulkan volumio[11307]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86
Sep 05 04:54:23 kukulkan volumio[11307]: 1:   0xa5360c - backtrace::backtrace::trace_unsynchronized::h9d71662eece903ab
Sep 05 04:54:23 kukulkan volumio[11307]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66
Sep 05 04:54:23 kukulkan volumio[11307]: 2:   0xa5360c - std::sys_common::backtrace::_print_fmt::h54695504f8601726
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:78
Sep 05 04:54:23 kukulkan volumio[11307]: 3:   0xa5360c - ::fmt::h512ecf96415d8ce2
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:59
Sep 05 04:54:23 kukulkan volumio[11307]: 4:   0xa78218 - core::fmt::write::h1060f85d3ee53644
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libcore/fmt/mod.rs:1076
Sep 05 04:54:23 kukulkan volumio[11307]: 5:   0xa4cee0 - std::io::Write::write_fmt::h34e2a8730bb7a876
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/io/mod.rs:1537
Sep 05 04:54:23 kukulkan volumio[11307]: 6:   0xa55c88 - std::sys_common::backtrace::_print::h2be13b0c8dff2c9b
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:62
Sep 05 04:54:23 kukulkan systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a
Sep 05 04:54:23 kukulkan systemd[1]: Unit volspotconnect2.service entered failed state.
Sep 05 04:54:23 kukulkan volumio[11307]: 7:   0xa55c88 - std::sys_common::backtrace::print::hd763058df6eec412
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/sys_common/backtrace.rs:49
Sep 05 04:54:23 kukulkan volumio[11307]: 8:   0xa55c88 - std::panicking::default_hook::{{closure}}::h48b9472d8fc0e6bf
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/panicking.rs:198
Sep 05 04:54:23 kukulkan volumio[11307]: 9:   0xa5593c - std::panicking::default_hook::h0fc1c5596bab0152
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/panicking.rs:217
Sep 05 04:54:23 kukulkan volumio[11307]: 10:   0xa56368 - std::panicking::rust_panic_with_hook::hae7ba177154e198d
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/panicking.rs:526
Sep 05 04:54:23 kukulkan volumio[11307]: 11:   0xa55f68 - rust_begin_unwind
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libstd/panicking.rs:437
Sep 05 04:54:23 kukulkan volumio[11307]: 12:   0xa758f0 - core::panicking::panic_fmt::hf855e45b7cc21a3f
Sep 05 04:54:23 kukulkan volumio[11307]: at src/libcore/panicking.rs:85
Sep 05 04:54:24 kukulkan volumio[11307]: 13:   0xa75748 - core::option::expect_none_failed::h4b0b008108079597
Sep 05 04:54:24 kukulkan volumio[11307]: at src/libcore/option.rs:1269
Sep 05 04:54:24 kukulkan volumio[11307]: 14:   0x616ce8 - librespot_playback::player::Player::pause::h27e4b77a111e89af
Sep 05 04:54:24 kukulkan volumio[11307]: 15:   0x5ee04c - librespot_connect::spirc::SpircTask::handle_pause::h2eb1add7b9e1c4e7
Sep 05 04:54:24 kukulkan volumio[11307]: 16:   0x5ebc14 - ::poll::h6201a2b9faae04af
Sep 05 04:54:24 kukulkan volumio[11307]: 17:   0x56090c - ::poll::h2c608b7bbd5483b9
Sep 05 04:54:24 kukulkan volumio[11307]: 18:   0x527028 - futures::task_impl::std::set::he0ba9d7a117059e7
Sep 05 04:54:24 kukulkan volumio[11307]: 19:   0x53b454 - std::thread::local::LocalKey::with::h69e67cf00223a2ba
Sep 05 04:54:24 kukulkan volumio[11307]: 20:   0x516718 - tokio_current_thread::Entered

::block_on::h2e4ff2c13a247d3c Sep 05 04:54:24 kukulkan volumio[11307]: 21: 0x53b298 - std::thread::local::LocalKey::with::h663fcb2d9f4b6c18 Sep 05 04:54:24 kukulkan volumio[11307]: 22: 0x5485d4 - tokio_reactor::with_default::h5d8f90b2b875599a Sep 05 04:54:24 kukulkan volumio[11307]: 23: 0x51a940 - tokio::runtime::current_thread::runtime::Runtime::block_on::h9505b8c1bab44fab Sep 05 04:54:24 kukulkan volumio[11307]: 24: 0x561f98 - vollibrespot::main::h3614a8e14a6045df Sep 05 04:54:24 kukulkan volumio[11307]: 25: 0x5492e0 - std::rt::lang_start::{{closure}}::h0a25e89da98ec7db Sep 05 04:54:24 kukulkan volumio[11307]: 26: 0xa5672c - std::rt::lang_start_internal::{{closure}}::h97efd249046dc8e8 Sep 05 04:54:24 kukulkan volumio[11307]: at src/libstd/rt.rs:52 Sep 05 04:54:24 kukulkan volumio[11307]: 27: 0xa5672c - std::panicking::try::do_call::h3d07de510dcbc7c8 Sep 05 04:54:24 kukulkan volumio[831]: [SpotifyConnect] Device palyback is inactive Sep 05 04:54:24 kukulkan volumio[831]: [SpotifyConnect] Sink released Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512063840] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064023] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[11493]: /usr/lib/startconnect.sh: line 5: 11498 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:24 kukulkan volumio[11307]: at src/libstd/panicking.rs:348 Sep 05 04:54:24 kukulkan volumio[11307]: 28: 0xa5672c - std::panicking::try::hc25ddf66eb022203 Sep 05 04:54:24 kukulkan volumio[11307]: at src/libstd/panicking.rs:325 Sep 05 04:54:24 kukulkan volumio[11307]: 29: 0xa5672c - std::panic::catch_unwind::h36b06962268095b4 Sep 05 04:54:24 kukulkan volumio[11307]: at src/libstd/panic.rs:394 Sep 05 04:54:24 kukulkan volumio[11307]: 30: 0xa5672c - std::rt::lang_start_internal::hdbd4407cfdcc64c8 Sep 05 04:54:24 kukulkan volumio[11307]: at src/libstd/rt.rs:51 Sep 05 04:54:24 kukulkan volumio[11307]: 31: 0x562a54 - main Sep 05 04:54:24 kukulkan volumio[11307]: 32: 0xb6ca4294 - __libc_start_main Sep 05 04:54:24 kukulkan volumio[11307]: [Vollibrespot] : EventSender disconnected Sep 05 04:54:24 kukulkan volumio[11307]: [Vollibrespot] : Player thread panicked! Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064299] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064468] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064547] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064650] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064714] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064781] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064846] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:24 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:24 kukulkan volumio[831]: info: [1725512064938] ControllerSpotify::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:24 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065146] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065246] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065383] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065488] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065583] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065668] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan volumio[831]: info: Is Connect Playback Sep 05 04:54:25 kukulkan volumio[831]: info: [1725512065731] ControllerSpotify::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::servicePushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreStateMachine::pushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:25 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:25 kukulkan systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Sep 05 04:54:25 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:25 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:25 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:25 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:25 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:25 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:26 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:26 kukulkan volumio[11513]: /usr/lib/startconnect.sh: line 5: 11515 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:26 kukulkan volumio[11514]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Sep 05 04:54:26 kukulkan volumio[11514]: Reading Config from "volspotify.toml" Sep 05 04:54:26 kukulkan volumio[11514]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Sep 05 04:54:26 kukulkan volumio[11514]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Sep 05 04:54:28 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:28 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:28 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:28 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:28 kukulkan volumio[11522]: /usr/lib/startconnect.sh: line 5: 11523 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:30 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:30 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:30 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:30 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:30 kukulkan volumio[11529]: /usr/lib/startconnect.sh: line 5: 11532 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:32 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:32 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:32 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:32 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:32 kukulkan volumio[11538]: /usr/lib/startconnect.sh: line 5: 11539 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:34 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:34 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:34 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:34 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:35 kukulkan volumio[11545]: /usr/lib/startconnect.sh: line 5: 11546 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:37 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:37 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:37 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:37 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:37 kukulkan volumio[11552]: /usr/lib/startconnect.sh: line 5: 11553 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:39 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:39 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:39 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:39 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:39 kukulkan volumio[11560]: /usr/lib/startconnect.sh: line 5: 11561 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:41 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:41 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:41 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:41 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:41 kukulkan volumio[11567]: /usr/lib/startconnect.sh: line 5: 11568 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:42 kukulkan volumio[831]: info: CoreCommandRouter::volumioClearQueue Sep 05 04:54:42 kukulkan volumio[831]: info: CoreStateMachine::ClearQueue Sep 05 04:54:42 kukulkan volumio[831]: info: CoreStateMachine::stop Sep 05 04:54:42 kukulkan volumio[831]: info: CoreStateMachine::serviceStop Sep 05 04:54:42 kukulkan volumio[831]: info: CoreCommandRouter::serviceStop Sep 05 04:54:42 kukulkan volumio[831]: [SpotifyConnect] Spotify Received stop Sep 05 04:54:42 kukulkan volumio[831]: info: CorePlayQueue::clearPlayQueue Sep 05 04:54:42 kukulkan volumio[831]: info: CorePlayQueue::saveQueue Sep 05 04:54:42 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:42 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:42 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:54:43 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:43 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:43 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:43 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:44 kukulkan volumio[11574]: /usr/lib/startconnect.sh: line 5: 11575 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:44 kukulkan volumio[831]: info: CoreCommandRouter::volumioClearQueue Sep 05 04:54:44 kukulkan volumio[831]: info: CoreStateMachine::ClearQueue Sep 05 04:54:44 kukulkan volumio[831]: info: CoreStateMachine::stop Sep 05 04:54:44 kukulkan volumio[831]: info: CoreStateMachine::serviceStop Sep 05 04:54:44 kukulkan volumio[831]: info: CoreCommandRouter::serviceStop Sep 05 04:54:44 kukulkan volumio[831]: [SpotifyConnect] Spotify Received stop Sep 05 04:54:44 kukulkan volumio[831]: info: CorePlayQueue::clearPlayQueue Sep 05 04:54:44 kukulkan volumio[831]: info: CorePlayQueue::saveQueue Sep 05 04:54:44 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushState Sep 05 04:54:44 kukulkan volumio[831]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 04:54:44 kukulkan volumio[831]: info: CoreCommandRouter::volumioPushQueue Sep 05 04:54:44 kukulkan volumio[11514]: [Vollibrespot] : couldn't parse packet from V4(192.168.0.21:5353): type 47 is invalid Sep 05 04:54:46 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:46 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:46 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:46 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:46 kukulkan volumio[11581]: /usr/lib/startconnect.sh: line 5: 11582 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:46 kukulkan volumio[831]: info: CoreCommandRouter::volumioPlay Sep 05 04:54:46 kukulkan volumio[831]: UNSET VOLATILE Sep 05 04:54:46 kukulkan volumio[831]: [SpotifyConnect] unSetVolatile called Sep 05 04:54:46 kukulkan volumio[831]: info: Spotify Unset Volatile called Sep 05 04:54:46 kukulkan volumio[831]: [SpotifyConnect] Relinquishing Volumio State to another service Sep 05 04:54:46 kukulkan volumio[831]: [SpotifyConnect] Spotify Received stop Sep 05 04:54:46 kukulkan volumio[831]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 04:54:46 kukulkan volumio[831]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Sep 05 04:54:46 kukulkan volumio[831]: at Socket._healthCheck (dgram.js:638:11) Sep 05 04:54:46 kukulkan volumio[831]: at Socket.send (dgram.js:435:8) Sep 05 04:54:46 kukulkan volumio[831]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Sep 05 04:54:46 kukulkan volumio[831]: at ControllerSpotify.stop (/data/plugins/music_service/spop/index.js:2674:19) Sep 05 04:54:46 kukulkan volumio[831]: at ControllerSpotify.spotConnUnsetVolatile (/data/plugins/music_service/spop/index.js:2355:17) Sep 05 04:54:46 kukulkan volumio[831]: at ControllerSpotify.unsetVol (/data/plugins/music_service/spop/index.js:68:21) Sep 05 04:54:46 kukulkan volumio[831]: at CoreStateMachine.unSetVolatile (/volumio/app/statemachine.js:1506:27) Sep 05 04:54:46 kukulkan volumio[831]: at CoreCommandRouter.volumioPlay (/volumio/app/index.js:1308:21) Sep 05 04:54:46 kukulkan volumio[831]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:202:35) Sep 05 04:54:46 kukulkan volumio[831]: at emitTwo (events.js:126:13) Sep 05 04:54:46 kukulkan volumio[831]: at Socket.emit (events.js:214:7) Sep 05 04:54:46 kukulkan volumio[831]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Sep 05 04:54:46 kukulkan volumio[831]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Sep 05 04:54:46 kukulkan volumio[831]: at process._tickCallback (internal/process/next_tick.js:180:9) Sep 05 04:54:46 kukulkan volumio[831]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 04:54:48 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:48 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:48 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:48 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:48 kukulkan volumio[11591]: /usr/lib/startconnect.sh: line 5: 11592 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:50 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:50 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:50 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:50 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:50 kukulkan volumio[11597]: /usr/lib/startconnect.sh: line 5: 11598 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml Sep 05 04:54:51 kukulkan sudo[11601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 04:53 Sep 05 04:54:51 kukulkan sudo[11601]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 04:54:52 kukulkan systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Sep 05 04:54:52 kukulkan systemd[1]: Stopping Volspotconnect2 Daemon... Sep 05 04:54:52 kukulkan systemd[1]: Starting Volspotconnect2 Daemon... Sep 05 04:54:52 kukulkan systemd[1]: Started Volspotconnect2 Daemon. Sep 05 04:54:53 kukulkan volumio[11606]: /usr/lib/startconnect.sh: line 5: 11607 Illegal instruction .$SPOP_PLUGIN_DATA -c /tmp/volspotify.toml PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="b9cd485c2d7861a79d18f42ff0fdb052338b2239" VOLUMIO_FE_VERSION="002b92c82c889cd6d6dba7b53841c5ae23bbbe3e" VOLUMIO_BE_VERSION="7d2ba3a94da666f8c268b206dcaf6a466691b221" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Jun 8 18:17:29 CEST 2020" VOLUMIO_VERSION="2.779" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d3e1021b8bd596622d29cc9abe301111"