Moto G6 Plus SMS not sending

Hey guys! First of all: /e/ is awesome!

Today I updated from 0.18 directly to 0.19. Worked like a charm.

Now I had the problem that sms are not getting send. I receive sms, I can call, I have mobile data. Only sms cant get through. Under the message it states “sending…”. After some minutes or so I get a notification saying it did not get send.
I remember from my first install of /e/ I had some problems with calls not going through. I had to deactive VoLTE to make those work.

What I tried so far:

  • change SIM slot
  • another message app
  • reset network settings
  • message different numbers
  • tried to check the SMSC-number, though the settings app crashes when I try to enter phone information with the ##4636… code
  • cleared cache & stuff with TWRP

I think the SIM-card is OK, I send a message this morning before updating and it worked, though I will ask my roommate if I can shortly insert my sim card in his phone.
Next step Id try is a fresh install, though I wouldnt like to spent the next day for installing all apps and stuff again ;(

Any other suggestions though? Thenks alot!

I guess nobody has an idea?

0.18 → 0.19 within Q? can you read logcat output while trying to send an sms?

1 Like

Hi tc, thanks for your reply!
I dont really know what Android version I had before. Now it says 10, I think before I had the same… And the release page does not list any other version: /e/ image ROM download

I used the update via settings, so hopefully I got the right update :wink:

I made a logcat, dont know if that is correct like this:

<<< log_count = 51 >>>
[11-10 19:52:35.951 820:1001 W/qti_sensors_hal]
processReportInd: lux: 30, ch0: 0, ch1: 145, pData: 102, pOffset: 4

[11-10 19:52:36.678 3195:3195 D/NotificationRepository]
updateNotification() called with: list = [2]

[11-10 19:52:37.324 820:1001 W/qti_sensors_hal]
processReportInd: lux: 36, ch0: 0, ch1: 160, pData: 53, pOffset: 5

[11-10 19:52:37.765 820:1001 W/qti_sensors_hal]
processReportInd: lux: 69, ch0: 0, ch1: 124, pData: 173, pOffset: 9

[11-10 19:52:38.028 820:1333 W/qti_sensors_hal]
processInd: MOT_DISPROT Data: -1.0

[11-10 19:52:38.255 820:1001 W/qti_sensors_hal]
processReportInd: lux: 57, ch0: 0, ch1: 130, pData: 4, pOffset: 8

[11-10 19:52:38.563 1592:1999 V/InputDispatcher]
Asynchronous input event injection succeeded.

[11-10 19:52:38.628 1592:1736 D/AutofillManagerService]
Close system dialogs

[11-10 19:52:38.633 1592:6028 I/ActivityTaskManager]
START u0 {act=android.intent.action.MAIN cat=[android.intent.category.DEFAULT] flg=0x10000000 cmp=com.android.launcher3/com.android.quickstep.RecentsActivity} from uid 10190

[11-10 19:52:38.624 1592:1999 V/InputDispatcher]
Asynchronous input event injection succeeded.

[11-10 19:52:38.637 2053:2053 V/StatusBar]
mStatusBarWindow: com.android.systemui.statusbar.phone.StatusBarWindowView{e8543d9 V.E...... ........ 0,0-1080,57} canPanelBeCollapsed(): false

[11-10 19:52:38.651 23929:23929 D/LogcatReader]
[Logcat] onActivityInBackground

[11-10 19:52:38.657 820:1333 W/qti_sensors_hal]
processInd: MOT_DISPROT Data:  0.0

[11-10 19:52:38.708 2053:2053 D/ImageWallpaper]
wallpaper visibility changes to: true

[11-10 19:52:38.999 658:658 E/Layer]
[Surface(name=AppWindowToken{7e88df8 token=Token{dc2515b ActivityRecord{f93486a u0 com.dp.logcatapp/.activities.MainActivity t3956}}})/@0xd5c3482 - animation-leash#0] No local sync point found

[11-10 19:52:38.999 658:658 E/Layer]
[Surface(name=AppWindowToken{313db3a token=Token{6756265 ActivityRecord{b172f5c u0 com.android.launcher3/com.android.quickstep.RecentsActivity t3943}}})/@0x5b3278c - animation-leash#0] No local sync point found

[11-10 19:52:39.460 820:1001 W/qti_sensors_hal]
processReportInd: lux: 35, ch0: 0, ch1: 81, pData: 0, pOffset: 5

[11-10 19:52:40.277 3195:3195 D/NotificationRepository]
updateNotification() called with: list = [1]

[11-10 19:52:40.306 2434:2434 I/LatinIME]
Starting input. Cursor position = 0,0

[11-10 19:52:40.578 2053:2053 D/ImageWallpaper]
wallpaper visibility changes to: false

[11-10 19:52:40.588 658:658 E/Layer]
[Surface(name=AppWindowToken{313db3a token=Token{6756265 ActivityRecord{b172f5c u0 com.android.launcher3/com.android.quickstep.RecentsActivity t3943}}})/@0x5b3278c - animation-leash#0] No local sync point found

[11-10 19:52:40.588 658:658 E/Layer]
[Surface(name=AppWindowToken{8454f1b token=Token{3f0a52a ActivityRecord{9605e15 u0 com.moez.QKSMS/.feature.compose.ComposeActivity t3935}}})/@0x11241ce - animation-leash#0] No local sync point found

[11-10 19:52:40.668 820:1001 W/qti_sensors_hal]
processReportInd: lux: 45, ch0: 0, ch1: 121, pData: 107, pOffset: 6

[11-10 19:52:41.383 820:1001 W/qti_sensors_hal]
processReportInd: lux: 36, ch0: 0, ch1: 123, pData: 37, pOffset: 5

[11-10 19:52:41.384 820:1333 W/qti_sensors_hal]
processInd: MOT_DISPROT Data: -1.0

[11-10 19:52:41.805 820:1333 W/qti_sensors_hal]
processInd: MOT_DISPROT Data:  0.0

[11-10 19:52:42.886 2434:2434 I/LatinIME]
Starting input. Cursor position = 4,4

[11-10 19:52:42.992 6777:6795 D/MmsService]
getAutoPersisting

[11-10 19:52:43.184 3195:3195 D/NotificationRepository]
updateNotification() called with: list = [2]

[11-10 19:52:43.242 2053:2053 D/StatusBar]
disable<e i a s b h r c s > disable2<q i n >

[11-10 19:52:43.246 2053:2053 W/HeadsUpManagerPhone]
onHeadsUpPinnedModeChanged

[11-10 19:52:44.577 1592:1999 V/InputDispatcher]
Asynchronous input event injection succeeded.

[11-10 19:52:44.675 1592:1736 D/AutofillManagerService]
Close system dialogs

[11-10 19:52:44.682 1592:6301 I/ActivityTaskManager]
START u0 {act=android.intent.action.MAIN cat=[android.intent.category.DEFAULT] flg=0x10000000 cmp=com.android.launcher3/com.android.quickstep.RecentsActivity} from uid 10190

[11-10 19:52:44.671 1592:1999 V/InputDispatcher]
Asynchronous input event injection succeeded.

[11-10 19:52:44.686 2053:2053 V/StatusBar]
mStatusBarWindow: com.android.systemui.statusbar.phone.StatusBarWindowView{e8543d9 V.E...... ........ 0,0-1080,2160} canPanelBeCollapsed(): false

[11-10 19:52:44.742 2053:2053 D/ImageWallpaper]
wallpaper visibility changes to: true

[11-10 19:52:45.047 658:658 E/Layer]
[Surface(name=AppWindowToken{313db3a token=Token{6756265 ActivityRecord{b172f5c u0 com.android.launcher3/com.android.quickstep.RecentsActivity t3943}}})/@0x5b3278c - animation-leash#0] No local sync point found

[11-10 19:52:45.047 658:658 E/Layer]
[Surface(name=AppWindowToken{313db3a token=Token{6756265 ActivityRecord{b172f5c u0 com.android.launcher3/com.android.quickstep.RecentsActivity t3943}}})/@0x5b3278c - animation-leash#0] No local sync point found

[11-10 19:52:45.047 658:658 E/Layer]
[Surface(name=AppWindowToken{8454f1b token=Token{3f0a52a ActivityRecord{9605e15 u0 com.moez.QKSMS/.feature.compose.ComposeActivity t3935}}})/@0x11241ce - animation-leash#0] No local sync point found

[11-10 19:52:45.047 658:658 E/Layer]
[Surface(name=AppWindowToken{8454f1b token=Token{3f0a52a ActivityRecord{9605e15 u0 com.moez.QKSMS/.feature.compose.ComposeActivity t3935}}})/@0x11241ce - animation-leash#0] No local sync point found

[11-10 19:52:46.228 23929:23929 D/LogcatReader]
[Logcat] onActivityInForeground

[11-10 19:52:46.228 23929:23929 D/LogcatReader]
[Logcat] Posting pending logs

[11-10 19:52:46.258 23929:23929 D/LogcatReader]
[LogcatLiveFragment] onServiceConnected

[11-10 19:52:46.561 2053:2053 D/ImageWallpaper]
wallpaper visibility changes to: false

[11-10 19:52:46.568 658:658 E/Layer]
[Surface(name=AppWindowToken{313db3a token=Token{6756265 ActivityRecord{b172f5c u0 com.android.launcher3/com.android.quickstep.RecentsActivity t3943}}})/@0x5b3278c - animation-leash#0] No local sync point found

[11-10 19:52:46.568 658:658 E/Layer]
[Surface(name=AppWindowToken{313db3a token=Token{6756265 ActivityRecord{b172f5c u0 com.android.launcher3/com.android.quickstep.RecentsActivity t3943}}})/@0x5b3278c - animation-leash#0] No local sync point found

[11-10 19:52:46.568 658:658 E/Layer]
[Surface(name=AppWindowToken{7e88df8 token=Token{dc2515b ActivityRecord{f93486a u0 com.dp.logcatapp/.activities.MainActivity t3956}}})/@0xd5c3482 - animation-leash#0] No local sync point found

[11-10 19:52:46.568 658:658 E/Layer]
[Surface(name=AppWindowToken{7e88df8 token=Token{dc2515b ActivityRecord{f93486a u0 com.dp.logcatapp/.activities.MainActivity t3956}}})/@0xd5c3482 - animation-leash#0] No local sync point found

[11-10 19:52:48.939 2053:2053 W/HeadsUpManagerPhone]
onHeadsUpPinnedModeChanged

[11-10 19:52:49.419 2053:2053 D/StatusBar]
disable<e i a s b h r c s > disable2<q i n >


Thanks for your interest so far!

ah ok you’re using an app to read the log. In those 51 loglines nothing of interest can be seen. The log can be very talkative, so you need narrow it down to the specific second. To help with this, use the “schedule message” feature to set it to a specific minute (behind the plus symbol in the entry box of Message). Then scroll to this time in your com.dp.logcatapp afterwards.

the E/Layer for com.moez.QKSMS/.feature.compose.ComposeActivity is just drawing canvas / UI related and can be ignored.

The uppercase letter “E” before the facility “Layer” constitutes the errorlevel, E being an error, W warning, D debug… etc

The only messaging relevant line is D/MmsService getAutoPersisting - but nothing surrounding it… so I wait for more loglines hitting “mms” or “sms”

Edit: I had a look what sending an sms (successfully) looks like, for reference:

W MmsServiceBroker: MmsService not connected. Try connecting...
I MmsServiceBroker: Connecting to MmsService
W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.bindService:1649 com.android.server.MmsServiceBroker.tryConnecting:242 com.android.server.MmsServiceBroker.access$000:54 com.android.server.MmsServiceBroker$1.handleMessage:84 android.os.Handler.dispatchMessage:107 
E MmsServiceBroker: Can not connect to MmsService (timed out)
D MmsService: onCreate
I MmsServiceBroker: MmsService connected
I MmsService: MmsConfigManager receiver action: android.intent.action.SIM_STATE_CHANGED
I MmsService: MmsConfigManager receiver action: android.telephony.action.CARRIER_CONFIG_CHANGED
I MmsService: MmsConfigManager loads in background mcc/mnc: ...
1 Like

Hi tc again! Thanks for you explanation! “talkative” is one way of saying it ;M

I sent a message at 21:35. After a short time 21:35:04 the headsup notification appears saying it could not be sent. I hope its fine with the app, I just arrived home and could reply you quicker that way :wink:

Here is the logcat again:

<<< log_count = 151 >>>
[11-10 21:34:49.287 820:1361 W/qti_sensors_hal]
reportSingleEvent: NearBy 0, pData 123, pOffset -54, highThres 0, lowThres 3

[11-10 21:34:50.438 3195:3195 D/NotificationRepository]
updateNotification() called with: list = [3]

[11-10 21:35:00.011 2053:2053 D/KeyguardClockSwitch]
Updating clock: 21:35

[11-10 21:35:04.042 6777:7315 D/MmsService]
getAutoPersisting

[11-10 21:35:04.274 3195:3195 D/NotificationRepository]
updateNotification() called with: list = [4]

[11-10 21:35:04.313 2053:2053 D/StatusBar]
disable<e i a s b h r c s > disable2<q i n >

[11-10 21:35:04.318 2053:2053 W/HeadsUpManagerPhone]
onHeadsUpPinnedModeChanged

[11-10 21:35:10.011 2053:2053 W/HeadsUpManagerPhone]
onHeadsUpPinnedModeChanged

[11-10 21:35:10.504 2053:2053 D/StatusBar]
disable<e i a s b h r c s > disable2<q i n >

[11-10 21:34:58.774 1592:2016 I/chatty]
uid=1000(system) ConnectivitySer identical 7 lines

[11-10 21:35:01.820 1592:2016 D/ConnectivityService]
maybeHandleNetworkMonitorMessage: 528388

[11-10 21:35:14.572 1592:1745 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:14.618 751:28566 E/ResolverController]
No valid NAT64 prefix (108, <unspecified>/0)

[11-10 21:35:14.642 5544:25969 I/System.out]
DEBUG IMAP CP: mail.imap.connectionpoolsize: 1

[11-10 21:35:14.643 5544:25969 I/System.out]
DEBUG IMAP CP: mail.imap.connectionpooltimeout: 60000

[11-10 21:35:14.678 751:1115 I/netd]
firewallSetUidRule(2, 10099, 1) <6.79ms>

[11-10 21:35:14.681 3308:3326 D/GmsAuthenticator]
PERSONAL DATA

[11-10 21:35:14.684 3308:3326 E/TEST]
auth_manager_trust_google = 1

[11-10 21:35:14.685 3308:3326 D/GmsAuthManager]
peekAuthToken: eu.faircode.email:17ba15c1af55d925f98b99cea4375d4cdf4c174b:oauth2:https://mail.google.com/

[11-10 21:35:14.685 3308:3326 D/GmsAuthenticator]
getAuthToken: ya29.a0ARrdaM9lXs41piCWeFLV5iHQ4EnGcsUwsidH4uupaSKI1d2Z33MFUygPV6okNZI8ONWVRgC_NnASH72H003UuT5TNwur6A8SuPjH0EW8GUHW2ZyD9PdIDGNGm9JfPQ2Jv0UfCDYC2KXwLDtAsofnL9EgPZwc

[11-10 21:35:14.692 751:1115 I/netd]
firewallSetUidRule(2, 10094, 1) <4.68ms>

[11-10 21:35:14.782 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:15.098 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.098 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.098 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.098 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.098 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.155 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.156 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.157 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.171 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.171 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.171 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.171 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.171 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.171 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.177 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.177 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.177 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.177 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.177 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.177 1592:16706 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:15.234 5544:25969 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:15.243 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() borrowing a connection

[11-10 21:35:15.244 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.244 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.302 5544:25969 I/System.out]
DEBUG IMAP CP: releaseFolderStoreProtocol()

[11-10 21:35:15.308 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() borrowing a connection

[11-10 21:35:15.308 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.308 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.371 5544:25969 I/System.out]
DEBUG IMAP CP: releaseFolderStoreProtocol()

[11-10 21:35:15.372 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() borrowing a connection

[11-10 21:35:15.372 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:15.372 5544:25969 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:15.441 5544:25969 I/System.out]
DEBUG IMAP CP: releaseFolderStoreProtocol()

[11-10 21:35:15.608 5544:28567 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:15.675 1592:16706 I/chatty]
uid=1000(system) Binder:1592_1A identical 2 lines

[11-10 21:35:15.692 1592:16706 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:15.700 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:15.958 5544:28567 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:16.339 5544:28567 W/.faircode.emai]
Long monitor contention with owner FairEmail_bg_account_6_0 (28569) at java.lang.Object com.sun.mail.imap.IMAPFolder.doProtocolCommand(com.sun.mail.imap.IMAPFolder$ProtocolCommand)(SourceFile:-1) waiters=0 in boolean com.sun.mail.imap.IMAPFolder.isOpen() for 253ms

[11-10 21:35:16.340 5544:28567 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:17.453 1592:16706 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:17.193 5544:28567 I/chatty]
uid=10157(eu.faircode.email) idler.103 identical 4 lines

[11-10 21:35:17.394 5544:28567 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:17.468 3308:27592 D/GmsAuthenticator]
PERSONAL DATA

[11-10 21:35:17.472 3308:27592 E/TEST]
auth_manager_trust_google = 1

[11-10 21:35:17.474 3308:27592 D/GmsAuthManager]
peekAuthToken: eu.faircode.email:17ba15c1af55d925f98b99cea4375d4cdf4c174b:oauth2:https://mail.google.com/

[11-10 21:35:17.474 3308:27592 D/GmsAuthenticator]
getAuthToken: ya29.a0ARrdaM9lXs41piCWeFLV5iHQ4EnGcsUwsidH4uupaSKI1d2Z33MFUygPV6okNZI8ONWVRgC_NnASH72H003UuT5TNwur6A8SuPjH0EW8GUHW2ZyD9PdIDGNGm9JfPQ2Jv0UfCDYC2KXwLDtAsofnL9EgPZwc

[11-10 21:35:18.620 5544:28569 I/System.out]
DEBUG IMAP CP: connection pool current size: 0   pool size: 1

[11-10 21:35:18.620 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:19.255 1592:7669 I/chatty]
uid=1000(system) Binder:1592_14 identical 1 line

[11-10 21:35:19.739 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:20.058 1592:2016 D/ConnectivityService]
maybeHandleNetworkMonitorMessage: 528388

[11-10 21:35:20.079 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:21.040 5544:28569 I/chatty]
uid=10157(eu.faircode.email) FairEmail_bg_ac identical 8 lines

[11-10 21:35:21.101 5544:28569 I/System.out]
DEBUG IMAP CP: connection pool current size: 0   pool size: 1

[11-10 21:35:25.284 10336:10362 D/trackworktime]
executing periodic hook

[11-10 21:35:25.286 10336:10362 D/trackworktime]
checking location-based tracking

[11-10 21:35:25.287 10336:10362 D/trackworktime]
location-based tracking service stopped

[11-10 21:35:25.287 10336:10362 D/trackworktime]
checking wifi-based tracking

[11-10 21:35:25.287 10336:10362 D/trackworktime]
wifi-based tracking service stopped

[11-10 21:35:25.321 10336:10362 D/trackworktime]
checking persistent notification

[11-10 21:35:25.322 10336:10362 D/trackworktime]
removed persistent notification

[11-10 21:35:25.322 10336:10362 D/trackworktime]
calculating time sum for DAY containing 2021-11-10

[11-10 21:35:25.322 10336:10362 D/trackworktime]
Fetching events for today

[11-10 21:35:25.322 10336:10362 D/trackworktime]
Time worked: 0

[11-10 21:35:21.053 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:26.130 1592:2016 D/ConnectivityService]
maybeHandleNetworkMonitorMessage: 528388

[11-10 21:35:26.816 1592:1745 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:26.855 751:28583 E/ResolverController]
No valid NAT64 prefix (108, <unspecified>/0)

[11-10 21:35:26.868 5544:25968 I/System.out]
DEBUG IMAP CP: mail.imap.connectionpoolsize: 1

[11-10 21:35:26.869 5544:25968 I/System.out]
DEBUG IMAP CP: mail.imap.connectionpooltimeout: 60000

[11-10 21:35:27.023 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:27.070 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.070 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.070 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.070 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.070 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.088 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.093 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.093 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.094 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.101 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.101 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.101 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.102 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.102 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.102 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.105 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.105 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.105 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.105 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.105 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.121 5544:25968 I/System.out]
DEBUG IMAP CP: releaseStoreProtocol()

[11-10 21:35:27.125 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() borrowing a connection

[11-10 21:35:27.125 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.125 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.140 5544:25968 I/System.out]
DEBUG IMAP CP: releaseFolderStoreProtocol()

[11-10 21:35:27.141 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() borrowing a connection

[11-10 21:35:27.142 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.142 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.160 5544:25968 I/System.out]
DEBUG IMAP CP: releaseFolderStoreProtocol()

[11-10 21:35:27.161 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() borrowing a connection

[11-10 21:35:27.161 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() - connection available -- size: 1

[11-10 21:35:27.161 5544:25968 I/System.out]
DEBUG IMAP CP: getStoreProtocol() -- storeConnectionInUse

[11-10 21:35:27.179 5544:25968 I/System.out]
DEBUG IMAP CP: releaseFolderStoreProtocol()

[11-10 21:35:27.244 5544:28584 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:27.457 5544:28585 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:27.103 1592:7669 I/chatty]
uid=1000(system) Binder:1592_14 identical 1 line

[11-10 21:35:27.254 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:27.465 1592:16706 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:27.683 5544:28586 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)

[11-10 21:35:27.688 1592:7669 E/NotificationService]
Suppressing notification from package by user request.

[11-10 21:35:27.882 5544:28588 W/.faircode.emai]
Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)


I don’t see anything message related but again the D/MmsService:getAutoPersisting …

Do you have adb setup? as a sanity check you could try to invoke a sms on the command line… there will be no log events for this, but it should work:

adb shell service call isms 7 i32 0 s16 "com.android.mms.service" s16 "+123456789" s16 "null" s16 "just\ for\ fun\ !" s16 "null" s16 "null"
1 Like

Okay so lets try with adb again. I send a message with the command you provided. Cleared the logcat just before. Its a mess again ;(

--------- beginning of system
11-11 08:28:34.835  1592  1744 I UsageStatsService: User[0] Flushing usage stats to disk
11-11 08:28:35.075  1592  1744 W AppIdleHistory: Skipping App Idle write for unexpected null package
--------- beginning of main
11-11 08:28:38.607 10739 16605 I [DefaultDispatcher-worker-7] SyncWorker: Scheduling sync-job to run in .0000ns (append=false)
11-11 08:28:38.668  1592  2231 D ConnectivityService: requestNetwork for uid/pid:10185/10739 NetworkRequest [ TRACK_DEFAULT id=547, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10185] ]
11-11 08:28:38.669  6777  6777 D PhoneSwitcherNetworkRequstListener: got request NetworkRequest [ TRACK_DEFAULT id=547, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10185] ] with score 60 and serial -1
11-11 08:28:38.669  1592  2045 D Ethernet: got request NetworkRequest [ TRACK_DEFAULT id=547, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10185] ] with score 60 and serial -1
11-11 08:28:38.669  1592  2012 D WifiNetworkFactory: got request NetworkRequest [ TRACK_DEFAULT id=547, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10185] ] with score 60 and serial -1
11-11 08:28:38.669  1592  2012 D UntrustedWifiNetworkFactory: got request NetworkRequest [ TRACK_DEFAULT id=547, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10185] ] with score 60 and serial -1
11-11 08:28:38.677 10336 10362 D trackworktime: executing periodic hook
11-11 08:28:38.677 10336 10362 D trackworktime: checking location-based tracking
11-11 08:28:38.679 10336 10362 D trackworktime: location-based tracking service stopped
11-11 08:28:38.679 10336 10362 D trackworktime: checking wifi-based tracking
11-11 08:28:38.679 10336 10362 D trackworktime: wifi-based tracking service stopped
11-11 08:28:38.679 10336 10362 D trackworktime: checking persistent notification
11-11 08:28:38.679 10336 10362 D trackworktime: removed persistent notification
11-11 08:28:38.679 10336 10362 D trackworktime: calculating time sum for DAY containing 2021-11-11
11-11 08:28:38.679 10336 10362 D trackworktime: Fetching events for today
11-11 08:28:38.679 10336 10362 D trackworktime: Time worked: 0
11-11 08:28:38.696 10739 16605 I [DefaultDispatcher-worker-7] SyncWorker: Sync worker started (started by SyncNow)
11-11 08:28:38.696 10739 16605 I [DefaultDispatcher-worker-7] SyncService: Performing a sync operation now
11-11 08:28:38.697 10739 16497 I [DefaultDispatcher-worker-3] SyncWorker: Scheduling sync-job to run in 1.0000min (append=true)
11-11 08:28:38.697 10739 16605 I [DefaultDispatcher-worker-7] SyncService: Sync with pr0gramm api
11-11 08:28:38.931 10739 10813 I WM-WorkerWrapper: Worker result SUCCESS for Work [ id=230c7f3d-cb47-4e5e-9393-f8ad4308c409, tags={ com.pr0gramm.app.sync.SyncWorker } ]
11-11 08:28:38.935 10739 10813 I WM-WorkerWrapper: Setting status to enqueued for ffd22e64-2c5a-4a39-b2ae-2e62fb1db277
11-11 08:28:38.957  1592  2016 D ConnectivityService: releasing NetworkRequest [ TRACK_DEFAULT id=547, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED Uid: 10185] ] (release request)
11-11 08:28:40.073   820  1001 W qti_sensors_hal: processReportInd: lux: 26, ch0: 0, ch1: 3407, pData: 148, pOffset: 8
11-11 08:28:52.317  1592  1745 E NotificationService: Suppressing notification from package by user request.
11-11 08:28:52.416  5544 11965 W .faircode.emai: Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)
11-11 08:28:52.484  5544 11966 W .faircode.emai: Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)
11-11 08:28:52.562  5544 11971 W .faircode.emai: Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)
11-11 08:28:52.643  5544 11972 W .faircode.emai: Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)
11-11 08:28:52.733  5544 11973 W .faircode.emai: Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)
11-11 08:29:00.004  2053  2053 D KeyguardClockSwitch: Updating clock: 08:29
11-11 08:29:01.870  6777  7400 W Parcel  : Attempt to read object from Parcel 0x7d125aba68 at offset 228 that is not in the object list
11-11 08:29:02.096  2462  2499 I QcrilOemhookMsgTunnel: [0]processOemHookIndication length=21
11-11 08:29:02.120  2462  2462 D QcrilMsgTunnelIfaceManager: handleMessage what = 0
11-11 08:29:02.156  6777  6777 D ServiceStateProvider: subId=1
11-11 08:29:02.160 16074 16074 D CellBroadcastReceiver: onReceive Intent { act=android.intent.action.SERVICE_STATE flg=0x1000010 cmp=com.android.cellbroadcastreceiver/.CellBroadcastReceiver (has extras) }
11-11 08:29:02.161  2254  2771 D QCNEJ/WwanInfoRelay: onServiceStateChanged: dataState: 2 DDS:1 Notify service state update
11-11 08:29:09.926  1592  1745 E NotificationService: Suppressing notification from package by user request.
11-11 08:29:10.113  5544 11995 W .faircode.emai: Accessing hidden field Lcom/android/org/conscrypt/AbstractConscryptSocket;->socket:Ljava/net/Socket; (blacklist, reflection, denied)
11-11 08:29:10.138  1592 17073 E NotificationService: Suppressing notification from package by user request.
11-11 08:29:10.168  1592  3101 E NotificationService: Suppressing notification from package by user request.
11-11 08:29:10.222  3308 21776 D GmsAuthenticator: getAuthToken: Account {name=PERSONAL}, oauth2:https:PERSONAL, Bundle[{androidPackageName=eu.faircode.email, callerPid=5544, callerUid=10157, notifyOnAuthFailure=true}]
11-11 08:29:10.234  3308 21776 E TEST    : auth_manager_trust_google = 1
11-11 08:29:10.245  3308 21776 D GmsAuthManager: peekAuthToken: PERSONAL
11-11 08:29:10.246  3308 21776 E TEST    : androidId = 4324281124277216334
11-11 08:29:10.246  3308 21776 E TEST    : digest = 1-929a0dca0eee55513280171a8585da7dcd3700f8
11-11 08:29:10.246  3308 21776 E TEST    : lastCheckin = 1636531641445
11-11 08:29:10.246  3308 21776 E TEST    : securityToken = 8338884023417075238
11-11 08:29:10.246  3308 21776 E TEST    : versionInfo = 
11-11 08:29:10.246  3308 21776 E TEST    : deviceDataVersionInfo = ABFEt1UvIYqAxoQHKe9tTmgCzXg7sr3Lym0QqxctUJbpRW-ur-Z9bBFPudNbMDy13WCXCyheIF6MEwL2xz8cEm8zrPCL--DK9Fw-7SHCTa7QGpBYkRJxalbvsdZFfewd5tlHteW-lv6M
11-11 08:29:10.257  3308 21776 D GmsHttpFormClient: -- Request --
11-11 08:29:10.257  3308 21776 D GmsHttpFormClient: androidId=3c02eec58178644e&app=eu.faircode.email&client_sig=17ba15c1af55d925f98b99cea4375d4cdf4c174b&callerPkg=com.google.android.gms&callerSig=38918a453d07199354f8b19af05ec6562ced5788&device_country=us&Email=PERSONAL&google_play_services_version=212158000&is_called_from_account_manager=1&_opt_is_called_from_account_manager=1&lang=en_US&operatorCountry=us&sdk_version=29&service=oauth2%3Ahttps%3A%2F%2Fmail.google.com%2F&source=android&Token=aas_et%2FAKppINZvKKOMjeTAdVbGiiwxKnpbrSdJK6W8F90-w9YklP-1s22T9zS3C0WBVLgOSKC8gxMO0dXepFft7HPFqlNNJ1wFNXGAnCWnXBfJ1-_vcdUbJGC1q-9vYWa-G6t7okNOS2h8_JgGc8_sv7mHkok2MhU-rO8jTPH5lry4E-FgdQJafz-U5KxOlyRxswJSuCGSiNDlI7Fbv2pCrQnBx9Y%3D
11-11 08:29:10.289   751 16914 E ResolverController: No valid NAT64 prefix (113, <unspecified>/0)
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: -- Response --
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: issueAdvice=auto
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: Expiry=1636616648
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: ExpiresInDurationSec=898
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: storeConsentRemotely=1
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: isTokenSnowballed=1
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: grantedScopes=PERSONAL
11-11 08:29:10.521  3308 21776 D GmsHttpFormClient: PERSONAL
11-11 08:29:10.528  3308 21776 E TEST    : auth_manager_trust_google = 1
11-11 08:29:10.570  1592 17073 I AccountManagerService: the accounts changed, sending broadcast of android.accounts.LOGIN_ACCOUNTS_CHANGED
11-11 08:29:10.599   752   752 D Zygote  : Forked child process 16916
11-11 08:29:10.602  1592  1746 I ActivityManager: Start proc 16916:com.android.exchange/u0a139 for broadcast {com.android.exchange/com.android.exchange.ExchangeBroadcastReceiver}
11-11 08:29:10.627 16916 16916 W main    : type=1400 audit(0.0:278): avc: granted { read } for uid=10139 name="u:object_r:net_dns_prop:s0" dev="tmpfs" ino=17171 scontext=u:r:untrusted_app_25:s0:c512,c768 tcontext=u:object_r:net_dns_prop:s0 tclass=file app=com.android.exchange
11-11 08:29:10.640  3308 21776 D GmsAuthenticator: getAuthToken: PERSONAL
11-11 08:29:10.865 16916 16916 D ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.hidl.base-V1.0-java.jar
11-11 08:29:10.865 16916 16916 D ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.hidl.manager-V1.0-java.jar
11-11 08:29:10.865 16916 16916 D ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.hidl.base-V1.0-java.jar
11-11 08:29:10.873 16916 16916 I ndroid.exchang: The ClassLoaderContext is a special shared library.
11-11 08:29:10.932 16916 16916 I ndroid.exchang: Starting a blocking GC Alloc
11-11 08:29:10.932 16916 16916 I ndroid.exchang: Starting a blocking GC Alloc
11-11 08:29:10.951 16916 16916 E ActivityThread: Failed to find provider info for com.android.email.provider
11-11 08:29:10.953 16916 16916 I Exchange: Accounts changed - requesting FolderSync for unsynced accounts
11-11 08:29:10.979   752   752 D Zygote  : Forked child process 16937
11-11 08:29:10.981  1592  1746 I ActivityManager: Start proc 16937:foundation.e.browser/u0a110 for broadcast {foundation.e.browser/org.chromium.chrome.browser.services.AccountsChangedReceiver}
11-11 08:29:11.142 16937 16958 I ation.e.browse: Starting a blocking GC Alloc
11-11 08:29:11.142 16937 16958 I ation.e.browse: Starting a blocking GC Alloc
11-11 08:29:11.147 16937 16959 I ation.e.browse: Waiting for a blocking GC Alloc
11-11 08:29:11.161 16937 16959 I ation.e.browse: WaitForGcToComplete blocked Alloc on AddRemoveAppImageSpace for 14.705ms
11-11 08:29:11.161 16937 16959 I ation.e.browse: Starting a blocking GC Alloc
11-11 08:29:11.215  3308  3326 E FontsProvider: query: content://com.google.android.gms.fonts [_id, file_id, font_ttc_index, font_variation_settings, font_weight, font_italic, result_code] query = ?
11-11 08:29:11.224  3308  3326 D FontsProvider: openFile: content://com.google.android.gms.fonts/file/1337 mode: r
11-11 08:29:11.231   752   752 D Zygote  : Forked child process 16964
11-11 08:29:11.233  1592  1746 I ActivityManager: Start proc 16964:com.google.android.apps.messaging/u0a222 for broadcast {com.google.android.apps.messaging/com.google.apps.tiktok.account.data.device.DeviceAccountsChangedReceiver_Receiver}
11-11 08:29:11.243  3308  3326 E FontsProvider: query: content://com.google.android.gms.fonts [_id, file_id, font_ttc_index, font_variation_settings, font_weight, font_italic, result_code] query = ?
11-11 08:29:11.247  3308  3326 D FontsProvider: openFile: content://com.google.android.gms.fonts/file/1337 mode: r
11-11 08:29:11.256  3308  3326 E FontsProvider: query: content://com.google.android.gms.fonts [_id, file_id, font_ttc_index, font_variation_settings, font_weight, font_italic, result_code] query = ?
11-11 08:29:11.260  3308  3326 D FontsProvider: openFile: content://com.google.android.gms.fonts/file/1337 mode: r
11-11 08:29:11.295 16964 16964 I .apps.messagin: The ClassLoaderContext is a special shared library.

...

Gosh this logcat is too long, cant update all … Though I dont see anything related to MmsServiceBroker …
Should I upload more?

And another update:
I just inserted my SIM to 3 other phones. I could not send SMS on them all… So I guess my SIM-card got wrecked. I updated the SMSC number in the ##4636 … settings on one phone. No change though…
I guess the SIM may be damaged, I alrdy ordered a new one. Thanks alot @tcecyk ! I’ll come back to you if my problem persists!
Greets!

for you, sending an sms really does not leave much in logcat → no mms/sms related lines. I don’t know how to elevate loglevel for those subsystems → qksms itself could be run in debug mode to spew more lines maybe… but yeah you want to send text not debug endlessly, sim card replacement is an easy choice.

A call with carrier support could’ve been interesting, maybe the sending capability got disabled for you at their end, when they noticed too many device changes for the SIM. Will stay a mystery, good luck with the new card.

1 Like

Just to let you know @tcecyk , the new SIM did the magic. Thanks alot again for your helpfulness!
Greets!