PINE64   ›   PinePhone   ›   General Discussion on PinePhone   ›   Troubleshooting and Understanding MMS

Troubleshooting and Understanding MMS
guy
Pine Initiate
**
Joined: Jan 2018
Posts: 3

Reputation: 1
#42
07-23-2022, 01:53 PM
After a little more digging, I found that when I send an MMS to myself, the messages in .mms/modemmanager/ are in fact sent, deleted, then downloaded only partially by mmsd-tng:

Message sent:
Code:
Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:time_to_str() Time 1658600634, Human Format 2022-07-23T14:23:54-0400 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_create_smil() This MMS has content other than text Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_create_smil() This MMS only has Images, Videos, or plaintext Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_create_smil() This MMS has images or videos Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:send_message_get_args() Attaching SMIL Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:send_message_get_attachments() number_of_attachments 1 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:send_message_get_attachments() Content ID: image2-00001.JPG, MIME Type: image/jp> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:send_message_get_attachments() Total attachment size: 28212 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:send_message_get_attachments() Maximum Attachment Total Size (in bytes): 1100000 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/store.c:mms_store_file() pathname /home/mobian/.mms/modemmanager/6d43ce8214395145fa1b1a612> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_message_register() message registered /org/ofono/mms/modemmanager/6d43ce8214> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:append_message() appending pdu path /home/mobian/.mms/modemmanager/6d43ce8214395> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:append_attachment_properties() Need to fix content type Content-Type: "image/jpe> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:append_attachment_properties() fixed content-type: image/jpeg Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:append_msg_attachments() Attaching Smil! Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 setup 0 active 0 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 waiting for 20 seconds Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:23:54 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() active and context_active, bearer_notify Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=1 iface=wwan0 proxy=(n> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() interface wwan0 proxy (null) Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() Proxy is set to 0 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location http://63.55.99.247/servlets/mms?message-id= Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() resolve_host_ares Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() Binding resolver queries to interface wwan0 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() All Nameservers: 2001:4888:16:ff00:1e1:d::,2001:4888:17:ff00> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:resolve_callback() Found IP for '63.55.99.247': 63.55.99.247 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:resolve_host() Using URI for request: http://63.55.99.247/servlets/mms?message-i> Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() host: 63.55.99.247, port: -1 Jul 23 14:23:54 mobian mmsdtng[8614]: GSocketClient: Starting new address enumeration Jul 23 14:23:54 mobian mmsdtng[8614]: GSocketClient: Address enumeration succeeded Jul 23 14:23:54 mobian mmsdtng[8614]: GSocketClient: Starting TCP connection attempt Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket 452 Binding to wwan0 length 5 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket is bound to wwan0 Jul 23 14:23:54 mobian mmsdtng[8614]: GSocketClient: TCP connection successful Jul 23 14:23:54 mobian mmsdtng[8614]: GSocketClient: Starting application layer connection Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:18:23 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > POST /servlets/mms HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600391 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929100), SoupMessage 1 (0xaaab111f68c0), SoupSocket 1 (0xaaa> Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: mms.vtext.com Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Content-Type: application/vnd.wap.mms-message Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 500 Internal Server Error Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600391 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 1 (0xaaab111f68c0) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:19:51 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: close Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > GET /servlets/mms?message-id= HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600397 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929100), SoupMessage 2 (0xaaab111f6aa0), SoupSocket 2 (0xaaa> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept: */* Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: 63.55.99.247 Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 404 Not Found Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600397 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 2 (0xaaab111f6aa0) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:19:57 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > GET /servlets/mms?message-id= HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600429 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929220), SoupMessage 1 (0xaaab111f6c80), SoupSocket 1 (0xaaa> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept: */* Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: 63.55.128.103 Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 404 Not Found Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600429 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 1 (0xaaab111f6c80) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:20:29 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > POST /servlets/mms HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600495 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929340), SoupMessage 1 (0xaaab111f6c80), SoupSocket 1 (0xaaa> Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: mms.vtext.com Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Content-Type: application/vnd.wap.mms-message Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: GSocketClient: Connection successful! Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 500 Internal Server Error Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600495 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 1 (0xaaab111f6c80) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:21:35 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: close Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > GET /servlets/mms?message-id= HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600501 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929340), SoupMessage 2 (0xaaab111f6aa0), SoupSocket 2 (0xfff> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept: */* Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: 63.55.99.247 Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 404 Not Found Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600501 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 2 (0xaaab111f6aa0) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:21:41 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > GET /servlets/mms?message-id= HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600506 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929340), SoupMessage 3 (0xaaab111f68c0), SoupSocket 3 (0xaaa> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept: */* Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: 63.55.128.103 Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 404 Not Found Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600506 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 3 (0xaaab111f68c0) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:21:46 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > POST /servlets/mms HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600550 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug: SoupSession 1 (0xaaab11929460), SoupMessage 1 (0xaaab111f68c0), SoupSocket 1 (0xaaa> Jul 23 14:23:54 mobian mmsdtng[8614]: > Host: mms.vtext.com Jul 23 14:23:54 mobian mmsdtng[8614]: > X-VzW-MDN: <redacted> Jul 23 14:23:54 mobian mmsdtng[8614]: > Content-Type: application/vnd.wap.mms-message Jul 23 14:23:54 mobian mmsdtng[8614]: > Accept-Encoding: gzip, deflate Jul 23 14:23:54 mobian mmsdtng[8614]: > Connection: Keep-Alive Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: < HTTP/1.1 500 Internal Server Error Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug-Timestamp: 1658600550 Jul 23 14:23:54 mobian mmsdtng[8614]: < Soup-Debug: SoupMessage 1 (0xaaab111f68c0) Jul 23 14:23:54 mobian mmsdtng[8614]: < Server: Apache Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Type: text/html Jul 23 14:23:54 mobian mmsdtng[8614]: < Date: Sat, 23 Jul 2022 18:22:29 GMT Jul 23 14:23:54 mobian mmsdtng[8614]: < Connection: close Jul 23 14:23:54 mobian mmsdtng[8614]: < Content-Length: 0 Jul 23 14:23:54 mobian mmsdtng[8614]:   Jul 23 14:23:54 mobian mmsdtng[8614]: > GET /servlets/mms?message-id= HTTP/1.1 Jul 23 14:23:54 mobian mmsdtng[8614]: > Soup-Debug-Timestamp: 1658600634 Jul 23 14:23:54 mobian mmsdtng[8614]: Fail to get data (http status = 404) Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:on_message_done() status: 404 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:on_message_done() data size = 0 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:on_message_done() request->result_cb=0xaaaada31fe90 vs. retrieve_conf=0xaaaada31> Jul 23 14:23:54 mobian mmsdtng[8614]: Fail to get data (http status = 404) Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:on_message_done() On attempt 2 Jul 23 14:23:54 mobian mmsdtng[8614]: ../src/service.c:on_message_done() Requeued Message Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:service_activate_bearer() Retrying Modem Bearer Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 setup 0 active 1 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location http://63.55.128.103/servlets/mms?message-id= Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() resolve_host_ares Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() Binding resolver queries to interface wwan0 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() All Nameservers: 2001:4888:16:ff00:1e1:d::,2001:4888:17:ff00> Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:resolve_callback() Found IP for '63.55.128.103': 63.55.128.103 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:resolve_host() Using URI for request: http://63.55.128.103/servlets/mms?message-> Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() host: 63.55.128.103, port: -1 Jul 23 14:23:57 mobian mmsdtng[8614]: GSocketClient: Starting new address enumeration Jul 23 14:23:57 mobian mmsdtng[8614]: GSocketClient: Address enumeration succeeded Jul 23 14:23:57 mobian mmsdtng[8614]: GSocketClient: Starting TCP connection attempt Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket 452 Binding to wwan0 length 5 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket is bound to wwan0 Jul 23 14:23:57 mobian mmsdtng[8614]: GSocketClient: TCP connection successful Jul 23 14:23:57 mobian mmsdtng[8614]: GSocketClient: Starting application layer connection Jul 23 14:23:57 mobian mmsdtng[8614]: GSocketClient: Connection successful! Jul 23 14:23:57 mobian mmsdtng[8614]: Fail to get data (http status = 404) Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:on_message_done() status: 404 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:on_message_done() data size = 0 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:on_message_done() request->result_cb=0xaaaada31fe90 vs. retrieve_conf=0xaaaada31> Jul 23 14:23:57 mobian mmsdtng[8614]: Fail to get data (http status = 404) Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:on_message_done() On attempt 2 Jul 23 14:23:57 mobian mmsdtng[8614]: ../src/service.c:on_message_done() Requeued Message Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:service_retry_process_request_queue() Retrying Queue Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:24:00 mobian mmsdtng[8614]: [56B blob data] Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:24:00 mobian mmsdtng[8614]: [31B blob data] Jul 23 14:24:00 mobian mmsdtng[8614]: Failed to resolve DNS Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() On attempt 3 Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() Had Max attempts Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:deactivate_bearer() service 0xaaab11164e70 Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:deactivate_bearer() service 0xaaab11164e70 Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() checking for failure Jul 23 14:24:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() No failures Jul 23 14:24:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=0 iface=wwan0 proxy=(n> Jul 23 14:24:00 mobian mmsdtng[8614]: Unable to create GET message Jul 23 14:24:02 mobian mmsdtng[8614]: ../src/service.c:service_retry_process_request_queue() Retrying Queue Jul 23 14:24:02 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:24:05 mobian mmsdtng[8614]: ../src/service.c:service_retry_process_request_queue() Retrying Queue Jul 23 14:24:05 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:service_activate_bearer() Retrying Modem Bearer Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 setup 0 active 0 Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 waiting for 20 seconds Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() active and context_active, bearer_notify Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=1 iface=wwan0 proxy=(n> Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() interface wwan0 proxy (null) Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() Proxy is set to 0 Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:24:31 mobian mmsdtng[8614]: Unable to init new uri: Jul 23 14:24:31 mobian mmsdtng[8614]: Failed to resolve DNS Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() On attempt 3 Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() Had Max attempts Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:deactivate_bearer() service 0xaaab11164e70 Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:deactivate_bearer() service 0xaaab11164e70 Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() checking for failure Jul 23 14:24:31 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() No failures Jul 23 14:24:31 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=0 iface=wwan0 proxy=(n> Jul 23 14:24:31 mobian mmsdtng[8614]: Unable to create GET message Jul 23 14:24:36 mobian mmsdtng[8614]: ../src/service.c:service_retry_process_request_queue() Retrying Queue Jul 23 14:24:36 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:service_activate_bearer() Retrying Modem Bearer Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 setup 0 active 0 Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 waiting for 20 seconds Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:26:00 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() active and context_active, bearer_notify Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=1 iface=wwan0 proxy=(n> Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() interface wwan0 proxy (null) Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() Proxy is set to 0 Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location 1.2.840.113549.1.1.11 Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:26:00 mobian mmsdtng[8614]: Unable to init new uri: 1.2.840.113549.1.1.11 Jul 23 14:26:00 mobian mmsdtng[8614]: Failed to resolve DNS Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() On attempt 1 Jul 23 14:26:00 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() Requeued Message Jul 23 14:26:00 mobian mmsdtng[8614]: Unable to create GET message Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:service_retry_process_request_queue() Retrying Queue Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location (null) Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() resolve_host_ares Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() Binding resolver queries to interface wwan0 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() All Nameservers: 2001:4888:16:ff00:1e1:d::,2001:4888:17:ff00> Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:resolve_callback() Found IP for 'mms.vtext.com': 63.55.1.87 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:resolve_host() Using URI for request: http://63.55.1.87/servlets/mms Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() host: mms.vtext.com, port: -1 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:process_request() POST 0 bytes to http://mms.vtext.com/servlets/mms Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:process_request() Sending </home/mobian/.cache/mmstng/modemmanager/post-rsp.ZEEO> Jul 23 14:26:05 mobian mmsdtng[8614]: GSocketClient: Starting new address enumeration Jul 23 14:26:05 mobian mmsdtng[8614]: GSocketClient: Address enumeration succeeded Jul 23 14:26:05 mobian mmsdtng[8614]: GSocketClient: Starting TCP connection attempt Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket 485 Binding to wwan0 length 5 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket is bound to wwan0 Jul 23 14:26:05 mobian mmsdtng[8614]: GSocketClient: TCP connection successful Jul 23 14:26:05 mobian mmsdtng[8614]: GSocketClient: Starting application layer connection Jul 23 14:26:05 mobian mmsdtng[8614]: GSocketClient: Connection successful! Jul 23 14:26:05 mobian mmsdtng[8614]: Fail to get data (http status = 500) Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:on_message_done() status: 500 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:on_message_done() data size = 0 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:on_message_done() request->result_cb=0xaaaada31a1c0 vs. retrieve_conf=0xaaaada31> Jul 23 14:26:05 mobian mmsdtng[8614]: Fail to get data (http status = 500) Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:on_message_done() On attempt 1 Jul 23 14:26:05 mobian mmsdtng[8614]: ../src/service.c:on_message_done() Requeued Message Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:service_retry_process_request_queue() Retrying Queue Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location (null) Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() resolve_host_ares Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() Binding resolver queries to interface wwan0 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() All Nameservers: 2001:4888:16:ff00:1e1:d::,2001:4888:17:ff00> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_callback() Found IP for 'mms.vtext.com': 63.55.1.87 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host() Using URI for request: http://63.55.1.87/servlets/mms Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() host: mms.vtext.com, port: -1 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:process_request() POST 28619 bytes to http://mms.vtext.com/servlets/mms Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:process_request() Sending </home/mobian/.cache/mmstng/modemmanager/post-rsp.9T0L> Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Starting new address enumeration Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Address enumeration succeeded Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Starting TCP connection attempt Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket 485 Binding to wwan0 length 5 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket is bound to wwan0 Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: TCP connection successful Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Starting application layer connection Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Connection successful! Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:response_got_chunk() Got chunk: 74 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:on_message_done() status: 200 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:on_message_done() data size = 74 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:on_message_done() request->result_cb=0xaaaada31a1c0 vs. retrieve_conf=0xaaaada31> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() about to check well known Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() about to extract short Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() octet 129 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() MMS_MESSAGE_TYPE_SEND_CONF Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x18 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x18 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() claimed len: 41 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() val: <redacted> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x18 was success Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x0D Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x0D Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x0D was success Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x0B Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x0B Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() claimed len: 25 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() val: <redacted> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x0B was success Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x12 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x12 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x12 was success Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:result_request_send_conf() response status : 128 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:emit_msg_status_changed() Emitting status of /org/ofono/mms/modemmanager/6d43ce8> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location http://63.55.99.247/servlets/mms?message-id= Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() resolve_host_ares Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() Binding resolver queries to interface wwan0 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() All Nameservers: 2001:4888:16:ff00:1e1:d::,2001:4888:17:ff00> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_callback() Found IP for '63.55.99.247': 63.55.99.247 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:resolve_host() Using URI for request: http://63.55.99.247/servlets/mms?message-i> Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() host: 63.55.99.247, port: -1 Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Starting new address enumeration Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Address enumeration succeeded Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Starting TCP connection attempt Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket 452 Binding to wwan0 length 5 Jul 23 14:26:11 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket is bound to wwan0 Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: TCP connection successful Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Starting application layer connection Jul 23 14:26:11 mobian mmsdtng[8614]: GSocketClient: Connection successful! Jul 23 14:26:12 mobian mmsdtng[8614]: Fail to get data (http status = 404) Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:on_message_done() status: 404 Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:on_message_done() data size = 0 Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:on_message_done() request->result_cb=0xaaaada31fe90 vs. retrieve_conf=0xaaaada31> Jul 23 14:26:12 mobian mmsdtng[8614]: Fail to get data (http status = 404) Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:on_message_done() On attempt 3 Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:on_message_done() Had Max attempts Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:deactivate_bearer() service 0xaaab11164e70 Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:deactivate_bearer() service 0xaaab11164e70 Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() checking for failure Jul 23 14:26:12 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() No failures Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=0 iface=wwan0 proxy=(n> Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:handle_method_call_message() Deleting Message path /org/ofono/mms/modemmanager/6> Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:mms_message_unregister() message unregistered /org/ofono/mms/modemmanager/6d43ce> Jul 23 14:26:12 mobian mmsdtng[8614]: ../src/service.c:handle_method_call_message() Successfully Deleted Message!

Message received:
Code:
Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:cb_dbus_signal_sms_added() Got new SMS Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:mmsd_process_sms() Received SMS WAP! Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_push_notify() Processing push notify Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_push_notify() pdu 0xffffb00e1f20 len 119 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_push_notify() <redacted>> Jul 23 14:26:13 mobian mmsdtng[8614]: Body Length: 113 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_push_notify() Content Type: application/vnd.wap.mms-message Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/store.c:mms_store() pathname /home/mobian/.mms/modemmanager/6ffe44ce107ed148061bcba1bef6ee> Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() about to check well known Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() about to extract short Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() octet 130 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_message_decode() MMS_MESSAGE_TYPE_NOTIFICATION_IND Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x18 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x18 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() claimed len: 27 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() val: <redacted> Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x18 was success Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x0D Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x0D Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x0D was success Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x0A Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x0A Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x0A was success Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x0E Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x0E Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x0E was success Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x08 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x08 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x08 was success Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x03 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x03 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() claimed len: 43 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:decode_text() val: http://63.55.1.87/servlets/mms?message-id= Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x03 was success Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() saw header of type 0x09 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() running handler for type 0x09 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:extract_from() Successfully decoded text! Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/mmsutil.c:mms_parse_headers() handler for type 0x09 was success Jul 23 14:26:13 mobian mmsdtng[8614]: MMS transaction id: <redacted> Jul 23 14:26:13 mobian mmsdtng[8614]: MMS version: 1.0 Jul 23 14:26:13 mobian mmsdtng[8614]: Subject: (null) Jul 23 14:26:13 mobian mmsdtng[8614]: Class: Personal Jul 23 14:26:13 mobian mmsdtng[8614]: Size: 28544 Jul 23 14:26:13 mobian mmsdtng[8614]: Expiry: 2022-07-28T14:26:12-0400 Jul 23 14:26:13 mobian mmsdtng[8614]: Location: http://63.55.1.87/servlets/mms?message-id= Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:time_to_str() Time 1659032772, Human Format 2022-07-28T14:26:12-0400 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 setup 0 active 0 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:activate_bearer() service 0xaaab11164e70 waiting for 20 seconds Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Setting Context... Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_mmsc() service 0xaaab11164e70 mmsc http://mms.vtext.com/servlets> Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Max number of bearers: 1 Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() Current Context APN: vzwapp, mmsd-tng settings MMS APN: v> Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() You are connected to the correct APN! Enabling context... Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_apn() Service APN Set to vzwapp Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_set_resolvers() service 0xaaab11164e70 resolvers: ipv4: 198.224.190.> Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:set_context() DNS from modemmanager IPv6: 2001:4888:16:ff00:1e1:d::,200> Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() At Bearer Handler: path /org/freedesktop/ModemManager1> Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:bearer_handler() active and context_active, bearer_notify Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() service=0xaaab11164e70 active=1 iface=wwan0 proxy=(n> Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() interface wwan0 proxy (null) Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:mms_service_bearer_notify() Proxy is set to 0 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() service 0xaaab11164e70 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:process_request_queue() location http://63.55.128.103/servlets/mms?message-id= Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:resolve_host() No active proxy Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() resolve_host_ares Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() Binding resolver queries to interface wwan0 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:resolve_host_ares() All Nameservers: 2001:4888:16:ff00:1e1:d::,2001:4888:17:ff00> Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:resolve_callback() Found IP for '63.55.128.103': 63.55.128.103 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:resolve_host() Using URI for request: http://63.55.128.103/servlets/mms?message-> Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:create_new_web_message() host: 63.55.128.103, port: -1 Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:mmsd_process_sms() Deleting SMS Now... Jul 23 14:26:13 mobian mmsdtng[8614]: GSocketClient: Starting new address enumeration Jul 23 14:26:13 mobian mmsdtng[8614]: GSocketClient: Address enumeration succeeded Jul 23 14:26:13 mobian mmsdtng[8614]: GSocketClient: Starting TCP connection attempt Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket 486 Binding to wwan0 length 5 Jul 23 14:26:13 mobian mmsdtng[8614]: ../src/service.c:soupmessage_network_event_cb() Socket is bound to wwan0 Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:cb_sms_delete_finish() Couldn't delete SMS - error: GDBus.Error:org.fre> Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:cb_sms_delete_finish() Trying to delete SMS again.... Jul 23 14:26:13 mobian mmsdtng[8614]: GSocketClient: TCP connection successful Jul 23 14:26:13 mobian mmsdtng[8614]: GSocketClient: Starting application layer connection Jul 23 14:26:13 mobian mmsdtng[8614]: GSocketClient: Connection successful! Jul 23 14:26:13 mobian mmsdtng[8614]: ../plugins/modemmanager.c:cb_sms_delete_finish_retry() Message delete finish

During these events I saw the mentioned outgoing file (/home/mobian/.mms/modemmanager/6d43ce8214395) after successfully being sent it was removed, then the incoming file (/home/mobian/.mms/modemmanager/6ffe44ce107ed148061bcba1bef6ee) was created and then stayed there.  The outgoing message has the correct size (the size of the picture I sent) and the incoming message shows the correct size n the log (Jul 23 14:26:13 mobian mmsdtng[8614]: Size: 28544) so I assume the upload went ok.  However, the incoming file as only a few bytes, 113 to be exact when I check it on the filesystem.

I wonder if the message is getting deleted by mmsd-tng on Verizon's servers when the download attempt fails.
  Reply


Messages In This Thread
Troubleshooting and Understanding MMS - by epiii2 - 05-31-2022, 07:44 PM
RE: Troubleshooting and Understanding MMS - by guy - 07-23-2022, 01:53 PM

Possibly Related Threads…
Thread Author Replies Views Last Post
Big Grin troubleshooting Chatty / modem? no SMS outbound vap0rtranz 5 9,132 02-14-2022, 12:15 PM
Last Post: vap0rtranz

Forum Jump:


Users browsing this thread: 1 Guest(s)