raspotify-problem-logs

## Fresh restart of raspotify
Mar  7 23:04:06 raspberrypi systemd[1]: Starting Session c7 of user pi.
Mar  7 23:04:06 raspberrypi systemd[1]: Started Session c7 of user pi.
Mar  7 23:04:18 raspberrypi systemd[1]: Stopping Raspotify...
Mar  7 23:04:18 raspberrypi systemd[1]: Starting Raspotify...
Mar  7 23:04:18 raspberrypi systemd[1]: Started Raspotify.
Mar  7 23:04:18 raspberrypi librespot[10662]: [2021-03-07T23:04:18Z INFO  librespot] librespot (raspotify v0.16.0) 0adb851 (2020-12-15). Built on 2020-12-30. Build ID: KLdkAvgt
Mar  7 23:04:30 raspberrypi librespot[10662]: [2021-03-07T23:04:30Z INFO  librespot_core::session] Connecting to AP "gew1-accesspoint-e-3lkd.ap.spotify.com:4070"
Mar  7 23:04:30 raspberrypi librespot[10662]: [2021-03-07T23:04:30Z INFO  librespot_core::session] Authenticated as "blah" !
Mar  7 23:04:30 raspberrypi librespot[10662]: [2021-03-07T23:04:30Z INFO  librespot_playback::audio_backend::alsa] Using alsa sink
Mar  7 23:04:30 raspberrypi librespot[10662]: [2021-03-07T23:04:30Z INFO  librespot_core::session] Country: "IE"

## I cant remember if I had hit play on my phone at this point. I don't think I did - I think librespot just loaded this as it was the active paused track on my phone when I set the device to raspotify.
Mar  7 23:04:31 raspberrypi librespot[10662]: [2021-03-07T23:04:31Z INFO  librespot_playback::player] Loading <Teardrop> with Spotify URI <spotify:track:0WmYMaZFDs6qsPzpnEXRM5>
Mar  7 23:04:31 raspberrypi librespot[10662]: [2021-03-07T23:04:31Z INFO  librespot_playback::player] <Teardrop> (202840 ms) loaded

# I think I pressed play here and the song started playing as normal and I could hear it.
Mar  7 23:04:44 raspberrypi rtkit-daemon[1939]: Supervising 0 threads of 0 processes of 1 users.
Mar  7 23:04:44 raspberrypi rtkit-daemon[1939]: Supervising 0 threads of 0 processes of 1 users.
Mar  7 23:04:44 raspberrypi rtkit-daemon[1939]: Supervising 0 threads of 0 processes of 1 users.
Mar  7 23:04:44 raspberrypi rtkit-daemon[1939]: Supervising 0 threads of 0 processes of 1 users.
Mar  7 23:04:44 raspberrypi rtkit-daemon[1939]: Supervising 0 threads of 0 processes of 1 users.

# Some pulse / Bluez audio problems ?
Mar  7 23:04:44 raspberrypi pulseaudio[10716]: [pulseaudio] server-lookup.c: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar  7 23:04:44 raspberrypi pulseaudio[10716]: [pulseaudio] main.c: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar  7 23:04:44 raspberrypi pulseaudio[10716]: [pulseaudio] bluez5-util.c: GetManagedObjects() failed: org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 2 matched rules; type="method_call", sender=":1.127" (uid=118 pid=10716 comm="/usr/bin/pulseaudio --start --log-target=syslog ") interface="org.freedesktop.DBus.ObjectManager" member="GetManagedObjects" error name="(unset)" requested_reply="0" destination="org.bluez" (uid=0 pid=5937 comm="/usr/lib/bluetooth/bluetoothd ")
Mar  7 23:04:44 raspberrypi pulseaudio[10716]: [pulseaudio] bluez4-util.c: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.AccessDenied: Rejected send message, 2 matched rules; type="method_call", sender=":1.127" (uid=118 pid=10716 comm="/usr/bin/pulseaudio --start --log-target=syslog ") interface="org.bluez.Manager" member="GetProperties" error name="(unset)" requested_reply="0" destination="org.bluez" (uid=0 pid=5937 comm="/usr/lib/bluetooth/bluetoothd ")

# Pressed next track triggered loading it in librespot by the looks of things
# Spotify showed it as playing but no sound coming through
Mar  7 23:04:59 raspberrypi librespot[10662]: [2021-03-07T23:04:59Z INFO  librespot_playback::player] Loading <The Forest> with Spotify URI <spotify:track:1jo6LVJYiZR5uHOi9tdYvb>
Mar  7 23:04:59 raspberrypi librespot[10662]: [2021-03-07T23:04:59Z INFO  librespot_playback::player] <The Forest> (200013 ms) loaded

# At this point I skipped around a bunch in spotify (different songs / next song / prev song etc)
# Now I wasn't seeing anything in sys log but still showing as playing via raspotify on my phone