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:
Message received:
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.
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 finishDuring 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.
