// mautrix-whatsapp - A Matrix-WhatsApp puppeting bridge. // Copyright (C) 2018 Tulir Asokan // // This program is free software: you can redistribute it and/or modify // it under the terms of the GNU Affero General Public License as published by // the Free Software Foundation, either version 3 of the License, or // (at your option) any later version. // // This program is distributed in the hope that it will be useful, // but WITHOUT ANY WARRANTY; without even the implied warranty of // MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the // GNU Affero General Public License for more details. // // You should have received a copy of the GNU Affero General Public License // along with this program. If not, see . package main import ( "bytes" "encoding/gob" "encoding/hex" "fmt" "image" "image/gif" "image/jpeg" "image/png" "math/rand" "mime" "net/http" "strings" "sync" "github.com/Rhymen/go-whatsapp" waProto "github.com/Rhymen/go-whatsapp/binary/proto" "maunium.net/go/gomatrix" log "maunium.net/go/maulogger" "maunium.net/go/mautrix-appservice" "maunium.net/go/mautrix-whatsapp/database" "maunium.net/go/mautrix-whatsapp/types" "maunium.net/go/mautrix-whatsapp/whatsapp-ext" ) func (bridge *Bridge) GetPortalByMXID(mxid types.MatrixRoomID) *Portal { bridge.portalsLock.Lock() defer bridge.portalsLock.Unlock() portal, ok := bridge.portalsByMXID[mxid] if !ok { dbPortal := bridge.DB.Portal.GetByMXID(mxid) if dbPortal == nil { return nil } portal = bridge.NewPortal(dbPortal) bridge.portalsByJID[portal.Key] = portal if len(portal.MXID) > 0 { bridge.portalsByMXID[portal.MXID] = portal } } return portal } func (bridge *Bridge) GetPortalByJID(key database.PortalKey) *Portal { bridge.portalsLock.Lock() defer bridge.portalsLock.Unlock() portal, ok := bridge.portalsByJID[key] if !ok { dbPortal := bridge.DB.Portal.GetByJID(key) if dbPortal == nil { dbPortal = bridge.DB.Portal.New() dbPortal.Key = key dbPortal.Insert() } portal = bridge.NewPortal(dbPortal) bridge.portalsByJID[portal.Key] = portal if len(portal.MXID) > 0 { bridge.portalsByMXID[portal.MXID] = portal } } return portal } func (bridge *Bridge) GetAllPortals() []*Portal { bridge.portalsLock.Lock() defer bridge.portalsLock.Unlock() dbPortals := bridge.DB.Portal.GetAll() output := make([]*Portal, len(dbPortals)) for index, dbPortal := range dbPortals { portal, ok := bridge.portalsByJID[dbPortal.Key] if !ok { portal = bridge.NewPortal(dbPortal) bridge.portalsByJID[portal.Key] = portal if len(dbPortal.MXID) > 0 { bridge.portalsByMXID[dbPortal.MXID] = portal } } output[index] = portal } return output } func (bridge *Bridge) NewPortal(dbPortal *database.Portal) *Portal { return &Portal{ Portal: dbPortal, bridge: bridge, log: bridge.Log.Sub(fmt.Sprintf("Portal/%s", dbPortal.Key)), messageLocks: make(map[types.WhatsAppMessageID]sync.Mutex), recentlyHandled: [recentlyHandledLength]types.WhatsAppMessageID{}, } } const recentlyHandledLength = 100 type Portal struct { *database.Portal bridge *Bridge log log.Logger roomCreateLock sync.Mutex messageLocksLock sync.Mutex messageLocks map[types.WhatsAppMessageID]sync.Mutex recentlyHandled [recentlyHandledLength]types.WhatsAppMessageID recentlyHandledLock sync.Mutex recentlyHandledIndex uint8 isPrivate *bool } func (portal *Portal) getMessageLock(messageID types.WhatsAppMessageID) sync.Mutex { portal.messageLocksLock.Lock() defer portal.messageLocksLock.Unlock() lock, ok := portal.messageLocks[messageID] if !ok { portal.messageLocks[messageID] = lock } return lock } func (portal *Portal) deleteMessageLock(messageID types.WhatsAppMessageID) { portal.messageLocksLock.Lock() delete(portal.messageLocks, messageID) portal.messageLocksLock.Unlock() } func (portal *Portal) isRecentlyHandled(id types.WhatsAppMessageID) bool { start := portal.recentlyHandledIndex for i := start; i != start; i = (i - 1) % recentlyHandledLength { if portal.recentlyHandled[i] == id { return true } } return false } func (portal *Portal) isDuplicate(id types.WhatsAppMessageID) bool { msg := portal.bridge.DB.Message.GetByJID(portal.Key, id) if msg != nil { return true } return false } func init() { gob.Register(&waProto.Message{}) } func (portal *Portal) markHandled(source *User, message *waProto.WebMessageInfo, mxid types.MatrixEventID) { msg := portal.bridge.DB.Message.New() msg.Chat = portal.Key msg.JID = message.GetKey().GetId() msg.MXID = mxid if message.GetKey().GetFromMe() { msg.Sender = source.JID } else if portal.IsPrivateChat() { msg.Sender = portal.Key.JID } else { msg.Sender = message.GetKey().GetParticipant() if len(msg.Sender) == 0 { msg.Sender = message.GetParticipant() } } msg.Content = message.Message msg.Insert() portal.recentlyHandledLock.Lock() index := portal.recentlyHandledIndex portal.recentlyHandledIndex = (portal.recentlyHandledIndex + 1) % recentlyHandledLength portal.recentlyHandledLock.Unlock() portal.recentlyHandled[index] = msg.JID } func (portal *Portal) startHandling(id types.WhatsAppMessageID) (*sync.Mutex, bool) { if portal.isRecentlyHandled(id) { return nil, false } lock := portal.getMessageLock(id) lock.Lock() if portal.isDuplicate(id) { lock.Unlock() return nil, false } return &lock, true } func (portal *Portal) finishHandling(source *User, message *waProto.WebMessageInfo, mxid types.MatrixEventID) { portal.markHandled(source, message, mxid) id := message.GetKey().GetId() portal.deleteMessageLock(id) portal.log.Debugln("Handled message", id, "->", mxid) } func (portal *Portal) SyncParticipants(metadata *whatsappExt.GroupInfo) { changed := false levels, err := portal.MainIntent().PowerLevels(portal.MXID) if err != nil { levels = portal.GetBasePowerLevels() changed = true } for _, participant := range metadata.Participants { puppet := portal.bridge.GetPuppetByJID(participant.JID) puppet.Intent().EnsureJoined(portal.MXID) user := portal.bridge.GetUserByJID(participant.JID) if user != nil && !portal.bridge.AS.StateStore.IsInvited(portal.MXID, user.MXID) { portal.MainIntent().InviteUser(portal.MXID, &gomatrix.ReqInviteUser{ UserID: user.MXID, }) } expectedLevel := 0 if participant.IsSuperAdmin { expectedLevel = 95 } else if participant.IsAdmin { expectedLevel = 50 } changed = levels.EnsureUserLevel(puppet.MXID, expectedLevel) || changed if user != nil { changed = levels.EnsureUserLevel(user.MXID, expectedLevel) || changed } } if changed { _, err = portal.MainIntent().SetPowerLevels(portal.MXID, levels) if err != nil { portal.log.Errorln("Failed to change power levels:", err) } } } func (portal *Portal) UpdateAvatar(user *User, avatar *whatsappExt.ProfilePicInfo) bool { if avatar == nil { var err error avatar, err = user.Conn.GetProfilePicThumb(portal.Key.JID) if err != nil { portal.log.Errorln(err) return false } } if avatar.Status != 0 { // 401: ??? // 404: ??? return false } if portal.Avatar == avatar.Tag { return false } data, err := avatar.DownloadBytes() if err != nil { portal.log.Errorln("Failed to download avatar:", err) return false } mimeType := http.DetectContentType(data) resp, err := portal.MainIntent().UploadBytes(data, mimeType) if err != nil { portal.log.Errorln("Failed to upload avatar:", err) return false } _, err = portal.MainIntent().SetRoomAvatar(portal.MXID, resp.ContentURI) if err != nil { portal.log.Warnln("Failed to set room topic:", err) return false } portal.Avatar = avatar.Tag return true } func (portal *Portal) UpdateName(name string, setBy types.WhatsAppID) bool { if portal.Name != name { intent := portal.bridge.GetPuppetByJID(setBy).Intent() _, err := intent.SetRoomName(portal.MXID, name) if err == nil { portal.Name = name return true } portal.log.Warnln("Failed to set room name:", err) } return false } func (portal *Portal) UpdateTopic(topic string, setBy types.WhatsAppID) bool { if portal.Topic != topic { intent := portal.bridge.GetPuppetByJID(setBy).Intent() _, err := intent.SetRoomTopic(portal.MXID, topic) if err == nil { portal.Topic = topic return true } portal.log.Warnln("Failed to set room topic:", err) } return false } func (portal *Portal) UpdateMetadata(user *User) bool { metadata, err := user.Conn.GetGroupMetaData(portal.Key.JID) if err != nil { portal.log.Errorln(err) return false } if metadata.Status != 0 { // 401: access denied // 404: group does (no longer) exist // 500: ??? happens with status@broadcast // TODO: update the room, e.g. change priority level // to send messages to moderator return false } portal.SyncParticipants(metadata) update := false update = portal.UpdateName(metadata.Name, metadata.NameSetBy) || update update = portal.UpdateTopic(metadata.Topic, metadata.TopicSetBy) || update return update } func (portal *Portal) Sync(user *User, contact whatsapp.Contact) { if portal.IsPrivateChat() { return } if len(portal.MXID) == 0 { portal.Name = contact.Name err := portal.CreateMatrixRoom([]string{user.MXID}) if err != nil { portal.log.Errorln("Failed to create portal room:", err) return } } else { portal.MainIntent().EnsureInvited(portal.MXID, user.MXID) } update := false update = portal.UpdateMetadata(user) || update update = portal.UpdateAvatar(user, nil) || update if update { portal.Update() } } func (portal *Portal) GetBasePowerLevels() *gomatrix.PowerLevels { anyone := 0 nope := 99 return &gomatrix.PowerLevels{ UsersDefault: anyone, EventsDefault: anyone, RedactPtr: &anyone, StateDefaultPtr: &nope, BanPtr: &nope, InvitePtr: &nope, Users: map[string]int{ portal.MainIntent().UserID: 100, }, Events: map[string]int{ gomatrix.StateRoomName.Type: anyone, gomatrix.StateRoomAvatar.Type: anyone, gomatrix.StateTopic.Type: anyone, }, } } func (portal *Portal) ChangeAdminStatus(jids []string, setAdmin bool) { levels, err := portal.MainIntent().PowerLevels(portal.MXID) if err != nil { levels = portal.GetBasePowerLevels() } newLevel := 0 if setAdmin { newLevel = 50 } changed := false for _, jid := range jids { puppet := portal.bridge.GetPuppetByJID(jid) changed = levels.EnsureUserLevel(puppet.MXID, newLevel) || changed user := portal.bridge.GetUserByJID(jid) if user != nil { changed = levels.EnsureUserLevel(user.MXID, newLevel) || changed } } if changed { _, err = portal.MainIntent().SetPowerLevels(portal.MXID, levels) if err != nil { portal.log.Errorln("Failed to change power levels:", err) } } } func (portal *Portal) RestrictMessageSending(restrict bool) { levels, err := portal.MainIntent().PowerLevels(portal.MXID) if err != nil { levels = portal.GetBasePowerLevels() } if restrict { levels.EventsDefault = 50 } else { levels.EventsDefault = 0 } _, err = portal.MainIntent().SetPowerLevels(portal.MXID, levels) if err != nil { portal.log.Errorln("Failed to change power levels:", err) } } func (portal *Portal) RestrictMetadataChanges(restrict bool) { levels, err := portal.MainIntent().PowerLevels(portal.MXID) if err != nil { levels = portal.GetBasePowerLevels() } newLevel := 0 if restrict { newLevel = 50 } changed := false changed = levels.EnsureEventLevel(gomatrix.StateRoomName, newLevel) || changed changed = levels.EnsureEventLevel(gomatrix.StateRoomAvatar, newLevel) || changed changed = levels.EnsureEventLevel(gomatrix.StateTopic, newLevel) || changed if changed { _, err = portal.MainIntent().SetPowerLevels(portal.MXID, levels) if err != nil { portal.log.Errorln("Failed to change power levels:", err) } } } func (portal *Portal) CreateMatrixRoom(invite []string) error { portal.roomCreateLock.Lock() defer portal.roomCreateLock.Unlock() if len(portal.MXID) > 0 { return nil } intent := portal.MainIntent() if err := intent.EnsureRegistered(); err != nil { return err } name := portal.Name topic := portal.Topic isPrivateChat := false if portal.IsPrivateChat() { name = "" topic = "WhatsApp private chat" isPrivateChat = true } resp, err := intent.CreateRoom(&gomatrix.ReqCreateRoom{ Visibility: "private", Name: name, Topic: topic, Invite: invite, Preset: "private_chat", IsDirect: isPrivateChat, InitialState: []*gomatrix.Event{{ Type: gomatrix.StatePowerLevels, Content: gomatrix.Content{ PowerLevels: *portal.GetBasePowerLevels(), }, }}, }) if err != nil { return err } portal.MXID = resp.RoomID portal.Update() return nil } func (portal *Portal) IsPrivateChat() bool { if portal.isPrivate == nil { val := strings.HasSuffix(portal.Key.JID, whatsappExt.NewUserSuffix) portal.isPrivate = &val } return *portal.isPrivate } func (portal *Portal) MainIntent() *appservice.IntentAPI { if portal.IsPrivateChat() { return portal.bridge.GetPuppetByJID(portal.Key.JID).Intent() } return portal.bridge.Bot } func (portal *Portal) GetMessageIntent(user *User, info whatsapp.MessageInfo) *appservice.IntentAPI { if info.FromMe { if portal.IsPrivateChat() { // TODO handle own messages in private chats properly return nil } return portal.bridge.GetPuppetByJID(user.JID).Intent() } else if portal.IsPrivateChat() { return portal.MainIntent() } else if len(info.SenderJid) == 0 { if len(info.Source.GetParticipant()) != 0 { info.SenderJid = info.Source.GetParticipant() } else { return nil } } return portal.bridge.GetPuppetByJID(info.SenderJid).Intent() } func (portal *Portal) SetReply(content *gomatrix.Content, info whatsapp.MessageInfo) { if len(info.QuotedMessageID) == 0 { return } message := portal.bridge.DB.Message.GetByJID(portal.Key, info.QuotedMessageID) if message != nil { event, err := portal.MainIntent().GetEvent(portal.MXID, message.MXID) if err != nil { portal.log.Warnln("Failed to get reply target:", err) return } content.SetReply(event) } return } func (portal *Portal) HandleTextMessage(source *User, message whatsapp.TextMessage) { lock, ok := portal.startHandling(message.Info.Id) if !ok { return } defer lock.Unlock() err := portal.CreateMatrixRoom([]string{source.MXID}) if err != nil { portal.log.Errorln("Failed to create portal room:", err) return } intent := portal.GetMessageIntent(source, message.Info) if intent == nil { return } content := &gomatrix.Content{ Body: message.Text, MsgType: gomatrix.MsgText, } portal.bridge.Formatter.ParseWhatsApp(content) portal.SetReply(content, message.Info) intent.UserTyping(portal.MXID, false, 0) resp, err := intent.SendMassagedMessageEvent(portal.MXID, gomatrix.EventMessage, content, int64(message.Info.Timestamp*1000)) if err != nil { portal.log.Errorfln("Failed to handle message %s: %v", message.Info.Id, err) return } portal.finishHandling(source, message.Info.Source, resp.EventID) } func (portal *Portal) HandleMediaMessage(source *User, download func() ([]byte, error), thumbnail []byte, info whatsapp.MessageInfo, mimeType, caption string) { lock, ok := portal.startHandling(info.Id) if !ok { return } defer lock.Unlock() err := portal.CreateMatrixRoom([]string{source.MXID}) if err != nil { portal.log.Errorln("Failed to create portal room:", err) return } intent := portal.GetMessageIntent(source, info) if intent == nil { return } data, err := download() if err != nil { portal.log.Errorln("Failed to download media:", err) return } uploaded, err := intent.UploadBytes(data, mimeType) if err != nil { portal.log.Errorln("Failed to upload media:", err) return } fileName := info.Id exts, _ := mime.ExtensionsByType(mimeType) if exts != nil && len(exts) > 0 { fileName += exts[0] } content := &gomatrix.Content{ Body: fileName, URL: uploaded.ContentURI, Info: &gomatrix.FileInfo{ Size: len(data), MimeType: mimeType, }, } portal.SetReply(content, info) if thumbnail != nil { thumbnailMime := http.DetectContentType(thumbnail) uploadedThumbnail, _ := intent.UploadBytes(thumbnail, thumbnailMime) if uploadedThumbnail != nil { content.Info.ThumbnailURL = uploadedThumbnail.ContentURI cfg, _, _ := image.DecodeConfig(bytes.NewReader(data)) content.Info.ThumbnailInfo = &gomatrix.FileInfo{ Size: len(thumbnail), Width: cfg.Width, Height: cfg.Height, MimeType: thumbnailMime, } } } switch strings.ToLower(strings.Split(mimeType, "/")[0]) { case "image": content.MsgType = gomatrix.MsgImage cfg, _, _ := image.DecodeConfig(bytes.NewReader(data)) content.Info.Width = cfg.Width content.Info.Height = cfg.Height case "video": content.MsgType = gomatrix.MsgVideo case "audio": content.MsgType = gomatrix.MsgAudio default: content.MsgType = gomatrix.MsgFile } intent.UserTyping(portal.MXID, false, 0) ts := int64(info.Timestamp * 1000) resp, err := intent.SendMassagedMessageEvent(portal.MXID, gomatrix.EventMessage, content, ts) if err != nil { portal.log.Errorfln("Failed to handle message %s: %v", info.Id, err) return } if len(caption) > 0 { captionContent := &gomatrix.Content{ Body: caption, MsgType: gomatrix.MsgNotice, } portal.bridge.Formatter.ParseWhatsApp(captionContent) _, err := intent.SendMassagedMessageEvent(portal.MXID, gomatrix.EventMessage, captionContent, ts) if err != nil { portal.log.Warnfln("Failed to handle caption of message %s: %v", info.Id, err) } // TODO store caption mxid? } portal.finishHandling(source, info.Source, resp.EventID) } func makeMessageID() *string { b := make([]byte, 10) rand.Read(b) str := strings.ToUpper(hex.EncodeToString(b)) return &str } func (portal *Portal) downloadThumbnail(evt *gomatrix.Event) []byte { if evt.Content.Info == nil || len(evt.Content.Info.ThumbnailURL) == 0 { return nil } thumbnail, err := portal.MainIntent().DownloadBytes(evt.Content.Info.ThumbnailURL) if err != nil { portal.log.Errorln("Failed to download thumbnail in %s: %v", evt.ID, err) return nil } thumbnailType := http.DetectContentType(thumbnail) var img image.Image switch thumbnailType { case "image/png": img, err = png.Decode(bytes.NewReader(thumbnail)) case "image/gif": img, err = gif.Decode(bytes.NewReader(thumbnail)) case "image/jpeg": return thumbnail default: return nil } var buf bytes.Buffer err = jpeg.Encode(&buf, img, &jpeg.Options{ Quality: jpeg.DefaultQuality, }) if err != nil { portal.log.Errorln("Failed to re-encode thumbnail in %s: %v", evt.ID, err) return nil } return buf.Bytes() } func (portal *Portal) preprocessMatrixMedia(sender *User, evt *gomatrix.Event, mediaType whatsapp.MediaType) *MediaUpload { if evt.Content.Info == nil { evt.Content.Info = &gomatrix.FileInfo{} } caption := evt.Content.Body exts, err := mime.ExtensionsByType(evt.Content.Info.MimeType) for _, ext := range exts { if strings.HasSuffix(caption, ext) { caption = "" break } } content, err := portal.MainIntent().DownloadBytes(evt.Content.URL) if err != nil { portal.log.Errorfln("Failed to download media in %s: %v", evt.ID, err) return nil } url, mediaKey, fileEncSHA256, fileSHA256, fileLength, err := sender.Conn.Upload(bytes.NewReader(content), mediaType) if err != nil { portal.log.Errorfln("Failed to upload media in %s: %v", evt.ID, err) return nil } return &MediaUpload{ Caption: caption, URL: url, MediaKey: mediaKey, FileEncSHA256: fileEncSHA256, FileSHA256: fileSHA256, FileLength: fileLength, Thumbnail: portal.downloadThumbnail(evt), } } type MediaUpload struct { Caption string URL string MediaKey []byte FileEncSHA256 []byte FileSHA256 []byte FileLength uint64 Thumbnail []byte } func (portal *Portal) HandleMatrixMessage(sender *User, evt *gomatrix.Event) { if portal.IsPrivateChat() && sender.JID != portal.Key.Receiver { return } ts := uint64(evt.Timestamp / 1000) status := waProto.WebMessageInfo_ERROR fromMe := true info := &waProto.WebMessageInfo{ Key: &waProto.MessageKey{ FromMe: &fromMe, Id: makeMessageID(), RemoteJid: &portal.Key.JID, }, MessageTimestamp: &ts, Message: &waProto.Message{}, Status: &status, } ctxInfo := &waProto.ContextInfo{} replyToID := evt.Content.GetReplyTo() if len(replyToID) > 0 { evt.Content.RemoveReplyFallback() msg := portal.bridge.DB.Message.GetByMXID(replyToID) if msg != nil && msg.Content != nil { ctxInfo.StanzaId = &msg.JID ctxInfo.Participant = &msg.Sender ctxInfo.QuotedMessage = []*waProto.Message{msg.Content} } } var err error switch evt.Content.MsgType { case gomatrix.MsgText, gomatrix.MsgEmote: text := evt.Content.Body if evt.Content.Format == gomatrix.FormatHTML { text = portal.bridge.Formatter.ParseMatrix(evt.Content.FormattedBody) } if evt.Content.MsgType == gomatrix.MsgEmote { text = "/me " + text } ctxInfo.MentionedJid = mentionRegex.FindAllString(text, -1) for index, mention := range ctxInfo.MentionedJid { ctxInfo.MentionedJid[index] = mention[1:] + whatsappExt.NewUserSuffix } if ctxInfo.StanzaId != nil || ctxInfo.MentionedJid != nil { info.Message.ExtendedTextMessage = &waProto.ExtendedTextMessage{ Text: &text, ContextInfo: ctxInfo, } } else { info.Message.Conversation = &text } case gomatrix.MsgImage: media := portal.preprocessMatrixMedia(sender, evt, whatsapp.MediaImage) if media == nil { return } info.Message.ImageMessage = &waProto.ImageMessage{ Caption: &media.Caption, JpegThumbnail: media.Thumbnail, Url: &media.URL, MediaKey: media.MediaKey, Mimetype: &evt.Content.GetInfo().MimeType, FileEncSha256: media.FileEncSHA256, FileSha256: media.FileSHA256, FileLength: &media.FileLength, } case gomatrix.MsgVideo: media := portal.preprocessMatrixMedia(sender, evt, whatsapp.MediaVideo) if media == nil { return } duration := uint32(evt.Content.GetInfo().Duration) info.Message.VideoMessage = &waProto.VideoMessage{ Caption: &media.Caption, JpegThumbnail: media.Thumbnail, Url: &media.URL, MediaKey: media.MediaKey, Mimetype: &evt.Content.GetInfo().MimeType, Seconds: &duration, FileEncSha256: media.FileEncSHA256, FileSha256: media.FileSHA256, FileLength: &media.FileLength, } case gomatrix.MsgAudio: media := portal.preprocessMatrixMedia(sender, evt, whatsapp.MediaAudio) if media == nil { return } duration := uint32(evt.Content.GetInfo().Duration) info.Message.AudioMessage = &waProto.AudioMessage{ Url: &media.URL, MediaKey: media.MediaKey, Mimetype: &evt.Content.GetInfo().MimeType, Seconds: &duration, FileEncSha256: media.FileEncSHA256, FileSha256: media.FileSHA256, FileLength: &media.FileLength, } case gomatrix.MsgFile: media := portal.preprocessMatrixMedia(sender, evt, whatsapp.MediaDocument) if media == nil { return } info.Message.DocumentMessage = &waProto.DocumentMessage{ Url: &media.URL, MediaKey: media.MediaKey, Mimetype: &evt.Content.GetInfo().MimeType, FileEncSha256: media.FileEncSHA256, FileSha256: media.FileSHA256, FileLength: &media.FileLength, } default: portal.log.Debugln("Unhandled Matrix event:", evt) return } portal.markHandled(sender, info, evt.ID) err = sender.Conn.Send(info) if err != nil { portal.log.Errorfln("Error handling Matrix event %s: %v", evt.ID, err) } else { portal.log.Debugln("Handled Matrix event:", evt) } }