From 765dc618e6990e8b173c3d732b15da1f911e779f Mon Sep 17 00:00:00 2001
From: Tulir Asokan <tulir@maunium.net>
Date: Fri, 1 Mar 2024 14:43:25 +0200
Subject: [PATCH] Add more logs for avatar syncing

---
 puppet.go | 10 ++++++++--
 1 file changed, 8 insertions(+), 2 deletions(-)

diff --git a/puppet.go b/puppet.go
index 48431ab..48743e0 100644
--- a/puppet.go
+++ b/puppet.go
@@ -196,19 +196,20 @@ func (puppet *Puppet) UpdateAvatar(ctx context.Context, source *User) bool {
 	}
 	puppet.AvatarUpdateTS = time.Now()
 	if len(resp.Thumbnail) == 0 {
-		if puppet.AvatarHash == [32]byte{} && puppet.AvatarMXC.IsEmpty() {
-			puppet.AvatarSet = true
+		if puppet.AvatarHash == [32]byte{} && puppet.AvatarMXC.IsEmpty() && puppet.AvatarSet {
 			return true
 		}
 		puppet.AvatarHash = [32]byte{}
 		puppet.AvatarMXC = id.ContentURI{}
 		puppet.AvatarSet = false
+		puppet.log.Debug().Msg("Clearing user avatar")
 	} else {
 		thumbData := resp.Thumbnail[0].GetData()
 		hash := sha256.Sum256(thumbData.GetImageBuffer())
 		if hash == puppet.AvatarHash && puppet.AvatarSet {
 			return true
 		}
+		puppet.log.Debug().Hex("avatar_hash", hash[:]).Msg("Uploading new user avatar")
 		puppet.AvatarHash = hash
 		puppet.AvatarSet = false
 		avatarBytes := thumbData.GetImageBuffer()
@@ -220,6 +221,10 @@ func (puppet *Puppet) UpdateAvatar(ctx context.Context, source *User) bool {
 			puppet.log.Err(err).Msg("Failed to upload avatar")
 			return true
 		}
+		puppet.log.Debug().
+			Hex("avatar_hash", hash[:]).
+			Stringer("mxc", uploadResp.ContentURI).
+			Msg("Uploaded new user avatar")
 		puppet.AvatarMXC = uploadResp.ContentURI
 	}
 	err = puppet.DefaultIntent().SetAvatarURL(ctx, puppet.AvatarMXC)
@@ -227,6 +232,7 @@ func (puppet *Puppet) UpdateAvatar(ctx context.Context, source *User) bool {
 		puppet.log.Err(err).Msg("Failed to set avatar")
 	} else {
 		puppet.AvatarSet = true
+		puppet.log.Debug().Stringer("mxc", puppet.AvatarMXC).Msg("Updated avatar")
 	}
 	go puppet.updatePortalAvatar(ctx)
 	return true