search

mautrix / meta

A Matrix-Facebook Messenger and Instagram DM puppeting bridge.
GNU Affero General Public License v3.0
155 stars 11 forks source link

Failure to upload image - refresh error #61

Closed alexmaras closed 3 months ago

alexmaras commented 3 months ago

An error is occurring when sending images in a chat:

image

May 13 16:56:18 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:18Z DBG Event decrypted successfully action="decrypt megolm event" decrypted_event_type="m.room.message (message)" event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 message_index=55 sender=@coolbug:nop.chat sender_key=yCgMCO+6KFmxTXRtuuyqUkSchYMJyKUoJjiYRLmHxBY session_id=0U4hwy2DpPJZNiNEhJVBf06IbHJs7TdblLvRWC9S4yw
May 13 16:56:18 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:18Z DBG Received message action="handle matrix event" age=48.500199 event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message room_id=!HXapaLKNFEXfapGIYO:nop.chat sender=@coolbug:nop.chat thread_id=2404118289665795
May 13 16:56:18 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:18Z DBG Sent message checkpoint message_checkpoint={"event_id":"$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4","event_type":"m.room.message","message_type":"m.image","reported_by":"BRIDGE","retry_num":0,"room_id":"!HXapaLKNFEXfapGIYO:nop.chat","status":"SUCCESS","step":"DECRYPTED","timestamp":1715590578740}
May 13 16:56:18 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:18Z DBG Request completed action="handle matrix event" duration=11.726592 event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message method=GET response_length=71983 response_mime=application/octet-stream room_id=!HXapaLKNFEXfapGIYO:nop.chat status_code=200 thread_id=2404118289665795 url=https://nop.chat/_matrix/media/v3/download/nop.chat/xMzUpLfACGKKDzqKhlsIDDcJ?allow_redirect=true&user_id=%40fbmessengerbot%3Anop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Request successful component=messagix duration=883.743921 method=POST url=https://www.messenger.com/ajax/mercury/upload.php?__a=1&__ccg=EXCELLENT&__comet_req=1&__csr=2E-uiawzGcoSibCCiJQBaZl4i24LFa9AKiRFAAWqGiBWq-XmGQozlJ4jB4pcJ5BEVDXQqi-aGhVEwGpAiummnGkyh5Yghb-iExGV4WhqhqiESykvAWRT8vABQaWWuDZ2btHF5mnCJKWh9HWyiamiHqi-QFqGZqqFG4dyO28yOk02Be057o6G0BA0jh7yokg4W0_E6C0qG0qZ08-78tg20yo9ag6C0x8_g0grwau0bWOw6ry6-06_m581vozx9017-0lC0Lyw7Kw17G02TC0ocr6UrgtQ0197w38o1O82aw6gg0Vm0i62l0cudAyEzxm1qw3s407T81pk0gq0g8to1440d3w8QDe0Saa48akm0skVqBt6aDy88S1i2Xg-gg081wby1To2OA4oeoS6E1UU1YK0oC7E6y0BawcK0gG04RUK0ym2Jw3zE2oa0RZwDCa1mK8wi8iFm0GUrwF7zoN1T51a1yw2sk2m59X5jWx4wf45y0828ohExll9u7ubnlS3W683ewVwkQ12zE10oSfyU1lHxNG1AwoE9olwoosEwco7m7E5y260r8w5i0cGwDLGOJ6e44E9DK9ixcMCfnxei0aiwe2dy45o6y1Ww7Mwq47bxSmE4J0tou4w2bU1487O09pwfi6UiwfG9o0U6A5awk80B69g3HOwbK0aM89yA0Mp3w2GE1K3a6E0Hy0su05d834w9W0nm0lt0bGu3x0WxC0h268F0d52UK09YU0hBw8W1UAwam3a0b3g0AUE&__dyn=7AzHK4HwBgDx-5Q1hyoyEqxd4Ag2owIxu13wFw_xebzEdF8vyUco2qwJyEiw9-1DwUx60xU8k0Z82_CxS320om78c87m2210wEwgo9oO0wE3Jx62G5Usw9m1cwLwBgK7o8417wc60D85m1mzXw8W58jwGzEjxq1NxGm2SUbElxm1HzEjUlwhEe88o4qdx_wHwf-2CVEbUGdwb61jg2cwMwhU9UK2K362-2B0LwnU8oC1Iwqo5u3a3G3W4VU-18xW1fy8bU&__hs=19853.HYP%3Amessengerdotcom_comet_pkg.2.1..0.1&__hsi=7367319894987174136&__req=18&__rev=1013419040&__s=uzz3hh%3Af6ledh%3Ajriwdy&__spin_b=trunk&__spin_r=1013419040&__spin_t=1715337833&__user=649129456&av=649129456&dpr=1&fb_dtsg=NAcM0o3LSdET6gVr9HAmusnMhx3anHx3d4CqLzE8cKdsVkAqT0qRvAQ%3A11%3A1710329737&jazoest=25485&lsd=Vc7-kX132JjngClyQVqjZQ user_id=@coolbug:nop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Failed upload metadata action="handle matrix event" event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message file_name=1000026225.png is_voice_clip=false mime_type=image/png room_id=!HXapaLKNFEXfapGIYO:nop.chat thread_id=2404118289665795
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z ERR Got please reload page error while converting message, reloading page in background error="failed to upload media: error in mercury upload: 1357004: Please try closing and re-opening your browser window." action="handle matrix event" event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message room_id=!HXapaLKNFEXfapGIYO:nop.chat thread_id=2404118289665795
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z ERR Failed to convert message error="refresh error; please retry in a few minutes" action="handle matrix event" event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message room_id=!HXapaLKNFEXfapGIYO:nop.chat thread_id=2404118289665795
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z ERR Sending message metrics for event error="refresh error; please retry in a few minutes" event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message handling_step="Error converting" room_id=!HXapaLKNFEXfapGIYO:nop.chat sender=@coolbug:nop.chat thread_id=2404118289665795
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Frame websocket read pump exiting 0xc0007fc0d0 component=whatsmeow sublogger=Socket user_id=@coolbug:nop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Sent message checkpoint message_checkpoint={"event_id":"$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4","event_type":"m.room.message","info":"refresh error; please retry in a few minutes","message_type":"m.image","reported_by":"BRIDGE","retry_num":0,"room_id":"!HXapaLKNFEXfapGIYO:nop.chat","status":"PERM_FAILURE","step":"REMOTE","timestamp":1715590579637}
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Closing handler queue loop component=whatsmeow user_id=@coolbug:nop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Connecting to Meta user_id=@coolbug:nop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Encrypted event successfully event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message expected_index=5 handling_step="Error converting" message_index=5 room_id=!HXapaLKNFEXfapGIYO:nop.chat sender=@coolbug:nop.chat session_id=ijudzfojpX5UKEDVTSLYS5TWs0efENuP/a+H877ZS/s thread_id=2404118289665795
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Request completed duration=3.796118 event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message handling_step="Error converting" method=PUT req_body={"typing":false} req_id=21496 response_length=2 response_mime=application/json room_id=!HXapaLKNFEXfapGIYO:nop.chat sender=@coolbug:nop.chat status_code=200 thread_id=2404118289665795 url=https://nop.chat/_matrix/client/v3/rooms/%21HXapaLKNFEXfapGIYO:nop.chat/typing/@fbmessengerbot:nop.chat?user_id=%40fbmessengerbot%3Anop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Request completed duration=35.095786 event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message handling_step="Error converting" method=PUT req_body={"algorithm":"m.megolm.v1.aes-sha2","ciphertext":"AwgFEtAG1ZXXaAxIy/zxkK5EQfTtMGBsR6Gl+JT8RxDTSW9WH923fB7YUXdA+IDZaw23y7MDs1meXvazwYHcEntA7nXtCA67xHqAnlViPOKJMOh5cvsx63gr5lGgha+ORl74CeoaMl35YZ6mvV4V38SifJpHCbedq6C8V93WJKoBuo0tRRqCm9wTKPgCMkUXWsJ1KYRkKBGSbfjXjfeG7gv8G64gcQivfoR78uiWPj4s8qOKTXIDZeiwYL9Sy1bV+K0GnE5sTp//xl5XOAztCGkCUKzubh8oj5Rnk0AojAI9PWn2Bb9pIcENS1NwBcVmJDOwIyraDEq4TFkSRnfjF4VtwpZ4vb/v73fxUpVisQnMNVh9cvwuQXp90x9hBArmAc9vFWEUHKgnR5e6xLW4wLmZgKTeL3GjUjRgEAUifhgI6weNtRN9tKx9pWImW/DbvTr8U6A6KIKHUN9pRmH3LCaLFBLjcbjKxKOJnHOkNxB9th1oJ/Z9f8YDtzN4h6OgM5UyMa3l+5EEfBmRcud7Up5k5LHRHMaCk3ORI6WqZbFRQTxlzPoaefB55htRiKr4VdZmTwvqBWAH64kU31UsYMtrQm8xHqxEw5xfYe9NjMNefkl+n5NX7u2bIvslwB5cv4nJhuzm+kLPeww5IWx955frcMW6WXpOMq4IkeCcb0205F2ewDYYt7CdDqwxxqlXp0R1WdQyC9vfyEkx0awo2xQe5Q142/hu/jCyiavjjZsnTIf9HhMtDMWaaTLh2HX/CVuzaKkLd8S/FwIzC/Kaxl8lzWar9JMAnKgk+b5X6YIj4WHwpqfR83QVpql/NReRgW0v7Hv8oXL3OShnHxEsSqefgAlsxTv79YNROMTVticT4WfiJRF9bshb+tDjFPmvPjZj4ARArvaHiSjaCNlOwvKbDwGmZUaQ7o/jobV/ri8gHDD2k0CjCwjJo3EaSVOl2BbmWLFaTNhNxWwShtZAgmaV4fTxvn+SImqLtP4mV1mrvWPrj3ZS7soWaNUyzJ4Il2S+MgBD9auamj4asJDaHZGmvtVrfx63VfYCI/xqgx3xWQL8vTNJquUrVndhHy2OI/Cn6BAy7zn8F1x0LZPzR+a+Rhmbra9U0BBOIS+zIlsKZvVgtXXA393jOUAUNpRg0PjlDWjVjs9dVle6IxMybbfcO3vUQcjwI9rtsU64eV/jAqHjY7DAwkaFEqqhj5V1ptvKdMB0Kj3NKUrjMg0","device_id":"EPZQITVKRM","m.relates_to":{"m.in_reply_to":{"event_id":"$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4"}},"sender_key":"GLFs9SnxULStifrQeQdxljwEE94herHbVod5VW1xH2Y","session_id":"ijudzfojpX5UKEDVTSLYS5TWs0efENuP/a+H877ZS/s"} req_id=21497 response_length=59 response_mime=application/json room_id=!HXapaLKNFEXfapGIYO:nop.chat sender=@coolbug:nop.chat status_code=200 thread_id=2404118289665795 url=https://nop.chat/_matrix/client/v3/rooms/%21HXapaLKNFEXfapGIYO:nop.chat/send/m.room.encrypted/mautrix-go_1715590579643945209_4?user_id=%40fbmessengerbot%3Anop.chat
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Timings for event event_id=$LTRz-AsMFklo6S_2P1eaCjuYcB4av9VLVLLzh-80dP4 event_type=m.room.message handling_step="Error converting" room_id=!HXapaLKNFEXfapGIYO:nop.chat sender=@coolbug:nop.chat thread_id=2404118289665795 timings={"bridge":{"decrypt":"3.9ms","init_receive":"44.4ms","queue":"0s","total_hs_to_portal":"48.5ms"},"portal":{"convert":"0s","implicit_rr":"120ns","preproc":"34.074µs","total_send":"0s"}}
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Starting handling of transaction content={"pdu":1,"unstable_edu":0,"unstable_to_device":0} transaction_id=203834
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Finished dispatching events from transaction transaction_id=203834
May 13 16:56:19 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:19Z DBG Sent message checkpoint message_checkpoint={"event_id":"$JNUsbD83BId1B-Ob5UOvvI00P9NaBpmTf8BCDfHQX4A","event_type":"m.room.encrypted","reported_by":"BRIDGE","retry_num":0,"room_id":"!HXapaLKNFEXfapGIYO:nop.chat","status":"SUCCESS","step":"BRIDGE","timestamp":1715590579694}
May 13 16:56:20 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:20Z WRN HTTP request was redirected component=messagix prev_url=https://www.messenger.com/ url=https://www.messenger.com/t/2404118289665795/ user_id=@coolbug:nop.chat
May 13 16:56:23 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:23Z DBG Request successful component=messagix duration=3924.44879 method=GET url=https://www.messenger.com/ user_id=@coolbug:nop.chat
May 13 16:56:23 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:23Z WRN Version ID not found in index page component=messagix user_id=@coolbug:nop.chat
May 13 16:56:23 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:23Z DBG Starting handling of transaction content={"pdu":1,"unstable_edu":0,"unstable_to_device":0} transaction_id=203835
May 13 16:56:23 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:23Z DBG Finished dispatching events from transaction transaction_id=203835
May 13 16:56:23 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:23Z DBG Decrypting received event event_id=$YA94yidqEnYVQHVpiMTrNnZfFtO-cfGFbw8vC2_pQ7A session_id=0U4hwy2DpPJZNiNEhJVBf06IbHJs7TdblLvRWC9S4yw
May 13 16:56:23 atlas systemd-matrix.bridge.meta[13253]: 2024-05-13T08:56:23Z DBG Sent message checkpoint message_checkpoint={"event_id":"$YA94yidqEnYVQHVpiMTrNnZfFtO-cfGFbw8vC2_pQ7A","event_type":"m.room.encrypted","reported_by":"BRIDGE","retry_num":0,"room_id":"!HXapaLKNFEXfapGIYO:nop.chat","status":"SUCCESS","step":"BRIDGE","timestamp":1715590583794}
tulir commented 3 months ago

As the error says, you just need to retry in a few minutes

Automatic periodic refreshing will be added later