search

dotintent / FlutterBleLib

Bluetooth Low Energy library for Flutter with support for simulating peripherals
Apache License 2.0
531 stars 195 forks source link

Missing Notifications / Missing Writes #554

Open claudeHasler opened 3 years ago

claudeHasler commented 3 years ago

My application uses two BLE Characteristics as RX/TX channels to implement a serial communication. For every write to the TX Characteristic I expect to receive an answer via notifying RX characteristic.

Generally this is working great with this library, however occaisionally (between 1000-10000) write/notify pairs I am missing a notification on the RX characteristic. I see three possibilites: The device is not answering my request, or my request is not reaching the device, or the devices answer is not reaching my application. As I cannot (yet) debug the device I'm communication with I'd like to make sure that the error is not on my side.

Is there a way I can confirm that my flutter side writing to the TX characteristic as well as the receiving of the RX notification are not being lost on their way to/from the android/ios host?

mikolak commented 3 years ago

Hi! You can try setting log level to verbose and then running it from the native project (xcode or Android Studio), as flutter run unfortunately does not display native logs. If your command reached radio and failed or a notification was not received, but should've been, it should be in the logs.

claudeHasler commented 3 years ago

Thansk for the reply. Is it enough to run the native app in Android studio and select "verbose" in the logcat menu? The output doesnt seem to be different so far. Do I need to start the app via commandline with a flag? I'm probably searching wrong but I'm not finding a guide on how to enable verbose logging anywhere.

EDIT, its working:

2020-12-07 08:44:05.823 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 2, 3, 232, 112, 239]      msg:29
2020-12-07 08:44:05.824 1462-1637/? D/WifiScoreReport: Score = 58, wifiConnectivityManagerEnabled = false, lowScoreCount = 0
2020-12-07 08:44:05.825 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 3, 240, 0, 2, 208, 234]      msg:29
2020-12-07 08:44:05.828 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:05.916 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 0, 0, 0, 0, 108, 60]      msg:30
2020-12-07 08:44:05.930 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 2, 142, 0, 2, 177, 14]      msg:30
2020-12-07 08:44:05.931 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:06.019 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 64, 192, 0, 0, 121, 192]      msg:31
2020-12-07 08:44:06.022 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 0, 44, 0, 1, 81, 85]      msg:31
**AFTER THIS WRITE IS WHERE THE ERROR APPEARS**
**2020-12-07 08:44:06.024 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:06.321 671-671/? I/aptouch_daemon: check_afe_status, afe[106 120 X1] [ctrl:X40020 ftr:X100] dif[26 -26] raw[33968 30393] [exp:X0 aN:0 lo:0]
2020-12-07 08:44:06.727 689-689/? W/irqbalance: type=1400 audit(0.0:42391): avc: denied { dac_override } for capability=1 scontext=u:r:irqbalance:s0 tcontext=u:r:irqbalance:s0 tclass=capability permissive=0
2020-12-07 08:44:06.730 689-689/? I/irqbalance: Decided to move irq 19 from cpu 1 to cpu 0
2020-12-07 08:44:06.731 689-689/? I/irqbalance: Decided to move irq 13 from cpu 1 to cpu 0
2020-12-07 08:44:06.732 689-689/? I/irqbalance: Decided to move irq 388 from cpu 1 to cpu 0
2020-12-07 08:44:06.732 689-689/? I/irqbalance: Decided to move irq 65 from cpu 1 to cpu 0
2020-12-07 08:44:06.733 689-689/? I/irqbalance: Decided to move irq 62 from cpu 1 to cpu 0**
**BECAUSE I RECEIVED NO RESPONSE I WRITE AGAIN
2020-12-07 08:44:07.030 21859-21896/com.althaus.motorexlynx I/flutter: retrying...(0)
2020-12-07 08:44:07.033 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 0, 44, 0, 1, 81, 85]      msg:32
2020-12-07 08:44:07.035 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:07.154 1418-1436/? I/OAM: [check_file_size:511]/data/vendor/log/hi110x/bfg_log/bfg_log_1 log file beyond size=10240Byte, creat new file
2020-12-07 08:44:07.154 1418-1436/? I/OAM: [rename_old_file:216]rename_old_file
2020-12-07 08:44:07.175 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 2, 3, 232, 112, 239]      msg:32**
2020-12-07 08:44:07.179 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 3, 240, 0, 2, 208, 234]      msg:33
2020-12-07 08:44:07.181 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:07.280 1462-1489/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{ca55d12 type 0 when 1607327167730 PendingIntent{37debc6: PendingIntentRecord{b97e787 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.280 1462-1489/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{2b04ce3 type 0 when 1607327161391 PendingIntent{10b2b22: PendingIntentRecord{731e9b3 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.280 1462-1489/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{71016e0 type 0 when 1607326857753 PendingIntent{2bb3ee3: PendingIntentRecord{d7520e0 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.280 1462-1489/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{f20f774 type 0 when 1607329344024 PendingIntent{93d8be1: PendingIntentRecord{d94d06 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.280 1462-1489/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{426e49d type 0 when 1607327341441 PendingIntent{46e97e: PendingIntentRecord{ec9fedf com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.281 1462-1489/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{274c347 type 0 when 1607329577453 PendingIntent{1fc4aa7: PendingIntentRecord{b54db54 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.319 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 0, 0, 0, 0, 108, 60]      msg:33
2020-12-07 08:44:07.335 1462-1836/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{274c347 type 0 when 1607329577453 PendingIntent{1fc4aa7: PendingIntentRecord{b54db54 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.336 1462-1836/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{71016e0 type 0 when 1607326857753 PendingIntent{2bb3ee3: PendingIntentRecord{d7520e0 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.336 1462-1836/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{426e49d type 0 when 1607327341441 PendingIntent{46e97e: PendingIntentRecord{ec9fedf com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.336 1462-1836/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{f20f774 type 0 when 1607329344024 PendingIntent{93d8be1: PendingIntentRecord{d94d06 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.336 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 2, 142, 0, 2, 177, 14]      msg:34
2020-12-07 08:44:07.336 1462-1836/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{2b04ce3 type 0 when 1607327161391 PendingIntent{10b2b22: PendingIntentRecord{731e9b3 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.337 1462-1836/? I/AlarmManager: adjustDeliveryTimeBasedOnStandbyBucketLocked alarm:Alarm{ca55d12 type 0 when 1607327167730 PendingIntent{37debc6: PendingIntentRecord{b97e787 com.huawei.hiai broadcastIntent}}com.huawei.hiai}, standbyBucket:40, minElapsed:334641460
2020-12-07 08:44:07.340 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:07.414 1462-1686/? D/HwSelfCureEngine: periodicArpDetection signalLevel = 3, isScreenOn = true , mArpDetectionFailedCnt = 0
2020-12-07 08:44:07.415 1462-1686/? D/wifiserviceimpl: netmask =16777215
2020-12-07 08:44:07.465 1462-1686/? D/HwArpUtils: getGateWayArpResult:Pair{true 12}
2020-12-07 08:44:07.467 1947-2116/? D/HwWifiCHRStateManager: handleMessage : 1121
2020-12-07 08:44:07.468 1947-2116/? E/HwCHRWifiRSSIGroupSummery: updatArpSummery mRSSI:-1  HwCHRWifiRSSIGroup [rssi_index=4, rtt_tcp_packets=0, rtt_tcp_duration=0, rtt_lan_duration=74767, rtt_lan_failures=29, rtt_lan_succ=5455, rtt_lan_max=354, rtt_lan_min=4,same_area_frequency=0,adjacent_area_frequency=0]
2020-12-07 08:44:07.471 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 64, 192, 0, 0, 121, 192]      msg:34
2020-12-07 08:44:07.474 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 0, 44, 0, 1, 81, 85]      msg:35
2020-12-07 08:44:07.476 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:44:07.575 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 2, 3, 232, 112, 239]      msg:35
2020-12-07 08:44:07.579 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 3, 240, 0, 2, 208, 234]      msg:36

Another instance of this error:-------------------------------------------------------------------------

2020-12-07 08:53:02.816 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 0, 0, 0, 0, 108, 60]      msg:4116
2020-12-07 08:53:02.847 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 2, 142, 0, 2, 177, 14]      msg:4117

**AFTER THIS WRITE THE ERROR APPEARS**

2020-12-07 08:53:02.848 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:53:03.102 1418-1436/? I/OAM: [check_file_size:511]/data/vendor/log/hi110x/bfg_log/bfg_log_1 log file beyond size=10240Byte, creat new file
2020-12-07 08:53:03.102 1418-1436/? I/OAM: [rename_old_file:216]rename_old_file

**BUT THIS TIME THE LOG LOOKS DIFFERENT**

2020-12-07 08:53:03.856 21859-21896/com.althaus.motorexlynx I/flutter: retrying...(1)
2020-12-07 08:53:03.857 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 2, 142, 0, 2, 177, 14]      msg:4118
2020-12-07 08:53:03.860 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:53:03.970 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 64, 192, 0, 0, 121, 192]      msg:4117**
2020-12-07 08:53:03.997 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 0, 44, 0, 1, 81, 85]      msg:4119
2020-12-07 08:53:03.998 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:53:04.307 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 2, 3, 232, 112, 239]      msg:4118
2020-12-07 08:53:04.339 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 3, 240, 0, 2, 208, 234]      msg:4120
2020-12-07 08:53:04.340 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:53:04.468 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 0, 0, 0, 0, 108, 60]      msg:4119
2020-12-07 08:53:04.508 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 2, 142, 0, 2, 177, 14]      msg:4121
2020-12-07 08:53:04.509 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:53:04.658 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 64, 192, 0, 0, 121, 192]      msg:4120
2020-12-07 08:53:04.670 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 0, 44, 0, 1, 81, 85]      msg:4122

and another: -----------------------------------------------------------------------------------------------------------------

2020-12-07 08:58:33.236 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 3, 240, 0, 2, 208, 234]      msg:6259
2020-12-07 08:58:33.237 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:58:33.366 1462-1637/? E/WificondControl: Noise: 0, Snr: -1, Chload: -1
2020-12-07 08:58:33.371 1462-1636/? D/WiFi_PRO: MSG_RETURN_AP_CURRY_RSSI rssi = -73
2020-12-07 08:58:33.371 1462-1705/? I/HuaweiWifiWatchdogStateMachine: POta txb txg rxg:0, 2, 0. Lr =0% Totpkt=2
2020-12-07 08:58:33.371 1462-22632/? I/HwUidTcpMonitor: TcpStatisticsRun run
2020-12-07 08:58:33.372 1462-1636/? I/QosMonitor: queryRtt: rtt_type = 3
2020-12-07 08:58:33.372 1462-1636/? I/QosMonitor: postEventFromNative: msg=100,arg1=4,arg2=10
2020-12-07 08:58:33.373 1462-1636/? I/QosMonitor: postEventFromNative: msg=100,arg1=0,arg2=10
2020-12-07 08:58:33.374 1462-1636/? I/MQoS: rtt=76,tcp_tx_pkts=44065,tcp_rx_pkts=39696
2020-12-07 08:58:33.375 1462-1705/? I/HuaweiWifiWatchdogStateMachine: PTcp RTT:0, rtt pkt=0, tcp_rx=0, tcp_tx=0, tcp_reTran=0, rtRate=0
2020-12-07 08:58:33.380 1462-4225/? I/MQoS: QOS_level:0(0),wifi-link;SIM error;use level-0
2020-12-07 08:58:33.892 671-671/? I/aptouch_daemon: check_afe_status, idle out.
2020-12-07 08:58:33.901 671-671/? I/aptouch_daemon: print_log, touch:1, down:1 (+0)
2020-12-07 08:58:33.902 1462-1614/? D/fingerTest: event.x=:724.0 event.y=:10.0
2020-12-07 08:58:33.902 1462-1614/? D/fingerTest: MotionEvent.ACTION_DOWN
2020-12-07 08:58:33.907 1769-1769/? W/HwKeyguardDragHelper: HwKyguardDragHelper onInterceptTouchEvent: 0,canBlocked :false
2020-12-07 08:58:33.911 590-653/? I/PF_FREQ_GRP: [recordLimitCmd:65] [CPU_B] end limit freq 1997000 ~ 2189000
2020-12-07 08:58:33.984 1769-1769/? I/PanelBar: panelExpansionChanged 0.2962963, true
2020-12-07 08:58:33.985 1462-7244/? V/AudioManager: getRingtonePlayer...
2020-12-07 08:58:33.985 1769-1769/? I/KeyguardFingerPrintView: FingerPrint:: onVisibilityChanged visibility = 0,mIsSupportHover=falsefalsetrue
2020-12-07 08:58:33.986 1769-1769/? D/StatusBar: Make expanded visible: expanded visible=false, force=false, panelsEnabled=true
2020-12-07 08:58:33.986 1769-1769/? W/AppHandler: sendImmediateMessage 18 with args (1, 0)
2020-12-07 08:58:33.986 1769-1769/? W/HwLockScreenPanel: Handle message: 18
2020-12-07 08:58:33.988 1769-1769/? D/StatusBar: disable<e I!a S!b h r c na s > disable2<q i n >
2020-12-07 08:58:33.988 1769-1769/? I/HwNavigationBarFragment: disable: < back home recent search >
2020-12-07 08:58:33.988 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:33.988 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:33.990 1462-2655/? V/AudioManager: getRingtonePlayer...
2020-12-07 08:58:33.994 1462-1502/? I/StatusBarDisable: setFlags what=0 which=1 pkg=Window{f946e14 u0 com.althaus.motorexlynx/com.althaus.motorexlynx.MainActivity}
2020-12-07 08:58:34.010 1462-7244/? D/ZrHung.AppEyeTransparentWindow: TransparentWindow mHungConfigStatus = 1,mHungConfigEnable = 
2020-12-07 08:58:34.011 1462-1502/? I/StatusBarDisable: setFlags what=0 which=1 pkg=Window{56e40b8 u0 StatusBar}
2020-12-07 08:58:34.018 1769-2129/? W/libEGL: EGLNativeWindowType 0x7d9db8f010 disconnect failed
2020-12-07 08:58:34.019 1769-1769/? W/View: requestLayout() improperly called by com.android.systemui.statusbar.phone.NotificationsQuickSettingsContainer{c98d7c V.E...... ......ID 0,0-1080,2340 #7f0a0381 app:id/notification_container_parent} during layout: running second layout pass
2020-12-07 08:58:34.019 1769-1769/? W/View: requestLayout() improperly called by com.android.systemui.statusbar.stack.NotificationStackScrollLayout{ff423b6 V.ED..... ......ID 12,0-1068,2124 #7f0a0394 app:id/notification_stack_scroller} during layout: running second layout pass
2020-12-07 08:58:34.021 1769-2129/? D/mali_winsys: EGLint new_window_surface(egl_winsys_display *, void *, EGLSurface, EGLConfig, egl_winsys_surface **, EGLBoolean) returns 0x3000
2020-12-07 08:58:34.031 1769-1769/? D/CameraLayout: onWindowFocusChanged hasWindowFocus = true
2020-12-07 08:58:34.032 554-910/? I/GRALLOC: getCallingPid=600, shrFd=31,fmt=0x1,intFmt=0x200000001,btStrd=4352,size=10399744,pid=554 
         yuv=2,w=1080,h=2340,Stride u=0 v=0,offset u=0 v=0 
         iova_size=10399744,conUsg=0xb00,proUsg=0xb00,   strd=1088,[afbc]HdrStrd=1088 PyldStrd=69632 Scrmbl=2,ionhnd = 1 
2020-12-07 08:58:34.032 1769-1769/? I/PhoneStatusBarView: remove mHideExpandedRunnable
2020-12-07 08:58:34.032 1769-2150/? I/ToolBoxView: updateFlashlightViewInner:isAvailabletrue;isEnabledfalse;lightControllercom.android.systemui.statusbar.policy.FlashlightController@2a7443d
2020-12-07 08:58:34.033 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.033 1769-1769/? I/chatty: uid=10028(com.huawei.HwMultiScreenShot) com.android.systemui identical 1 line
2020-12-07 08:58:34.041 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.043 1462-1502/? W/Settings: Setting device_provisioned has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
2020-12-07 08:58:34.043 1462-1502/? W/Settings: Setting device_provisioned has moved from android.provider.Settings.Secure to android.provider.Settings.Global.
2020-12-07 08:58:34.044 554-910/? I/GRALLOC: getCallingPid=600, shrFd=31,fmt=0x1,intFmt=0x200000001,btStrd=4352,size=10399744,pid=554 
         yuv=2,w=1080,h=2340,Stride u=0 v=0,offset u=0 v=0 
         iova_size=10399744,conUsg=0xb00,proUsg=0xb00,   strd=1088,[afbc]HdrStrd=1088 PyldStrd=69632 Scrmbl=2,ionhnd = 1 
2020-12-07 08:58:34.052 554-910/? I/GRALLOC: getCallingPid=600, shrFd=31,fmt=0x1,intFmt=0x200000001,btStrd=4352,size=10399744,pid=554 
         yuv=2,w=1080,h=2340,Stride u=0 v=0,offset u=0 v=0 
         iova_size=10399744,conUsg=0xb00,proUsg=0xb00,   strd=1088,[afbc]HdrStrd=1088 PyldStrd=69632 Scrmbl=2,ionhnd = 1 
2020-12-07 08:58:34.062 1769-1769/? I/ColorPickUitls: type = 2
2020-12-07 08:58:34.062 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.067 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.075 671-671/? I/aptouch_daemon: print_log, touch:0, down:0 
2020-12-07 08:58:34.075 671-671/? I/aptouch_daemon: report_events, 2
2020-12-07 08:58:34.076 1462-1614/? D/fingerTest: event.x=:731.0 event.y=:215.0
2020-12-07 08:58:34.076 1462-1614/? D/fingerTest: MotionEvent.ACTION_UP
2020-12-07 08:58:34.076 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.079 1769-1769/? I/chatty: uid=10028(com.huawei.HwMultiScreenShot) com.android.systemui identical 2 lines
2020-12-07 08:58:34.083 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.084 1769-1769/? D/HwAppInnerBoostImpl: asyncReportData com.android.systemui,1,1,1,0 interval=179
2020-12-07 08:58:34.085 590-652/? I/PF_FREQ_GRP: [recordLimitCmd:65] [CPU_L] end limit freq 1325000 ~ 1709000
2020-12-07 08:58:34.086 590-654/? I/PF_FREQ_GRP: [recordLimitCmd:65] [GPU] end limit freq 567000000 ~ 999000000
2020-12-07 08:58:34.100 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.217 1769-1769/? I/chatty: uid=10028(com.huawei.HwMultiScreenShot) com.android.systemui identical 7 lines
2020-12-07 08:58:34.233 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.242 21859-21896/com.althaus.motorexlynx I/flutter: retrying...(2)
2020-12-07 08:58:34.243 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 3, 240, 0, 2, 208, 234]      msg:6260
2020-12-07 08:58:34.244 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier
2020-12-07 08:58:34.246 595-595/? E/WifiHAL: enter wifi_get_link_stats
2020-12-07 08:58:34.247 1462-1637/? E/WificondControl: Noise: 0, Snr: -1, Chload: -1
2020-12-07 08:58:34.250 1462-1637/? D/WifiScoreReport: Score = 58, wifiConnectivityManagerEnabled = false, lowScoreCount = 8
2020-12-07 08:58:34.250 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.317 1769-1769/? I/chatty: uid=10028(com.huawei.HwMultiScreenShot) com.android.systemui identical 4 lines
2020-12-07 08:58:34.334 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.334 1769-1769/? D/HwAppInnerBoostImpl: asyncReportData com.android.systemui,1,1,2,0 interval=430
2020-12-07 08:58:34.335 590-652/? I/PF_FREQ_GRP: [recordLimitCmd:59] [CPU_L] start limit freq 480000 ~ 1536000
2020-12-07 08:58:34.336 590-653/? I/PF_FREQ_GRP: [recordLimitCmd:59] [CPU_B] start limit freq 807000 ~ 1824000
2020-12-07 08:58:34.336 590-653/? W/PF_SYS_WRAPPER: [sysOpenAndWriteStr:83] [CPU_B] 1824000 write to /sys/devices/system/cpu/cpu4/cpufreq/scaling_max_freq failed, errno=22 (Invalid argument) 
2020-12-07 08:58:34.336 590-654/? I/PF_FREQ_GRP: [recordLimitCmd:59] [GPU] start limit freq 413000000 ~ 826000000
2020-12-07 08:58:34.337 590-652/? I/PF_FREQ_GRP: [recordLimitCmd:65] [CPU_L] end limit freq 1325000 ~ 1709000
2020-12-07 08:58:34.337 590-653/? I/PF_FREQ_GRP: [recordLimitCmd:65] [CPU_B] end limit freq 1997000 ~ 2189000
2020-12-07 08:58:34.337 590-654/? I/PF_FREQ_GRP: [recordLimitCmd:65] [GPU] end limit freq 567000000 ~ 999000000
2020-12-07 08:58:34.350 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.363 1462-1636/? I/TrafficMonitor: expired arrive. level:-1
2020-12-07 08:58:34.364 1462-1636/? I/TrafficMonitor: update:rxPkts:8,txPkts:0,rxBytes:1054,txBytes:0
2020-12-07 08:58:34.364 1462-1636/? I/TrafficMonitor: start expired. level:-1
2020-12-07 08:58:34.364 1462-1636/? I/TrafficMonitor: gettimer:interval=5000
2020-12-07 08:58:34.364 1462-1636/? I/TrafficMonitor: during_ms:5003,period_ms:2000
2020-12-07 08:58:34.364 1462-1636/? I/TrafficMonitor: count:31,rx_sum:8,tx_sum:0,rxBytes:1054,during_ms:5003,rx_speed:210.6735958424945,tx_speed:0.0,rto:0.0
2020-12-07 08:58:34.368 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.383 1769-1769/? I/chatty: uid=10028(com.huawei.HwMultiScreenShot) com.android.systemui identical 1 line
2020-12-07 08:58:34.400 1769-1769/? I/PhoneStatusBarView: setAlpha with value : 0.0
2020-12-07 08:58:34.409 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 0, 0, 0, 0, 108, 60]      msg:6258

But again the log looks completely different.

The normal communication looks like this, a write followed by a read(notify): 2020-12-07 08:44:05.930 21859-21896/com.althaus.motorexlynx I/flutter: write:[247, 3, 2, 142, 0, 2, 177, 14] msg:30 2020-12-07 08:44:05.931 21859-21859/com.althaus.motorexlynx D/com.polidea.flutter_ble_lib.FlutterBleLibPlugin: on native side observed method: writeCharacteristicForIdentifier 2020-12-07 08:44:06.019 21859-21896/com.althaus.motorexlynx I/flutter: read:[247, 3, 4, 64, 192, 0, 0, 121, 192] msg:31

Does anything in those logs help?

mikolak commented 3 years ago

Hi!

You need to use https://pub.dev/documentation/flutter_ble_lib/latest/flutter_ble_lib/BleManager/setLogLevel.html and pass it LogLeve.verbose and there should be logs from Android's Bluetooth stack.