search

signalapp / Signal-Android

A private messenger for Android.
https://signal.org
GNU Affero General Public License v3.0
25.62k stars 6.15k forks source link

Error Sending Message when trying to use unencrypted mms #192

Closed wastedimage closed 11 years ago

wastedimage commented 11 years ago

Any text involving mms attempts for a bit and then results in an "Error Sending Message". Normal non-mms messages seem to work fine. This bug occurs every time and is very reproducible. This is occurring in 0.9.9.3 on and at&t htc one. This error seems to be the issue.

[ 06-09 10:57:29.721 1819: 1840 E/DatabaseUtils ] Writing exception to parcel java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.sqlite.SQLiteProgram.bind(SQLiteProgram.java:212) at android.database.sqlite.SQLiteProgram.bindString(SQLiteProgram.java:166) at android.database.sqlite.SQLiteProgram.bindAllArgsAsStrings(SQLiteProgram.java:200) at android.database.sqlite.SQLiteDirectCursorDriver.query(SQLiteDirectCursorDriver.java:68) at android.database.sqlite.SQLiteDatabase.rawQueryWithFactory(SQLiteDatabase.java:1370) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:421) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:315) at com.android.providers.telephony.TelephonyProvider.query(TelephonyProvider.java:1415) at android.content.ContentProvider.query(ContentProvider.java:673) at android.content.ContentProvider$Transport.query(ContentProvider.java:210) at android.content.ContentProviderNative.onTransact(ContentProviderNative.java:143) at android.os.Binder.execTransact(Binder.java:367) at dalvik.system.NativeStart.run(Native Method)

[ 06-09 10:57:29.721 180: 289 D/memalloc ] ion: Freeing buffer base:0x4e98c000 size:8355840 fd:155

[ 06-09 10:57:29.721 180: 289 D/memalloc ] ion: Unmapping buffer base:0x4e98c000 size:8355840

[ 06-09 10:57:29.721 1357: 6433 W/InputMethodManagerService ] Window already focused, ignoring focus gain of: com.android.internal.view.IInputMethodClient$Stub$Proxy@42220590 attribute=android.view.inputmethod.EditorInfo@4210f190

[ 06-09 10:57:29.731 180: 289 D/memalloc ] ion: Freeing buffer base:0x40121000 size:4096 fd:156

[ 06-09 10:57:29.731 180: 289 D/memalloc ] ion: Unmapping buffer base:0x40121000 size:4096

[ 06-09 10:57:29.731 2637: 2823 W/MmsCommunication ] java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:170) at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:140) at android.content.ContentProviderProxy.query(ContentProviderNative.java:423) at android.content.ContentResolver.query(ContentResolver.java:375) at android.content.ContentResolver.query(ContentResolver.java:318) at org.thoughtcrime.securesms.database.MmsDatabase.getCarrierMmsInformation(MmsDatabase.java:584) at org.thoughtcrime.securesms.mms.MmsCommunication.getMmsConnectionParameters(MmsCommunication.java:92) at org.thoughtcrime.securesms.mms.MmsSendHelper.hasNecessaryApnDetails(MmsSendHelper.java:119) at org.thoughtcrime.securesms.ConversationActivity$7.doInBackground(ConversationActivity.java:530) at org.thoughtcrime.securesms.ConversationActivity$7.doInBackground(ConversationActivity.java:527) at android.os.AsyncTask$2.call(AsyncTask.java:287) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:305) at java.util.concurrent.FutureTask.run(FutureTask.java:137) at android.os.AsyncTask$SerialExecutor$1.run(AsyncTask.java:230) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1076) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:569) at java.lang.Thread.run(Thread.java:864) [ 06-09 10:57:29.771 1357: 1445 D/memalloc ] ion: Unmapping buffer base:0x418e3000 size:4096

[ 06-09 10:57:29.771 180: 7560 D/memalloc ] ion: Allocated buffer base:0x47e8b000 size:8355840 fd:69

[ 06-09 10:57:29.771 180: 7560 D/memalloc ] ion: Allocated buffer base:0x40016000 size:4096 fd:70

[ 06-09 10:57:29.771 180: 929 D/memalloc ] ion: Freeing buffer base:0x49f6b000 size:8077312 fd:44

[ 06-09 10:57:29.771 180: 929 D/memalloc ] ion: Unmapping buffer base:0x49f6b000 size:8077312

[ 06-09 10:57:29.771 180: 929 D/memalloc ] ion: Freeing buffer base:0x40037000 size:4096 fd:50

[ 06-09 10:57:29.771 180: 929 D/memalloc ] ion: Unmapping buffer base:0x40037000 size:4096

[ 06-09 10:57:29.771 1357: 1445 D/memalloc ] ion: Unmapping buffer base:0x7983f000 size:8077312

[ 06-09 10:57:29.771 1357: 1445 D/memalloc ] ion: Unmapping buffer base:0x418e4000 size:4096

[ 06-09 10:57:29.771 2637: 2637 D/memalloc ] ion: Mapped buffer base:0x6ff4f000 size:8355840 offset:0 fd:81

[ 06-09 10:57:29.771 2637: 2637 D/memalloc ] ion: Mapped buffer base:0x699e0000 size:4096 offset:0 fd:85

[ 06-09 10:57:29.791 180: 289 D/memalloc ] ion: Freeing buffer base:0x4a783000 size:8077312 fd:123

[ 06-09 10:57:29.791 180: 289 D/memalloc ] ion: Unmapping buffer base:0x4a783000 size:8077312

[ 06-09 10:57:29.791 180: 289 D/memalloc ] ion: Freeing buffer base:0x400bc000 size:4096 fd:124

[ 06-09 10:57:29.791 180: 289 D/memalloc ] ion: Unmapping buffer base:0x400bc000 size:4096

[ 06-09 10:57:29.811 2637: 2824 W/MmsSmsDatabase ] Executing query: SELECT _id, body, read, type, address, subject, thread_id, status, date_sent, date_received, m_type, msg_box, part_count, ct_l, tr_id, m_size, exp, st, transport_type FROM (SELECT DISTINCT date_sent * 1 AS date_sent, date * 1 AS date_received, _id, body, read, thread_id, type, address, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, 'sms' AS transport_type FROM sms WHERE (read = 0) UNION ALL SELECT DISTINCT date * 1000 AS date_sent, date_received * 1000 AS date_received, _id, body, read, thread_id, NULL AS type, address, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, 'mms' AS transport_type FROM mms WHERE (read = 0) ORDER BY date_received ASC)

moxie0 commented 11 years ago

It'd be helpful if you could post the section of the logcat where it attempts to send the message.

Those stack traces indicate that you have a device with locked APN details. In that case, we fall back to predefined APN defaults. Assuming your MCC+MNC is 310410, that will resolve to these APN details:

MMSC: http://mmsc.cingular.com/ Proxy: wireless.cingular.com Proxy Port: 80

Later the log should include information about what happened when TextSecure tried to connect to that MMSC.

wastedimage commented 11 years ago

This bit? [ 06-09 11:53:15.929 12055:12055 W/PartDatabase ] Wrote part to file: /data/data/org.thoughtcrime.securesms/app_parts/part-225791676.mms

[ 06-09 11:53:15.959 12055:12055 W/PartDatabase ] Inserted part at ID: 1

[ 06-09 11:53:15.959 12055:12055 W/MmsSmsDatabase ] Executing query: SELECT _id, body, type, thread_id, address, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, transport_type FROM (SELECT DISTINCT date_sent * 1 AS date_sent, date * 1 AS date_received, _id, body, read, thread_id, type, address, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, 'sms' AS transport_type FROM sms WHERE (thread_id = 1) UNION ALL SELECT DISTINCT date * 1000 AS date_sent, date_received * 1000 AS date_received, _id, body, read, thread_id, NULL AS type, address, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, 'mms' AS transport_type FROM mms WHERE (thread_id = 1) ORDER BY date_received DESC) LIMIT 1

[ 06-09 11:53:15.999 12055:12055 D/libc ] pt_debug : pthread_create->start_routine=0x40b9107d, tls=0x6d2b2f00, arg=0x6bff7400

[ 06-09 11:53:15.999 12055:12955 D/libc ] pt_debug : __thread_entry->func=0x40b9107d , tls=0x6d2b2f00, arg=0x6bff7400

[ 06-09 11:53:16.009 12055:12055 D/libc ] pt_debug : pthread_create->start_routine=0x40b9107d, tls=0x6d4b2f00, arg=0x696cc008

[ 06-09 11:53:16.009 12055:12956 D/libc ] pt_debug : __thread_entry->func=0x40b9107d , tls=0x6d4b2f00, arg=0x696cc008

[ 06-09 11:53:16.009 12055:12956 W/MmsSmsDatabase ] Executing query: SELECT _id, body, type, thread_id, address, subject, date_sent, date_received, m_type, msg_box, status, part_count, ct_l, tr_id, m_size, exp, st, transport_type FROM (SELECT DISTINCT date_sent * 1 AS date_sent, date * 1 AS date_received, _id, body, read, thread_id, type, address, subject, NULL AS m_type, NULL AS msg_box, status, NULL AS part_count, NULL AS ct_l, NULL AS tr_id, NULL AS m_size, NULL AS exp, NULL AS st, 'sms' AS transport_type FROM sms WHERE (thread_id = 1) UNION ALL SELECT DISTINCT date * 1000 AS date_sent, date_received * 1000 AS date_received, _id, body, read, thread_id, NULL AS type, address, NULL AS subject, m_type, msg_box, NULL AS status, part_count, ct_l, tr_id, m_size, exp, st, 'mms' AS transport_type FROM mms WHERE (thread_id = 1) ORDER BY date_received ASC)

[ 06-09 11:53:16.009 12055:12055 W/KeyUtil ] Checking session...

[ 06-09 11:53:16.009 12055:12055 W/LocalKeyRecord ] Checking: 1-local

[ 06-09 11:53:16.019 2009: 2023 D/CursorWindowStats ] Allocate Cursor Window larger than default value, size: 4194304, default:2097152

[ 06-09 11:53:16.039 12055:12547 W/EncryptingPartDatabase ] Getting part at: /data/data/org.thoughtcrime.securesms/app_parts/part-225791676.mms

[ 06-09 11:53:16.149 1110: 1116 E/ThermalDaemon ] notify PnPMgr CPU2 frequency recover to 1728000

[ 06-09 11:53:16.159 2009:32350 D/CursorWindowStats ] Allocate Cursor Window larger than default value, size: 4194304, default:2097152

[ 06-09 11:53:16.179 2009:14542 D/CursorWindowStats ] Allocate Cursor Window larger than default value, size: 4194304, default:2097152

[ 06-09 11:53:16.209 1357: 2798 D/Process ] killProcessQuiet, pid=11862

[ 06-09 11:53:16.209 1357: 2798 D/Process ] dalvik.system.VMStack.getThreadStackTrace(Native Method)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] java.lang.Thread.getStackTrace(Thread.java:599)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] android.os.Process.killProcessQuiet(Process.java:981)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] com.android.server.am.ActivityManagerService.updateOomAdjLocked(ActivityManagerService.java:16193)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] com.android.server.am.ActivityManagerService.removeContentProvider(ActivityManagerService.java:7382)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] android.app.ActivityManagerNative.onTransact(ActivityManagerNative.java:648)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] com.android.server.am.ActivityManagerService.onTransact(ActivityManagerService.java:1884)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] android.os.Binder.execTransact(Binder.java:367)

[ 06-09 11:53:16.209 1357: 2798 D/Process ] dalvik.system.NativeStart.run(Native Method)

[ 06-09 11:53:16.209 12055:12055 W/ImageSlide ] Got soft reference: null

[ 06-09 11:53:16.209 12055:12055 W/ImageSlide ] Got soft reference: null

[ 06-09 11:53:16.209 12055:12145 W/ImageSlide ] Got soft reference: null

[ 06-09 11:53:16.209 12055:12145 W/Slide ] Loading Part URI: content://org.thoughtcrime.provider.securesms/part/1

[ 06-09 11:53:16.209 12055:12145 W/PartDatabase ] Getting part at ID: 1

[ 06-09 11:53:16.209 12055:12145 W/EncryptingPartDatabase ] Getting part at: /data/data/org.thoughtcrime.securesms/app_parts/part-225791676.mms [ 06-09 11:53:16.219 1357: 1357 I/ActivityManager ] Recipient 11862

[ 06-09 11:53:16.269 12055:12547 W/MmsService ] Got network info: NetworkInfo: type: mobile_mms[LTE, type_ext: mobile_mms], state: DISCONNECTED/DISCONNECTED, reason: dataDisabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: false, isIpv6Connected: false

[ 06-09 11:53:16.269 12055:12547 W/MmsSender ] Sending MMS with radio change..

[ 06-09 11:53:16.269 12055:12547 W/MmscProcessor ] startUsingNetworkFeature status: 1

[ 06-09 11:53:16.269 1357: 2504 D/ConnectivityService ] startUsingNetworkFeature for net 0: enableMMS by 12055/10226

[ 06-09 11:53:16.269 1357: 2504 D/ConnectivityService ] Disconnect 2 in 420000ms

[ 06-09 11:53:16.269 1357: 2504 D/ConnectivityService ] reconnecting to special network 2

[ 06-09 11:53:16.269 1357: 2504 D/ConnectivityService ] startUsingNetworkFeature took 1ms

[ 06-09 11:53:16.269 1357: 1492 D/ConnectivityService ] issue reconnect to special network:2

[ 06-09 11:53:16.269 1357: 1492 D/MobileDataStateTracker ] mms: setTeardownRequested(false)

[ 06-09 11:53:16.269 1357: 2304 D/PowerManagerService ] acquireWakeLock(432fc7d8): PARTIAL_WAKE_LOCK , tag=MMS Connection, flags=0x1, pid=12055, uid=10226, mUserState=0x3

[ 06-09 11:53:16.279 1819: 1819 D/DATA ] [PhoneNotifier] [DPN] state=CONNECTING possible=true reason=dataEnabled apn=phone type=mms lp=<> tech=13 roaming=false errorCause=0 carrier=ATT Phone

[ 06-09 11:53:16.279 1819: 1819 D/DATA ] [PhoneNotifier] update DcParam of mms(1)

[ 06-09 11:53:16.279 1357: 3771 I/TelephonyRegistry ] notifyDataConnection: state=1 isDataConnectivityPossible=true reason='dataEnabled' apn='phone' apnType=mms networkType=13 roaming=false errorCause=0preConn = []

[ 06-09 11:53:16.279 1357: 1492 D/MobileDataStateTracker ] mms: Broadcast received: ACTION_ANY_DATA_CONNECTION_STATE_CHANGED mApnType=mms == received apnType=mms

[ 06-09 11:53:16.279 1357: 1492 D/MobileDataStateTracker ] mms: Received state=CONNECTING, old=DISCONNECTED, reason=dataEnabled

[ 06-09 11:53:16.279 1357: 1492 D/MobileDataStateTracker ] mms: mMobileDataState=DISCONNECTED mTeardownRequested=false got [AnyDataCSC] : type=mms state=CONNECTING reason=dataEnabled apn=phone roaming=false unavailable=false NetworkType=13 NetworkTypeName=LTE entitleError=0

[ 06-09 11:53:16.279 1357: 1492 D/MobileDataStateTracker ] mms: setDetailed state, old=IDLE and new state=CONNECTING reason=dataEnabled extra=phone mEntitleError=0

[ 06-09 11:53:16.289 12055:12055 W/MmsService ] Dispatching connectivity change...

[ 06-09 11:53:16.289 12055:12055 W/MmsService ] Dispatched...

[ 06-09 11:53:16.289 1357: 1492 D/ConnectivityService ] ConnectivityChange for mobile_mms/LTE: CONNECTING/CONNECTING, default=1

[ 06-09 11:53:16.289 1357: 1492 D/ConnectivityService ] mActiveDefaultNetwork: WIFI

[ 06-09 11:53:16.289 1357: 2305 D/PowerManagerService ] acquireWakeLock(42aaef00): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1, pid=1819, uid=1001, mUserState=0x3

[ 06-09 11:53:16.299 12055:12547 W/MmsService ] NetworkInfo: NetworkInfo: type: mobile_mms[LTE, type_ext: mobile_mms], state: CONNECTING/CONNECTING, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: false, isIpv6Connected: false

[ 06-09 11:53:16.319 12055:12547 W/MmsService ] Got network info: NetworkInfo: type: mobile_mms[LTE, type_ext: mobile_mms], state: CONNECTING/CONNECTING, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: false, isIpv6Connected: false

[ 06-09 11:53:16.319 12055:12066 W/CursorWrapperInner ] Cursor finalized without prior close()

[ 06-09 11:53:16.359 12055:12145 W/ImageSlide ] Got soft reference: java.lang.ref.SoftReference@41f94928

[ 06-09 11:53:16.359 12055:12145 W/ImageSlide ] Got cached bitmap: android.graphics.Bitmap@41f4c7c0

[ 06-09 11:53:16.629 1357: 1472 D/StateMachine ] handleMessage: E msg.what=131155

[ 06-09 11:53:16.629 1357: 1472 D/StateMachine ] processMsg: ConnectedState

[ 06-09 11:53:16.629 1357: 1472 D/WifiStateMachine ] [ 06-09 11:53:16.669 1357: 1472 D/WifiStateMachine ] fetchRssiAndLinkSpeedNative RSSI = -51

[ 06-09 11:53:16.669 1357: 1472 D/WifiStateMachine ] fetchRssiAndLinkSpeedNative mLinkspeedCount = 5, mLinkspeedSum: 260

[ 06-09 11:53:16.679 1357: 1472 D/WifiStateMachine ] fetchRssiAndLinkSpeedNative: Update RSSI:-51 and linkspeed:52 in database

[ 06-09 11:53:16.679 1357: 1472 D/WifiApDatabaseHandler ] updateConnectedAP...

[ 06-09 11:53:16.699 1357: 1472 D/StateMachine ] handleMessage: X

[ 06-09 11:53:16.749 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x40157c70, tls=0x417fdf00, arg=0x41909010

[ 06-09 11:53:16.749 179:12964 D/libc ] pt_debug : __thread_entry->func=0x40157c70 , tls=0x417fdf00, arg=0x41909010

[ 06-09 11:53:16.880 182: 483 D/libc ] pt_debug : pthread_create->start_routine=0x400f9665, tls=0x40af7f00, arg= 0x8

[ 06-09 11:53:16.880 182:12965 D/libc ] pt_debug : __thread_entry->func=0x400f9665 , tls=0x40af7f00, arg= 0x8

[ 06-09 11:53:16.880 1357: 1465 D/Tethering ] [isWifi] getHotspotEnabled: false

[ 06-09 11:53:17.050 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.050 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.050 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.060 1819: 2532 D/libc ] 06-09 11:53:16.880 182:12965 D/libc ] pt_debug : __thread_entry->func=0x400f9665 , tls=0x40af7f00, arg= 0x8

[ 06-09 11:53:16.880 1357: 1465 D/Tethering ] [isWifi] getHotspotEnabled: false

[ 06-09 11:53:17.050 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.050 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.050 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1357: 1369 D/PowerManagerService ] releaseWakeLock(42aaef00): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 11:53:17.080 1819: 1819 D/DATA ] [PhoneNotifier] [DPN] state=CONNECTED possible=true reason=dataEnabled apn=phone type=mms lp=<<InterfaceName: rmnet_usb0 LinkAddresses: [10.32.33.47/27,] Routes: [0.0.0.0/0 -> 10.32.33.33,] DnsAddresses: [172.26.38.1,172.26.38.2,] >> tech=13 roaming=false errorCause=0 carrier=ATT Phone

[ 06-09 11:53:17.080 1819: 1819 D/DATA ] [PhoneNotifier] update DcParam of mms(1) [ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.060 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.060 1357: 1369 D/PowerManagerService ] releaseWakeLock(42aaef00): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 11:53:17.080 1819: 1819 D/DATA ] [PhoneNotifier] [DPN] state=CONNECTED possible=true reason=dataEnabled apn=phone type=mms lp=<<InterfaceName: rmnet_usb0 LinkAddresses: [10.32.33.47/27,] Routes: [0.0.0.0/0 -> 10.32.33.33,] DnsAddresses: [172.26.38.1,172.26.38.2,] >> tech=13 roaming=false errorCause=0 carrier=ATT Phone

[ 06-09 11:53:17.080 1819: 1819 D/DATA ] [PhoneNotifier] update DcParam of mms(1)

[ 06-09 11:53:17.080 1357: 2304 I/TelephonyRegistry ] notifyDataConnection: state=2 isDataConnectivityPossible=true reason='dataEnabled' apn='phone' apnType=mms networkType=13 roaming=false errorCause=0preConn = []

[ 06-09 11:53:17.090 1357: 1492 D/MobileDataStateTracker ] mms: Broadcast received: ACTION_ANY_DATA_CONNECTION_STATE_CHANGED mApnType=mms == received apnType=mms

[ 06-09 11:53:17.090 1650: 1650 D/TELEPHONY_CALLBACK ] @onDataConnectionStateChanged: dataState=2 networkType=LTE

[ 06-09 11:53:17.090 1650: 1650 D/StatusBar.NetworkController ] dumpState[hasService=true ServiceState=0 home AT&T AT&T 310410 LTE CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1 IsCdma=false SimState=UNKNOWN GprsState=0 DataState=Connected DataActivity=NONE NetworkType=LTE HtcModemLinkOn=false]

[ 06-09 11:53:17.090 1650: 1650 D/DATA_ICON ] updateDataNetType: networkType=LTE

[ 06-09 11:53:17.090 1650: 1650 D/DATA_ICON ] updateDataIcon

[ 06-09 11:53:17.090 1650: 1650 D/StatusBar.NetworkController ] dumpIconState[dataVisible=true dataIconId=stat_sys_data_4g_lte_connected signalIconId=stat_sys_5signal_2 wifiVisible=true wifiIconId=stat_sys_wifi_signal_4 simCardVisible=false simCardIconId=null airplaneMode=false]

[ 06-09 11:53:17.090 1357: 1492 D/MobileDataStateTracker ] mms: Received state=CONNECTED, old=CONNECTING, reason=dataEnabled

[ 06-09 11:53:17.100 1357: 1492 D/libc ] [ 06-09 11:53:17.080 1357: 2304 I/TelephonyRegistry ] notifyDataConnection: state=2 isDataConnectivityPossible=true reason='dataEnabled' apn='phone' apnType=mms networkType=13 roaming=false errorCause=0preConn = []

[ 06-09 11:53:17.090 1357: 1492 D/MobileDataStateTracker ] mms: Broadcast received: ACTION_ANY_DATA_CONNECTION_STATE_CHANGED mApnType=mms == received apnType=mms

[ 06-09 11:53:17.090 1650: 1650 D/TELEPHONY_CALLBACK ] @onDataConnectionStateChanged: dataState=2 networkType=LTE

[ 06-09 11:53:17.090 1650: 1650 D/StatusBar.NetworkController ] dumpState[hasService=true ServiceState=0 home AT&T AT&T 310410 LTE CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1 IsCdma=false SimState=UNKNOWN GprsState=0 DataState=Connected DataActivity=NONE NetworkType=LTE HtcModemLinkOn=false]

[ 06-09 11:53:17.090 1650: 1650 D/DATA_ICON ] updateDataNetType: networkType=LTE

[ 06-09 11:53:17.090 1650: 1650 D/DATA_ICON ] updateDataIcon [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.100 1357: 1492 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.100 1357: 1492 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.100 1357: 1492 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.100 1357: 1492 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.100 1357: 1492 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.100 1357: 1492 D/MobileDataStateTracker ] mms: mMobileDataState=CONNECTING mTeardownRequested=false got [AnyDataCSC] : type=mms state=CONNECTED reason=dataEnabled apn=phone roaming=false unavailable=false NetworkType=13 NetworkTypeName=LTE entitleError=0

[ 06-09 11:53:17.100 1357: 1492 D/MobileDataStateTracker ] mms: setDetailed state, old=CONNECTING and new state=CONNECTED reason=dataEnabled extra=phone mEntitleError=0

[ 06-09 11:53:17.100 1357: 1492 D/ConnectivityService ] ConnectivityChange for mobile_mms/LTE: CONNECTED/CONNECTED, default=1

[ 06-09 11:53:17.100 1357: 1492 D/MobileDataStateTracker ] mms: setTeardownRequested(false)

[ 06-09 11:53:17.100 1357: 1492 D/ConnectivityService ] Setting TCP values: [524288,1048576,5120000,4096,221184,3461120] which comes from [net.tcp.buffersize.lte]

[ 06-09 11:53:17.120 1357: 1492 D/ConnectivityService ] update dns 172.26.38.1 for pid 12055 into net.dns1.12055

[ 06-09 11:53:17.120 1357: 1492 D/ConnectivityService ]

[NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.100 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.100 1357: 1492 D/MobileDataStateTracker ] mms: mMobileDataState=CONNECTING mTeardownRequested=false got [AnyDataCSC] : type=mms state=CONNECTED reason=dataEnabled apn=phone roaming=false unavailable=false NetworkType=13 NetworkTypeName=LTE entitleError=0

[ 06-09 11:53:17.100 1357: 1492 D/MobileDataStateTracker ] mms: setDetailed state, old=CONNECTING and new state=CONNECTED reason=dataEnabled extra=phone mEntitleError=0

[ 06-09 11:53:17.100 1357: 1492 D/ConnectivityService ] ConnectivityChange for mobile_mms/LTE: CONNECTED/CONNECTED, default=1

[ 06-09 11:53:17.100 1357: 1492 D/MobileDataStateTracker ] mms: setTeardownRequested(false)

[ 06-09 11:53:17.100 1357: 1492 D/ConnectivityService ] Setting TCP values: [524288,1048576,5120000,4096,221184,3461120] which comes from [net.tcp.buffersize.lte]

[ 06-09 11:53:17.120 1357: 1492 D/ConnectivityService ] update dns 172.26.38.1 for pid 12055 into net.dns1.12055

[ 06-09 11:53:17.120 1357: 1492 D/ConnectivityService ] update dns 172.26.38.2 for pid 12055 into net.dns2.12055

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e update dns 172.26.38.2 for pid 12055 into net.dns2.12055

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1357: 1492 D/ConnectivityService ] Adding 10.32.33.33/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 11:53:17.140 1357: 1492 D/ConnectivityService ] Adding 0.0.0.0/0 -> 10.32.33.33 for interface rmnet_usb0

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.130 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.130 1357: 1492 D/ConnectivityService ] Adding 10.32.33.33/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 11:53:17.140 1357: 1492 D/ConnectivityService ] Adding 0.0.0.0/0 -> 10.32.33.33 for interface rmnet_usb0

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:17.150 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:17.150 1357: 1492 D/ConnectivityService ] Removing 0.0.0.0/0 -> 10.32.33.33 for interface rmnet_usb0

[ 06-09 11:53:17.150 1357: 1492 W/ConnectivityService ] Exception trying to remove a route: java.lang.IllegalStateException: command '8122 interface route remove rmnet_usb0 default 0.0.0.0 0 10.32.33.33' failed with '400 8122 Failed to remove route from default table (No such process)'

[ 06-09 11:53:17.150 1357: 1492 D/ConnectivityService ] Adding 10.32.33.33/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 11:53:17.150 1357: 1492 D/ConnectivityService ] Adding 172.26.38.1/32 -> 10.32.33.33 for interface rmnet_usb0

[ 06-09 11:53:17.150 1357: 1492 D/ConnectivityService ] Adding 10.32.33.33/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 11:53:17.160 1171: 1341 D/libc ]

moxie0 commented 11 years ago

There's no more? In this section, TextSecure flips the radio into MMS mode and is waiting for the callback. Next it should actually try sending the MMS.

wastedimage commented 11 years ago

[ 06-09 11:53:40.204 1650: 1650 D/memalloc ] ion: Mapped buffer base:0x6decf000 size:737280 offset:0 fd:84

[ 06-09 11:53:40.204 1650: 1650 D/memalloc ] ion: Mapped buffer base:0x689d4000 size:4096 offset:0 fd:86

[ 06-09 11:53:40.245 2009: 2023 V/ContactsProvider ] onNotifyTransactionFinished()

[ 06-09 11:53:40.355 12055:12547 W/MmsSender ] java.io.IOException: Non-successful HTTP response: Bad Gateway at org.thoughtcrime.securesms.mms.MmsSendHelper.makePost(MmsSendHelper.java:67) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendBytes(MmsSendHelper.java:107) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendMms(MmsSendHelper.java:90) at org.thoughtcrime.securesms.service.MmsSender.sendMmsMessage(MmsSender.java:126) at org.thoughtcrime.securesms.service.MmsSender.handleConnectivityChange(MmsSender.java:187) at org.thoughtcrime.securesms.service.MmsSender.process(MmsSender.java:83) at org.thoughtcrime.securesms.service.SendReceiveService$SendReceiveWorkItem.run(SendReceiveService.java:222) at org.thoughtcrime.securesms.util.WorkerThread.run(WorkerThread.java:46)

[ 06-09 11:53:40.355 12055:12547 W/MmsSender ] Falling back to sending MMS with radio and proxy...

[ 06-09 11:53:40.355 12055:12547 W/MmsService ] Got network info: NetworkInfo: type: mobile_mms[LTE, type_ext: mobile_mms], state: CONNECTED/CONNECTED, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: true, isIpv6Connected: false

[ 06-09 11:53:40.355 12055:12547 W/MmsSender ] Sending MMS with radio change..

[ 06-09 11:53:40.355 1357: 1918 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:40.355 1357: 1918 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:40.355 1357: 1918 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.355 1357: 1918 D/ConnectivityService ] startUsingNetworkFeature for net 0: enableMMS by 12055/10226

[ 06-09 11:53:40.355 1357: 1918 D/ConnectivityService ] Disconnect 2 in 420000ms

[ 06-09 11:53:40.365 1357: 1918 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:40.365 1357: 1918 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:40.365 1357: 1918 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.365 12055:12547 W/MmscProcessor ] startUsingNetworkFeature status: 0

[ 06-09 11:53:40.365 12055:12547 W/MmsService ] NetworkInfo: NetworkInfo: type: mobile_mms[LTE, type_ext: mobile_mms], state: CONNECTED/CONNECTED, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: true, isIpv6Connected: false

[ 06-09 11:53:40.365 12055:12547 W/MmsSender ] Sending MMS SendItem...

[ 06-09 11:53:40.365 12055:12547 W/PduComposer ] Making send request...

[ 06-09 11:53:40.365 1357: 1918 D/ConnectivityService ] special network 2 already active - CONNECTED

[ 06-09 11:53:40.365 1357: 1918 D/ConnectivityService ] startUsingNetworkFeature took 6ms [ 06-09 11:53:40.365 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.365 12055:12547 W/MmscProcessor ] startUsingNetworkFeature status: 0

[ 06-09 11:53:40.365 12055:12547 W/MmsService ] NetworkInfo: NetworkInfo: type: mobile_mms[LTE, type_ext: mobile_mms], state: CONNECTED/CONNECTED, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: true, isIpv6Connected: false

[ 06-09 11:53:40.365 12055:12547 W/MmsSender ] Sending MMS SendItem...

[ 06-09 11:53:40.365 12055:12547 W/PduComposer ] Making send request...

[ 06-09 11:53:40.365 1357: 1918 D/ConnectivityService ] special network 2 already active - CONNECTED

[ 06-09 11:53:40.365 1357: 1918 D/ConnectivityService ] startUsingNetworkFeature took 6ms

[ 06-09 11:53:40.375 12055:12547 W/PduComposer ] Making message body...

[ 06-09 11:53:40.405 12055:12547 W/PduComposer ] Returning: 135236 bytes...

[ 06-09 11:53:40.405 12055:12547 W/MmsSender ] Sending MMS of length: 135236

[ 06-09 11:53:40.405 1819:14860 E/DatabaseUtils ] Writing exception to parcel java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.sqlite.SQLiteProgram.bind(SQLiteProgram.java:212) at android.database.sqlite.SQLiteProgram.bindString(SQLiteProgram.java:166) at android.database.sqlite.SQLiteProgram.bindAllArgsAsStrings(SQLiteProgram.java:200) at android.database.sqlite.SQLiteDirectCursorDriver.query(SQLiteDirectCursorDriver.java:68) at android.database.sqlite.SQLiteDatabase.rawQueryWithFactory(SQLiteDatabase.java:1370) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:421) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:315) at com.android.providers.telephony.TelephonyProvider.query(TelephonyProvider.java:1415) at android.content.ContentProvider.query(ContentProvider.java:673) at android.content.ContentProvider$Transport.query(ContentProvider.java:210) at android.content.ContentProviderNative.onTransact(ContentProviderNative.java:143) at android.os.Binder.execTransact(Binder.java:367) at dalvik.system.NativeStart.run(Native Method)

[ 06-09 11:53:40.415 12055:12547 W/MmsCommunication ]

[ 06-09 11:53:40.405 1819:14860 E/DatabaseUtils ] Writing exception to parcel java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.sqlite.SQLiteProgram.bind(SQLiteProgram.java:212) at android.database.sqlite.SQLiteProgram.bindString(SQLiteProgram.java:166) at android.database.sqlite.SQLiteProgram.bindAllArgsAsStrings(SQLiteProgram.java:200) at android.database.sqlite.SQLiteDirectCursorDriver.query(SQLiteDirectCursorDriver.java:68) at android.database.sqlite.SQLiteDatabase.rawQueryWithFactory(SQLiteDatabase.java:1370) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:421) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:315) at com.android.providers.telephony.TelephonyProvider.query(TelephonyProvider.java:1415) at android.content.ContentProvider.query(ContentProvider.java:673) at android.content.ContentProvider$Transport.query(ContentProvider.java:210) at android.content.ContentProviderNative.onTransact(ContentProviderNative.java:143) at android.os.Binder.execTransact(Binder.java:367) at dalvik.system.NativeStart.run(Native Method)

[ 06-09 11:53:40.415 12055:12547 W/MmsCommunication ] java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:170) at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:140) at android.content.ContentProviderProxy.query(ContentProviderNative.java:423) at android.content.ContentResolver.query(ContentResolver.java:375) at android.content.ContentResolver.query(ContentResolver.java:318) at org.thoughtcrime.securesms.database.MmsDatabase.getCarrierMmsInformation(MmsDatabase.java:584) at org.thoughtcrime.securesms.mms.MmsCommunication.getMmsConnectionParameters(MmsCommunication.java:92) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendBytes(MmsSendHelper.java:105) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendMms(MmsSendHelper.java:90) at org.thoughtcrime.securesms.service.MmsSender.sendMmsMessage(MmsSender.java:126) at org.thoughtcrime.securesms.service.MmsSender.handleConnectivityChange(MmsSender.java:187) at org.thoughtcrime.securesms.service.MmsSender.process(MmsSender.java:83) at org.thoughtcrime.securesms.service.SendReceiveService$SendReceiveWorkItem.run(SendReceiveService.java:222) at org.thoughtcrime.securesms.util.WorkerThread.run(WorkerThread.java:46)

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] getaddrinfo-exit,err=8

[ 06-09 11:53:40.415 12055:12547 D/libc ] 06-09 11:53:40.415 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] getaddrinfo-exit,err=8

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]android_getaddrinfo_proxy+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy-

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_files_getaddrinfo+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_files_getaddrinfo-,1

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_dns_getaddrinfo+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] res_searchN : calling res_querydomainN without domain

[ 06-09 11:53:40.415 12055:12547 D/libc ][ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]android_getaddrinfo_proxy+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy-

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_files_getaddrinfo+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_files_getaddrinfo-,1

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_dns_getaddrinfo+

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] res_searchN : calling res_querydomainN without domain

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]res_nsend...

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]IN CACHE,entry=1,count=1,e=0x6bf65960(e->expires=1370796896,now=1370796820)

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET][SMD][Mode0]: Original TTL is not expired (e->expires=1370796896,now=1370796820)

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]FOUND IN CACHE entry=0x6bf65960

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]res_queryN: exit 3, ancount=2

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_dns_getaddrinfo-,0

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc 06-09 11:53:40.415 12055:12547 D/libc ] [NET]IN CACHE,entry=1,count=1,e=0x6bf65960(e->expires=1370796896,now=1370796820)

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET][SMD][Mode0]: Original TTL is not expired (e->expires=1370796896,now=1370796820)

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]FOUND IN CACHE entry=0x6bf65960

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]res_queryN: exit 3, ancount=2

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET]_dns_getaddrinfo-,0

[ 06-09 11:53:40.415 12055:12547 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 12, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x36362e3230392e

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33 [ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 12, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x36362e3230392e

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] ht 0x31302e33322e33

[ 06-09 11:53:40.415 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 11:53:40.415 1357: 2305 D/ConnectivityService ] requestRouteToHost 66.209.11.32 from network 2 to 2 by 1357/1000

[ 06-09 11:53:40.415 1357: 2305 D/ConnectivityService ] Adding 10.32.33.33/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 11:53:40.415 1357: 2305 D/ConnectivityService ] Adding 66.209.11.32/32 -> 10.32.33.33 for interface rmnet_usb0

[ 06-09 11:53:40.425 12055:12547 W/MmsSender ] Sending MMS1 of length: 135236

[ 06-09 11:53:40.425 12055:12547 D/libc ] pt_debug : pthread_create->start_routine=0x40b9107d, tls=0x6e0ebf00, arg=0x65e608b8

[ 06-09 11:53:40.425 12055:13492 D/libc ] pt_debug : __thread_entry->func=0x40b9107d , tls=0x6e0ebf00, arg=0x65e608b8

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+[ 06-09 11:53:40.415 1357: 2305 D/ConnectivityService ] requestRouteToHost 66.209.11.32 from network 2 to 2 by 1357/1000

[ 06-09 11:53:40.415 1357: 2305 D/ConnectivityService ] Adding 10.32.33.33/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 11:53:40.415 1357: 2305 D/ConnectivityService ] Adding 66.209.11.32/32 -> 10.32.33.33 for interface rmnet_usb0

[ 06-09 11:53:40.425 12055:12547 W/MmsSender ] Sending MMS1 of length: 135236

[ 06-09 11:53:40.425 12055:12547 D/libc ] pt_debug : pthread_create->start_routine=0x40b9107d, tls=0x6e0ebf00, arg=0x65e608b8

[ 06-09 11:53:40.425 12055:13492 D/libc ] pt_debug : __thread_entry->func=0x40b9107d , tls=0x6e0ebf00, arg=0x65e608b8

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] getaddrinfo-exit,err=8

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]android_getaddrinfo_proxy+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy-

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]_files_getaddrinfo+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]_files_getaddrinfo-,1 [ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] getaddrinfo-exit,err=8

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]android_getaddrinfo_proxy+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] android_getaddrinfo_proxy-

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]_files_getaddrinfo+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]_files_getaddrinfo-,1

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]_dns_getaddrinfo+

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET] res_searchN : calling res_querydomainN without domain

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]res_nsend...

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]IN CACHE,entry=1,count=1,e=0x6bf65960(e->expires=1370796896,now=1370796820)

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET][SMD][Mode0]: Original TTL is not expired (e->expires=1370796896,now=1370796820)

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]FOUND IN CACHE entry=0x6bf65960

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]res_queryN: exit 3, ancount=2

[ 06-09 11:53:40.425 12055:12547 D/libc ] [NET]_dns_getaddrinfo-,0

wastedimage commented 11 years ago

I noticed the bad gateway message. Looks like I might have typo'd the URL you provided above. Here is another go. Let me know if you just want me to email you the entire log, its quite large.

pIconState[dataVisible=true dataIconId=stat_sys_data_4g_connected signalIconId=stat_sys_5signal_3 wifiVisible=false wifiIconId=stat_sys_wifi_signal_1 simCardVisible=false simCardIconId=null airplaneMode=false]

[ 06-09 13:22:16.913 23468:23593 W/MmsSender ] java.net.SocketTimeoutException at java.net.PlainSocketImpl.read(PlainSocketImpl.java:491) at java.net.PlainSocketImpl.access$000(PlainSocketImpl.java:46) at java.net.PlainSocketImpl$PlainSocketInputStream.read(PlainSocketImpl.java:240) at org.apache.http.impl.io.AbstractSessionInputBuffer.fillBuffer(AbstractSessionInputBuffer.java:103) at org.apache.http.impl.io.AbstractSessionInputBuffer.readLine(AbstractSessionInputBuffer.java:191) at org.apache.http.impl.conn.DefaultResponseParser.parseHead(DefaultResponseParser.java:82) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:174) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:180) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:235) at org.apache.http.impl.conn.AbstractClientConnAdapter.receiveResponseHeader(AbstractClientConnAdapter.java:259) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:279) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:121) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:435) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:593) at org.apache.http.impl.client.AbstractHttpClient.execute(AbstractHttpClient.java:532) at android.net.http.AndroidHttpClient.execute(AndroidHttpClient.java:262) at org.thoughtcrime.securesms.mms.MmsSendHelper.makePost(MmsSendHelper.java:63) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendBytes(MmsSendHelper.java:107) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendMms(MmsSendHelper.java:90) at org.thoughtcrime.securesms.service.MmsSender.sendMmsMessage(MmsSender.java:126) at org.thoughtcrime.securesms.service.MmsSender.handleConnectivityChange(MmsSender.java:187) at org.thoughtcrime.securesms.service.MmsSender.process(MmsSender.java:83) at org.thoughtcrime.securesms.service.SendReceiveService$SendReceiveWorkItem.run(SendReceiveService.java:222) at org.thoughtcrime.securesms.util.WorkerThread.run(WorkerThread.java:46)

[ 06-09 13:22:16.913 23468:23593 W/MmsSender ] Falling back to sending MMS with radio and proxy...

[ 06-09 13:22:16.913 23468:23593 W/MmsService ] Got network info: NetworkInfo: type: mobile_mms[HSDPA, type_ext: mobile_mms], state: CONNECTED/CONNECTED, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: true, isIpv6Connected: false

[ 06-09 13:22:16.923 23468:23593 W/MmsSender ] Sending MMS with radio change..

[ 06-09 13:22:16.923 1357: 1733 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 13:22:16.923 1357: 1733 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 13:22:16.923 1357: 1733 D/ConnectivityService ] startUsingNetworkFeature for net 0: enableMMS by 23468/10226

[ 06-09 13:22:16.923 1357: 1733 D/ConnectivityService ] Disconnect 2 in 420000ms

[ 06-09 13:22:16.933 1357: 1733 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:16.933 1357: 1733 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 13:22:16.933 1357: 1733 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 13:22:16.933 1357: 1733 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:16.933 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 13:22:16.933 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 13:22:16.933 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:16.933 1171: 1341 D/libc ] [NET] getaddrinfo hn 11, servname 53, ai_family 0+

[ 06-09 13:22:16.933 1171: 1341 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 13:22:16.933 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:16.933 23468:23593 W/MmscProcessor ] startUsingNetworkFeature status: 0

[ 06-09 13:22:16.933 1357: 1733 D/ConnectivityService ] special network 2 already active - CONNECTED

[ 06-09 13:22:16.933 1357: 1733 D/ConnectivityService ] startUsingNetworkFeature took 9ms

[ 06-09 13:22:16.943 23468:23593 W/MmsService ] NetworkInfo: NetworkInfo: type: mobile_mms[HSDPA, type_ext: mobile_mms], state: CONNECTED/CONNECTED, reason: dataEnabled, extra: phone, roaming: false, failover: false, isAvailable: true, isIpv4Connected: true, isIpv6Connected: false

[ 06-09 13:22:16.943 23468:23593 W/MmsSender ] Sending MMS SendItem...

[ 06-09 13:22:16.943 23468:23593 W/PduComposer ] Making send request...

[ 06-09 13:22:16.953 23468:23593 W/PduComposer ] Making message body...

[ 06-09 13:22:16.953 23468:23593 W/PduComposer ] Returning: 122026 bytes...

[ 06-09 13:22:16.963 23468:23593 W/MmsSender ] Sending MMS of length: 122026

[ 06-09 13:22:16.973 1819: 2531 E/DatabaseUtils ] Writing exception to parcel java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.sqlite.SQLiteProgram.bind(SQLiteProgram.java:212) at android.database.sqlite.SQLiteProgram.bindString(SQLiteProgram.java:166) at android.database.sqlite.SQLiteProgram.bindAllArgsAsStrings(SQLiteProgram.java:200) at android.database.sqlite.SQLiteDirectCursorDriver.query(SQLiteDirectCursorDriver.java:68) at android.database.sqlite.SQLiteDatabase.rawQueryWithFactory(SQLiteDatabase.java:1370) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:421) at android.database.sqlite.SQLiteQueryBuilder.query(SQLiteQueryBuilder.java:315) at com.android.providers.telephony.TelephonyProvider.query(TelephonyProvider.java:1415) at android.content.ContentProvider.query(ContentProvider.java:673) at android.content.ContentProvider$Transport.query(ContentProvider.java:210) at android.content.ContentProviderNative.onTransact(ContentProviderNative.java:143) at android.os.Binder.execTransact(Binder.java:367) at dalvik.system.NativeStart.run(Native Method)

[ 06-09 13:22:16.983 23468:23593 W/MmsCommunication ] java.lang.IllegalArgumentException: Cannot bind argument at index 1 because the index is out of range. The statement has 0 parameters. at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:170) at android.database.DatabaseUtils.readExceptionFromParcel(DatabaseUtils.java:140) at android.content.ContentProviderProxy.query(ContentProviderNative.java:423) at android.content.ContentResolver.query(ContentResolver.java:375) at android.content.ContentResolver.query(ContentResolver.java:318) at org.thoughtcrime.securesms.database.MmsDatabase.getCarrierMmsInformation(MmsDatabase.java:584) at org.thoughtcrime.securesms.mms.MmsCommunication.getMmsConnectionParameters(MmsCommunication.java:92) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendBytes(MmsSendHelper.java:105) at org.thoughtcrime.securesms.mms.MmsSendHelper.sendMms(MmsSendHelper.java:90) at org.thoughtcrime.securesms.service.MmsSender.sendMmsMessage(MmsSender.java:126) at org.thoughtcrime.securesms.service.MmsSender.handleConnectivityChange(MmsSender.java:187) at org.thoughtcrime.securesms.service.MmsSender.process(MmsSender.java:83) at org.thoughtcrime.securesms.service.SendReceiveService$SendReceiveWorkItem.run(SendReceiveService.java:222) at org.thoughtcrime.securesms.util.WorkerThread.run(WorkerThread.java:46)

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] getaddrinfo-exit,err=8

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] android_getaddrinfo_proxy+

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET]android_getaddrinfo_proxy+

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET] android_getaddrinfo_proxy-

[ 06-09 13:22:16.983 23468:23593 D/libc ] [NET]_files_getaddrinfo+

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]_files_getaddrinfo-,1

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]_dns_getaddrinfo+

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET] res_searchN : calling res_querydomainN without domain

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]res_nsend...

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]IN CACHE,entry=2,count=2,e=0x6bf03f88(e->expires=1370808015,now=1370802137)

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET][SMD][Mode0]: Original TTL is not expired (e->expires=1370808015,now=1370802137)

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]FOUND IN CACHE entry=0x6bf03f88

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]res_queryN: exit 3, ancount=2

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET]_dns_getaddrinfo-,0

[ 06-09 13:22:16.993 23468:23593 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:16.993 1357: 1994 D/ConnectivityService ] requestRouteToHost 66.209.11.32 from network 2 to 2 by 1357/1000

[ 06-09 13:22:16.993 1357: 1994 D/ConnectivityService ] Adding 10.18.231.178/32 -> 0.0.0.0 for interface rmnet_usb0

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo hn 13, servname NULL, ai_family 0+

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] ht 0x31302e31382e32

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo hn 7, servname NULL, ai_family 0+

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] ht 0x302e302e302e30

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo hn 12, servname NULL, ai_family 0+

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] ht 0x36362e3230392e

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo hn 13, servname NULL, ai_family 0+

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] ht 0x31302e31382e32

[ 06-09 13:22:17.003 1171: 1341 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:17.003 1357: 1994 D/ConnectivityService ] Adding 66.209.11.32/32 -> 10.18.231.178 for interface rmnet_usb0

[ 06-09 13:22:17.013 23468:23593 W/MmsSender ] Sending MMS1 of length: 122026

[ 06-09 13:22:17.013 23468:23593 D/libc ] pt_debug : pthread_create->start_routine=0x40b9107d, tls=0x6d451f00, arg=0x6c057b88

[ 06-09 13:22:17.013 23468:26289 D/libc ] pt_debug : __thread_entry->func=0x40b9107d , tls=0x6d451f00, arg=0x6c057b88

[ 06-09 13:22:17.013 23468:23593 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] getaddrinfo-exit,err=8

[ 06-09 13:22:17.023 1110: 1116 E/ThermalDaemon ] notify PnPMgr CPU3 frequency recover to 1728000

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] getaddrinfo hn 21, servname NULL, ai_family 0+

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] ht 0x776972656c6573

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] android_getaddrinfo_proxy+

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET]android_getaddrinfo_proxy+

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] android_getaddrinfo_proxy-

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET]_files_getaddrinfo+

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET]_files_getaddrinfo-,1

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET]_dns_getaddrinfo+

[ 06-09 13:22:17.023 23468:23593 D/libc ] [NET] res_searchN : calling res_querydomainN without domain

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET]res_nsend...

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET]IN CACHE,entry=2,count=2,e=0x6bf03f88(e->expires=1370808015,now=1370802137)

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET][SMD][Mode0]: Original TTL is not expired (e->expires=1370808015,now=1370802137)

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET]FOUND IN CACHE entry=0x6bf03f88

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET]res_queryN: exit 3, ancount=2

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET]_dns_getaddrinfo-,0

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:17.033 23468:23593 I/global ] call createSocket() return a new socket.

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET] getaddrinfo hn 12, servname NULL, ai_family 0+

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET] ht 0x36362e3230392e

[ 06-09 13:22:17.033 23468:23593 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:17.123 1357: 6433 D/PowerManagerService ] acquireWakeLock(43e51ce0): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1, pid=1819, uid=1001, mUserState=0x3

[ 06-09 13:22:17.133 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41892f00, arg=0x40290bf8

[ 06-09 13:22:17.133 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41c56f00, arg=0x4029f890

[ 06-09 13:22:17.133 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41e19f00, arg=0x402846d8

[ 06-09 13:22:17.133 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41f4df00, arg=0x402a7af8

[ 06-09 13:22:17.133 179:26296 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41892f00, arg=0x40290bf8

[ 06-09 13:22:17.133 179:26298 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41e19f00, arg=0x402846d8

[ 06-09 13:22:17.133 179:26299 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41f4df00, arg=0x402a7af8

[ 06-09 13:22:17.133 179:26297 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41c56f00, arg=0x4029f890

[ 06-09 13:22:17.184 1357: 1733 D/PowerManagerService ] releaseWakeLock(43e51ce0): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 13:22:17.384 1357: 1993 D/PowerManagerService ] acquireWakeLock(444719a0): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1, pid=1819, uid=1001, mUserState=0x3

[ 06-09 13:22:17.414 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41820f00, arg=0x4029f890

[ 06-09 13:22:17.424 179:26300 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41820f00, arg=0x4029f890

[ 06-09 13:22:17.424 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41c56f00, arg=0x402846d8

[ 06-09 13:22:17.434 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41dfaf00, arg=0x40290bf8

[ 06-09 13:22:17.434 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41fd1f00, arg=0x402a7af8

[ 06-09 13:22:17.434 179:26302 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41dfaf00, arg=0x40290bf8

[ 06-09 13:22:17.434 179:26301 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41c56f00, arg=0x402846d8

[ 06-09 13:22:17.434 179:26303 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41fd1f00, arg=0x402a7af8

[ 06-09 13:22:17.444 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41fa7f00, arg=0x402a7af8

[ 06-09 13:22:17.444 179:26304 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41fa7f00, arg=0x402a7af8

[ 06-09 13:22:17.454 1650: 1650 D/TELEPHONY_CALLBACK ] @onDataActivity: direction=2

[ 06-09 13:22:17.464 1650: 1650 D/StatusBar.NetworkController ] dumpState[hasService=true ServiceState=0 home AT&T AT&T 310410 HSDPA CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1 IsCdma=false SimState=UNKNOWN GprsState=0 DataState=Connected DataActivity=OUT NetworkType=HSDPA HtcModemLinkOn=false]

[ 06-09 13:22:17.464 1650: 1650 D/DATA_ICON ] updateDataIcon

[ 06-09 13:22:17.464 1650: 1650 D/DATA_ICON ] fakeui srv=0 mTimerEnabled=false

[ 06-09 13:22:17.464 1650: 1650 D/DATA_ICON ] refreshViews: mDataIconId -> stat_sys_data_att_4g_out

[ 06-09 13:22:17.464 1650: 1650 D/StatusBar.NetworkController ] dumpIconState[dataVisible=true dataIconId=stat_sys_data_att_4g_out signalIconId=stat_sys_5signal_3 wifiVisible=false wifiIconId=stat_sys_wifi_signal_1 simCardVisible=false simCardIconId=null airplaneMode=false]

[ 06-09 13:22:17.484 1357: 1918 D/PowerManagerService ] releaseWakeLock(444719a0): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 13:22:17.494 1650: 1650 D/TELEPHONY_CALLBACK ] @onServiceStateChanged: 0 home AT&T AT&T 310410 UMTS CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1

[ 06-09 13:22:17.494 1650: 1650 D/StatusBar.NetworkController ] dumpState[hasService=true ServiceState=0 home AT&T AT&T 310410 UMTS CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1 IsCdma=false SimState=UNKNOWN GprsState=0 DataState=Connected DataActivity=OUT NetworkType=HSDPA HtcModemLinkOn=false]

[ 06-09 13:22:17.494 1650: 1650 D/SIGNAL_ICON ] updateTelephonySignalStrength

[ 06-09 13:22:17.494 1650: 1650 D/SIGNAL_ICON ] fakeui srv=0 mTimerEnabled=false asu=9

[ 06-09 13:22:17.494 1650: 1650 D/SIGNAL_ICON ] mapTelephonySignalStrength for ATT: asu=9

[ 06-09 13:22:17.494 1650: 1650 D/SIGNAL_ICON ] asu=9 signalDbm=96 ecno=14 lteRsrp=1 lteRssnr=-1 nwkType=UMTS

[ 06-09 13:22:17.494 1650: 1650 D/SIGNAL_ICON ] 3G/HSPA dbm=-96 ecno=-14 iconLevel=3

[ 06-09 13:22:17.494 1650: 1650 D/DATA_ICON ] updateDataNetType: networkType=HSDPA

[ 06-09 13:22:17.494 1650: 1650 D/DATA_ICON ] updateDataIcon

[ 06-09 13:22:17.494 1650: 1650 D/StatusBar.NetworkController ] dumpIconState[dataVisible=true dataIconId=stat_sys_data_att_4g_out signalIconId=stat_sys_5signal_3 wifiVisible=false wifiIconId=stat_sys_wifi_signal_1 simCardVisible=false simCardIconId=null airplaneMode=false]

[ 06-09 13:22:17.504 1357: 1492 D/MobileDataStateTracker ] default: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.504 1357: 1492 D/MobileDataStateTracker ] mms: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.504 1357: 1492 D/MobileDataStateTracker ] supl: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.504 1357: 1492 D/MobileDataStateTracker ] dun: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.504 1357: 1492 D/MobileDataStateTracker ] hipri: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] fota: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] ims: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] null: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] httpproxy: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] admin: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] internet: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] null: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] cmmail: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] null: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] cmail: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] entitle: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] null: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.514 1357: 1492 D/MobileDataStateTracker ] bip: Broadcast received: ignore android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.524 1110: 1116 E/ThermalDaemon ] notify PnPMgr CPU3 frequency recover to 1728000

[ 06-09 13:22:17.524 1110: 1116 E/ThermalDaemon ] notify PnPMgr CPU2 frequency recover to 1728000

[ 06-09 13:22:17.534 1357: 1804 D/GpsLocationProvider ] ServiceState.STATE_IN_SERVICE

[ 06-09 13:22:17.534 1819: 1819 D/DATA ] [PhoneNotifier] [DPN] state=CONNECTED possible=true reason=nwTypeChanged apn=phone type=default lp=<<InterfaceName: rmnet_usb0 LinkAddresses: [10.18.231.177/30,] Routes: [0.0.0.0/0 -> 10.18.231.178,] DnsAddresses: [172.26.38.1,172.26.38.2,] >> tech=3 roaming=false errorCause=0 carrier=ATT Phone

[ 06-09 13:22:17.534 1819: 1819 D/DATA ] [PhoneNotifier] update DcParam of default(0)

[ 06-09 13:22:17.544 1357: 2504 I/TelephonyRegistry ] notifyDataConnection: state=2 isDataConnectivityPossible=true reason='nwTypeChanged' apn='phone' apnType=default networkType=3 roaming=false errorCause=0preConn = [default, mms]

[ 06-09 13:22:17.544 1650: 1650 D/TELEPHONY_CALLBACK ] @onDataConnectionStateChanged: dataState=2 networkType=UMTS

[ 06-09 13:22:17.544 1357: 1492 D/MobileDataStateTracker ] default: Broadcast received: ACTION_ANY_DATA_CONNECTION_STATE_CHANGED mApnType=default == received apnType=default

[ 06-09 13:22:17.544 1650: 1650 D/StatusBar.NetworkController ] dumpState[hasService=true ServiceState=0 home AT&T AT&T 310410 UMTS CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1 IsCdma=false SimState=UNKNOWN GprsState=0 DataState=Connected DataActivity=OUT NetworkType=UMTS HtcModemLinkOn=false]

[ 06-09 13:22:17.544 1650: 1650 D/DATA_ICON ] updateDataNetType: networkType=UMTS

[ 06-09 13:22:17.544 1357: 1446 D/GlobalActions ] onServiceStateChanged, serviceState = 0 home AT&T AT&T 310410 UMTS CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1

[ 06-09 13:22:17.544 1650: 1650 D/DATA_ICON ] updateDataIcon

[ 06-09 13:22:17.554 1819: 1819 D/DATA ] [PhoneNotifier] [DPN] state=CONNECTED possible=true reason=nwTypeChanged apn=phone type=mms lp=<<InterfaceName: rmnet_usb0 LinkAddresses: [10.18.231.177/30,] Routes: [0.0.0.0/0 -> 10.18.231.178,] DnsAddresses: [172.26.38.1,172.26.38.2,] >> tech=3 roaming=false errorCause=0 carrier=ATT Phone

[ 06-09 13:22:17.554 1819: 1819 D/DATA ] [PhoneNotifier] update DcParam of mms(1)

[ 06-09 13:22:17.554 1650: 1650 D/StatusBar.NetworkController ] dumpIconState[dataVisible=true dataIconId=stat_sys_data_att_4g_out signalIconId=stat_sys_5signal_3 wifiVisible=false wifiIconId=stat_sys_wifi_signal_1 simCardVisible=false simCardIconId=null airplaneMode=false]

[ 06-09 13:22:17.554 1357: 1492 D/MobileDataStateTracker ] default: Received state=CONNECTED, old=CONNECTED, reason=nwTypeChanged

[ 06-09 13:22:17.554 1357: 2504 D/PowerManagerService ] acquireWakeLock(4483b930): PARTIAL_WAKE_LOCK , tag=SWITCH_NETWORK_MODE_WAKE_LOCK, flags=0x1, pid=3905, uid=10039, mUserState=0x3

[ 06-09 13:22:17.554 1357: 1744 I/TelephonyRegistry ] notifyDataConnection: state=2 isDataConnectivityPossible=true reason='nwTypeChanged' apn='phone' apnType=mms networkType=3 roaming=false errorCause=0preConn = [default, mms]

[ 06-09 13:22:17.554 1357: 1492 D/MobileDataStateTracker ] default: mMobileDataState=CONNECTED mTeardownRequested=false got [AnyDataCSC] : type=default state=CONNECTED reason=nwTypeChanged apn=phone roaming=false unavailable=false NetworkType=3 NetworkTypeName=UMTS entitleError=0

[ 06-09 13:22:17.564 1357: 1492 D/ConnectivityService ] Setting TCP values: [4096,174760,1572864,4096,225280,1220608] which comes from [net.tcp.buffersize.umts]

[ 06-09 13:22:17.574 1357: 1368 D/PowerManagerService ] acquireWakeLock(4399cfe8): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1, pid=1819, uid=1001, mUserState=0x3

[ 06-09 13:22:17.574 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41876f00, arg=0x402846d8

[ 06-09 13:22:17.574 3905: 3905 V/FlexNetS ] onStartCommand() ACTION_CAMPNETWORK_NOT_IN_SERVICE_OR_ROAMING

[ 06-09 13:22:17.574 3905: 3905 V/FlexNetS ] handleCommand_AutoMode action: ACTION_CAMPNETWORK_NOT_IN_SERVICE_OR_ROAMING

[ 06-09 13:22:17.574 3905: 3905 D/FlexNetS ] cancelTimer: ACTION_FAKE_IN_SERVICE_AND_NOT_ROAMING

[ 06-09 13:22:17.574 1357: 1492 D/MobileDataStateTracker ] mms: Broadcast received: ACTION_ANY_DATA_CONNECTION_STATE_CHANGED mApnType=mms == received apnType=mms

[ 06-09 13:22:17.574 1357: 1492 D/MobileDataStateTracker ] mms: Received state=CONNECTED, old=CONNECTED, reason=nwTypeChanged

[ 06-09 13:22:17.584 179:26313 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41876f00, arg=0x402846d8

[ 06-09 13:22:17.584 3905: 3905 D/FlexNetS ] cancelTimer: ACTION_SWITCH_TO_GSM_ONLY_MODE

[ 06-09 13:22:17.584 3905: 3905 D/libc ] pt_debug : pthread_create->start_routine=0x40b9107d, tls=0x6bb92f00, arg=0x6b864c50

[ 06-09 13:22:17.584 3905:26314 D/libc ] pt_debug : __thread_entry->func=0x40b9107d , tls=0x6bb92f00, arg=0x6b864c50

[ 06-09 13:22:17.584 1357: 1492 D/MobileDataStateTracker ] mms: mMobileDataState=CONNECTED mTeardownRequested=false got [AnyDataCSC] : type=mms state=CONNECTED reason=nwTypeChanged apn=phone roaming=false unavailable=false NetworkType=3 NetworkTypeName=UMTS entitleError=0

[ 06-09 13:22:17.584 1357: 1492 D/ConnectivityService ] Setting TCP values: [4096,174760,1572864,4096,225280,1220608] which comes from [net.tcp.buffersize.umts]

[ 06-09 13:22:17.594 3905:26314 D/AutoSetting ] receiver - intent: android.intent.action.SERVICE_STATE

[ 06-09 13:22:17.594 3905:26314 D/AutoSetting ] receiver - flag: Service state 0, last 0

[ 06-09 13:22:17.594 1357: 1994 D/PowerManagerService ] releaseWakeLock(4483b930): PARTIAL_WAKE_LOCK , tag=SWITCH_NETWORK_MODE_WAKE_LOCK, flags=0x1

[ 06-09 13:22:17.604 1357: 6433 D/PowerManagerService ] releaseWakeLock(4399cfe8): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 13:22:17.914 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41895f00, arg=0x402846d8

[ 06-09 13:22:17.914 1357: 2504 D/PowerManagerService ] acquireWakeLock(43e35900): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1, pid=1819, uid=1001, mUserState=0x3

[ 06-09 13:22:17.924 179:26319 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41895f00, arg=0x402846d8

[ 06-09 13:22:17.924 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41c56f00, arg=0x40290bf8

[ 06-09 13:22:17.924 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41d88f00, arg=0x4029f890

[ 06-09 13:22:17.924 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41f72f00, arg=0x402a7af8

[ 06-09 13:22:17.924 179:26320 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41c56f00, arg=0x40290bf8

[ 06-09 13:22:17.924 179:26321 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41d88f00, arg=0x4029f890

[ 06-09 13:22:17.924 179:26322 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41f72f00, arg=0x402a7af8

[ 06-09 13:22:17.984 1357: 1994 D/PowerManagerService ] releaseWakeLock(43e35900): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 13:22:18.515 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41842f00, arg=0x4029f890

[ 06-09 13:22:18.515 1357: 2304 D/PowerManagerService ] acquireWakeLock(4380cb28): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1, pid=1819, uid=1001, mUserState=0x3

[ 06-09 13:22:18.525 179:26324 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41842f00, arg=0x4029f890

[ 06-09 13:22:18.525 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41c56f00, arg=0x40290bf8

[ 06-09 13:22:18.525 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41d9cf00, arg=0x402846d8

[ 06-09 13:22:18.525 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41ed6f00, arg=0x402a7af8

[ 06-09 13:22:18.525 179:26325 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41c56f00, arg=0x40290bf8

[ 06-09 13:22:18.525 179:26327 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41ed6f00, arg=0x402a7af8

[ 06-09 13:22:18.525 179:26326 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41d9cf00, arg=0x402846d8

[ 06-09 13:22:18.535 1819: 2532 D/libc ] [NET] getaddrinfo hn 13, servname NULL, ai_family 0+

[ 06-09 13:22:18.535 1819: 2532 D/libc ] [NET] ht 0x31302e31382e32

[ 06-09 13:22:18.535 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] getaddrinfo hn 11, servname NULL, ai_family 0+

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] ht 0x3137322e32362e

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] getaddrinfo hn 13, servname NULL, ai_family 0+

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] ht 0x31302e31382e32

[ 06-09 13:22:18.545 1819: 2532 D/libc ] [NET] getaddrinfo-exit SUCCESS

[ 06-09 13:22:18.555 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x4188cf00, arg=0x4029f890

[ 06-09 13:22:18.555 179:26328 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x4188cf00, arg=0x4029f890

[ 06-09 13:22:18.575 179: 241 D/libc ] pt_debug : pthread_create->start_routine=0x404d5005, tls=0x41c56f00, arg=0x40290bf8

[ 06-09 13:22:18.575 179:26329 D/libc ] pt_debug : __thread_entry->func=0x404d5005 , tls=0x41c56f00, arg=0x40290bf8

[ 06-09 13:22:18.595 1357: 1993 D/PowerManagerService ] releaseWakeLock(4380cb28): PARTIAL_WAKE_LOCK , tag=RILJ, flags=0x1

[ 06-09 13:22:18.605 1357: 1446 D/GlobalActions ] onServiceStateChanged, serviceState = 0 home AT&T AT&T 310410 HSPAP CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1

[ 06-09 13:22:18.605 1650: 1650 D/TELEPHONY_CALLBACK ] @onServiceStateChanged: 0 home AT&T AT&T 310410 HSPAP CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1

[ 06-09 13:22:18.605 1650: 1650 D/StatusBar.NetworkController ] dumpState[hasService=true ServiceState=0 home AT&T AT&T 310410 HSPAP CSS not supported -1 -1 RoamInd=-1 DefRoamInd=-1EriInd= -1EriMode= -1RadioPowerSv: false EmergOnly=false PhoneType: 1 IsCdma=false SimState=UNKNOWN GprsState=0 DataState=Connected DataActivity=OUT NetworkType=UMTS HtcModemLinkOn=false]

moxie0 commented 11 years ago

Thanks, but this still doesn't look like all of it. How about posting an entire log to pastebin?

wastedimage commented 11 years ago

Ha..apparently the entire thing is 800k, slightly too large. Will email work?

wastedimage commented 11 years ago

This should work - https://docs.google.com/file/d/0B74N2GOP6d4QTG15NlpiaDNiSk0/edit?pli=1

wastedimage commented 11 years ago

I think you were on the right track. After a bit of googling I found someone mentioning for LTE you should use a different server. I tried mmsc.mobile.att.net and proxy.mobile.att.net and that seems to have done the trick.

moxie0 commented 11 years ago

Thanks. It'd be good if we could find a way to autodetect that. If you look under System Settings -> Wireless & Networks -> Mobile Networks -> Access Point Names -> [your apn] can you post the MCC and MNC values from there?

wastedimage commented 11 years ago

It's 310 and 410 as you guessed.

moxie0 commented 11 years ago

Dang, then I don't think there's any way for me to detect ATT LTE vs ATT non-LTE in order to return the correct default on devices with locked APN dbs. =(

moxie0 commented 11 years ago

Actually, I'm not sure how this ATT LTE business works, but is there a way for you to turn it "off?" It'd be interesting to test and see whether the MMSC and gateway you used will work for non-LTE devices as well. If it did, we could just make that the default for ATT.

wastedimage commented 11 years ago

I wonder if the LTE servers would work for everyone? You could always do something weird like allow 2 fall backs.

wastedimage commented 11 years ago

Strangely there is no menu option in the stock rom to turn off LTE. If I end up with shitty coverage at some point I'll try and find out.

moxie0 commented 11 years ago

Cool, thanks for the help.