Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

On Huawei P20, Press get log button in dashboard will make device disconnected #1064

Open
TachunLin opened this issue Jun 24, 2019 · 6 comments
Open

On Huawei P20, Press get log button in dashboard will make device disconnected #1064

TachunLin opened this issue Jun 24, 2019 · 6 comments
Labels
bug

Comments

@TachunLin
Copy link

TachunLin commented Jun 24, 2019 •
edited
Loading

Using STF v3.4.0 from npm

What is the issue or idea you have?
On Huawei P20, Press get button in Logs panel in dashboard will make device auto disconnect

Does it only happen on a specific device? Please run adb devices -l and paste the corresponding row.
Occured on specific device Huawei P20, EML-L29 (Android 9.0)

Please provide the steps to reproduce the issue.
Open Hauwei P20 control page, click the Get button in Logs panel.
No logs display and after a while show "device is discconnected" pop up
Back to device we can find the button displayed in Stop status

What is the expected behavior?
Click the Get button in Logs panel should capture and display logs without auto disconnecting device.
This issue only happen on Huawei P20. It's an Android 9 device

Do you see errors or warnings in the stf local output? If so, please paste them or the full log here.
2019-06-17T08:21:23.415Z INF/device:plugins:group 254412 [UBV7N18410004543] Subscribing to group channel "gDbS/ZwWRzCdsrBhj86idA=="
2019-06-17T08:21:23.480Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Setting frame producer projection to 808x808
2019-06-17T08:21:23.481Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Requesting frame producer to start
2019-06-17T08:21:23.482Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Launching screen service
2019-06-17T08:21:23.571Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "PID: 12803"
2019-06-17T08:21:23.572Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: Using projection 1080x2244@389x808/0"
2019-06-17T08:21:23.572Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:241) Creating SurfaceComposerClient"
2019-06-17T08:21:23.574Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:244) Performing SurfaceComposerClient init check"
2019-06-17T08:21:23.575Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:255) Creating virtual display"
2019-06-17T08:21:23.575Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:261) Creating buffer queue"
2019-06-17T08:21:23.576Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:264) Setting buffer options"
2019-06-17T08:21:23.576Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:268) Creating CPU consumer"
2019-06-17T08:21:23.577Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:272) Creating frame waiter"
2019-06-17T08:21:23.577Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:276) Publishing virtual display"
2019-06-17T08:21:23.579Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Connecting to minicap service
2019-06-17T08:21:23.581Z IMP/device:plugins:connect 254412 [UBV7N18410004543] Remote Connect Started for device "UBV7N18410004543" at "stf.ict888.net:7401"
2019-06-17T08:21:23.602Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 7365636 bytes for JPG encoder"
2019-06-17T08:21:23.699Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Reading minicap banner
2019-06-17T08:21:23.704Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection"
2019-06-17T08:21:35.082Z INF/device:plugins:screen:capture 254412 [UBV7N18410004543] Capturing screenshot
2019-06-17T08:21:35.417Z INF/storage:temp 254359 [] Uploaded "UBV7N18410004543.jpg" to "/tmp/upload_9546343c5cfa76027e6bf9953ad8e38d"
2019-06-17T08:21:35.422Z INF/device:support:storage 254412 [UBV7N18410004543] Uploaded to "/s/image/5de5e070-88f3-4653-941f-e43af7001c9f/UBV7N18410004543.jpg"
2019-06-17T08:21:41.495Z IMP/device:plugins:group 254412 [UBV7N18410004543] No longer owned by "[email protected]"
2019-06-17T08:21:41.498Z INF/device:plugins:group 254412 [UBV7N18410004543] Unsubscribing from group channel "gDbS/ZwWRzCdsrBhj86idA=="
2019-06-17T08:21:41.510Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Requesting frame producer to stop
2019-06-17T08:21:41.511Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Disconnecting from minicap service
2019-06-17T08:21:41.514Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Stopping minicap service
2019-06-17T08:21:41.515Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Sending SIGTERM to minicap
2019-06-17T08:21:41.543Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (jni/minicap/minicap.cpp:197) Received SIGTERM, stopping"
2019-06-17T08:21:41.624Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (jni/minicap/minicap.cpp:516) Closing client connection"
2019-06-17T08:21:41.625Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:291) Destroying virtual display"
2019-06-17T08:21:49.062Z IMP/device:plugins:group 254412 [UBV7N18410004543] Now owned by "[email protected]"
2019-06-17T08:21:49.063Z INF/device:plugins:group 254412 [UBV7N18410004543] Subscribing to group channel "gDbS/ZwWRzCdsrBhj86idA=="
2019-06-17T08:21:49.136Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Keeping 808x808 as current frame producer projection
2019-06-17T08:21:49.137Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Requesting frame producer to start
2019-06-17T08:21:49.137Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Launching screen service
2019-06-17T08:21:49.202Z IMP/device:plugins:connect 254412 [UBV7N18410004543] Remote Connect Started for device "UBV7N18410004543" at "stf.ict888.net:7401"
2019-06-17T08:21:49.249Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "PID: 12903"
2019-06-17T08:21:49.250Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: Using projection 1080x2244@389x808/0"
2019-06-17T08:21:49.250Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:241) Creating SurfaceComposerClient"
2019-06-17T08:21:49.251Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:244) Performing SurfaceComposerClient init check"
2019-06-17T08:21:49.252Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:255) Creating virtual display"
2019-06-17T08:21:49.252Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:261) Creating buffer queue"
2019-06-17T08:21:49.253Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:264) Setting buffer options"
2019-06-17T08:21:49.253Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:268) Creating CPU consumer"
2019-06-17T08:21:49.253Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:272) Creating frame waiter"
2019-06-17T08:21:49.254Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (external/MY_minicap/src/minicap_28.cpp:276) Publishing virtual display"
2019-06-17T08:21:49.254Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Connecting to minicap service
2019-06-17T08:21:49.271Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 7365636 bytes for JPG encoder"
2019-06-17T08:21:49.364Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] Reading minicap banner
2019-06-17T08:21:49.368Z INF/device:plugins:screen:stream 254412 [UBV7N18410004543] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection"
2019-06-17T08:22:06.999Z IMP/device:plugins:connect 254412 [UBV7N18410004543] Remote Connect Started for device "UBV7N18410004543" at "stf.ict888.net:7401"
2019-06-17T08:22:08.677Z IMP/device:plugins:connect 254412 [UBV7N18410004543] Remote Connect Started for device "UBV7N18410004543" at "stf.ict888.net:7401"
2019-06-17T08:22:12.467Z INF/device:plugins:screen:capture 254412 [UBV7N18410004543] Capturing screenshot
2019-06-17T08:22:12.789Z INF/storage:temp 254359 [] Uploaded "UBV7N18410004543.jpg" to "/tmp/upload_07e645e514216e92553ea5eef9ab4538"
2019-06-17T08:22:12.792Z INF/device:support:storage 254412 [UBV7N18410004543] Uploaded to "/s/image/f54af2a8-3045-48dc-be7b-a4daa5061250/UBV7N18410004543.jpg"
2019-06-17T08:22:16.571Z IMP/device:plugins:connect 254412 [UBV7N18410004543] Remote Connect Started for device "UBV7N18410004543" at "stf.ict888.net:7401"
2019-06-17T08:22:28.796Z INF/device:plugins:logcat 254412 [UBV7N18410004543] Starting logcat
events.js:193
throw er; // Unhandled 'error' event
^
Error: Unprocessable entry data '1140 2620 I AppEyeSocketThread: connecting socket: ZRHungServer
06-17 16:22:27.092 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:27.617 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:27.635 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:28.096 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:28.097 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:28.098 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:28.098 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:28.098 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:28.098 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:29.075 1140 1487 I TrafficMonitor: expired arrive. level:-1
06-17 16:22:29.078 1140 1487 I TrafficMonitor: update:rxPkts:1,txPkts:0,rxBytes:328,txBytes:0
06-17 16:22:29.078 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:29.078 1140 1487 I TrafficMonitor: gettimer:interval=5000
06-17 16:22:29.078 1140 1487 I TrafficMonitor: during_ms:5004,period_ms:2000
06-17 16:22:29.078 1140 1487 I TrafficMonitor: count:36,rx_sum:1,tx_sum:0,rxBytes:328,during_ms:5004,rx_speed:65.54756195043966,tx_speed:0.0,rto:0.0
06-17 16:22:29.960 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:29.976 1140 1576 I HuaweiWifiWatchdogStateMachine: POta txb txg rxg:0, 70, 0. Lr =0% Totpkt=70
06-17 16:22:29.980 1140 1487 I QosMonitor: queryRtt: rtt_type = 3
06-17 16:22:29.982 1140 1487 I QosMonitor: postEventFromNative: msg=100,arg1=4,arg2=10
06-17 16:22:29.982 1140 1487 I QosMonitor: postEventFromNative: msg=100,arg1=0,arg2=10
06-17 16:22:29.982 1140 1487 I MQoS : rtt=272,tcp_tx_pkts=878541,tcp_rx_pkts=1295605
06-17 16:22:29.984 1140 1576 I HuaweiWifiWatchdogStateMachine: PTcp RTT:0, rtt pkt=0, tcp_rx=0, tcp_tx=0, tcp_reTran=0, rtRate=0
06-17 16:22:29.987 1140 3683 I MQoS : QOS_level:0(0),wifi-link;SIM error;use level-0
06-17 16:22:30.102 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:30.147 634 634 I chatty : uid=1000(system) /system/bin/surfaceflinger identical 6 lines
06-17 16:22:30.259 634 634 I chatty : uid=1000(system) /system/bin/surfaceflinger identical 6 lines
06-17 16:22:30.640 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:30.671 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:31.106 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:31.250 634 634 I chatty : uid=1000(system) /system/bin/surfaceflinger identical 14 lines
06-17 16:22:32.107 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:32.107 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:32.108 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:32.108 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:32.108 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:32.108 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:33.110 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:33.681 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:33.709 1140 1488 E WificondControl: Noise: -92, Snr: 26, Chload: -1
06-17 16:22:34.080 1140 1487 I TrafficMonitor: expired arrive. level:-1
06-17 16:22:34.082 1140 1487 I TrafficMonitor: update:rxPkts:6,txPkts:4,rxBytes:1562,txBytes:1144
06-17 16:22:34.082 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:34.082 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:34.082 1140 1487 I TrafficMonitor: during_ms:5005,period_ms:2000
06-17 16:22:34.083 1140 1487 I TrafficMonitor: count:37,rx_sum:6,tx_sum:4,rxBytes:1562,during_ms:5005,rx_speed:312.0879120879121,tx_speed:228.57142857142858,rto:1.5
06-17 16:22:34.112 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:34.112 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:34.112 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:34.113 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:34.113 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:34.113 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:36.025 10166 11755 I CastDatabase: Saving the database
06-17 16:22:36.043 10166 11755 I SQLiteCastStore: [CastNetworkInfo] saved: 0, skipped: 0, [CastDeviceInfo]: saved 0, skipped 0, [Paired Guest Mode DeviceInfo] saved: 0, skipped: 0. [ProbedNetworks]: saved 0, skipped: 0, [ProbedSocketAddress]: saved 0, skipped: 0, [Network-Device pairs]: saved: 0
06-17 16:22:36.085 1140 1487 I TrafficMonitor: update:rxPkts:1,txPkts:0,rxBytes:314,txBytes:0
06-17 16:22:36.085 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:36.085 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:36.115 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:36.719 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:36.745 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:37.117 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:37.118 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:37.118 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:37.118 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:37.118 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:37.118 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:37.966 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:37.984 1140 1576 I HuaweiWifiWatchdogStateMachine: POta txb txg rxg:0, 73, 0. Lr =0% Totpkt=73
06-17 16:22:37.986 1140 1487 I QosMonitor: queryRtt: rtt_type = 3
06-17 16:22:37.988 1140 1487 I QosMonitor: postEventFromNative: msg=100,arg1=4,arg2=10
06-17 16:22:37.988 1140 1487 I QosMonitor: postEventFromNative: msg=100,arg1=0,arg2=10
06-17 16:22:37.988 1140 1487 I MQoS : rtt=272,tcp_tx_pkts=878541,tcp_rx_pkts=1295605
06-17 16:22:37.989 1140 1576 I HuaweiWifiWatchdogStateMachine: PTcp RTT:0, rtt pkt=0, tcp_rx=0, tcp_tx=0, tcp_reTran=0, rtRate=0
06-17 16:22:37.994 1140 3683 I MQoS : QOS_level:0(0),wifi-link;SIM error;use level-0
06-17 16:22:38.088 1140 1487 I TrafficMonitor: update:rxPkts:0,txPkts:0,rxBytes:0,txBytes:0
06-17 16:22:38.088 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:38.088 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:39.084 1140 1487 I TrafficMonitor: expired arrive. level:-1
06-17 16:22:39.084 1140 1487 I TrafficMonitor: during_ms:2002,period_ms:2000
06-17 16:22:39.084 1140 1487 I TrafficMonitor: count:39,rx_sum:0,tx_sum:0,rxBytes:0,during_ms:2002,rx_speed:0.0,tx_speed:0.0,rto:0.0
06-17 16:22:39.123 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:39.753 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:39.778 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:40.092 1140 1487 I TrafficMonitor: update:rxPkts:1,txPkts:0,rxBytes:105,txBytes:0
06-17 16:22:40.092 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:40.092 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:40.124 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:40.125 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:40.125 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:40.125 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:40.126 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:40.126 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:40.797 1140 12859 W AwareLog: AwareMem_Reclaim: generateCompressList procsGroups error!
06-17 16:22:41.128 1611 1611 I chatty : uid=10030(com.huawei.desktop.systemui) com.android.systemui identical 1 line
06-17 16:22:42.097 1140 1487 I TrafficMonitor: update:rxPkts:7,txPkts:0,rxBytes:1105,txBytes:0
06-17 16:22:42.097 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:42.098 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:42.788 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:42.813 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:44.101 1140 1487 I TrafficMonitor: update:rxPkts:2,txPkts:0,rxBytes:646,txBytes:0
06-17 16:22:44.101 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:44.101 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:45.093 1140 1487 I TrafficMonitor: expired arrive. level:-1
06-17 16:22:45.094 1140 1487 I TrafficMonitor: during_ms:2004,period_ms:2000
06-17 16:22:45.094 1140 1487 I TrafficMonitor: count:42,rx_sum:2,tx_sum:0,rxBytes:646,during_ms:2004,rx_speed:322.3552894211577,tx_speed:0.0,rto:0.0
06-17 16:22:45.136 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:45.137 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:45.137 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:45.137 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:45.137 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:45.137 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:45.822 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:45.856 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:45.974 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:45.991 1140 1576 I HuaweiWifiWatchdogStateMachine: POta txb txg rxg:0, 76, 0. Lr =0% Totpkt=76
06-17 16:22:45.993 1140 1487 I QosMonitor: queryRtt: rtt_type = 3
06-17 16:22:45.995 1140 1487 I QosMonitor: postEventFromNative: msg=100,arg1=4,arg2=10
06-17 16:22:45.995 1140 1487 I QosMonitor: postEventFromNative: msg=100,arg1=0,arg2=10
06-17 16:22:45.996 1140 1576 I HuaweiWifiWatchdogStateMachine: PTcp RTT:0, rtt pkt=0, tcp_rx=0, tcp_tx=0, tcp_reTran=0, rtRate=0
06-17 16:22:45.997 1140 1487 I MQoS : rtt=272,tcp_tx_pkts=878541,tcp_rx_pkts=1295605
06-17 16:22:46.003 1140 3683 I MQoS : QOS_level:0(0),wifi-link;SIM error;use level-0
06-17 16:22:46.104 1140 1487 I TrafficMonitor: update:rxPkts:0,txPkts:0,rxBytes:0,txBytes:0
06-17 16:22:46.104 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:46.104 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:47.270 1140 1699 W Watchdog: hungtask: writing kick
06-17 16:22:47.272 1140 1217 I EventHub: EventHub monitor: no key events in the past 264s, no motion events in the past 69s
06-17 16:22:48.107 1140 1487 I TrafficMonitor: update:rxPkts:3,txPkts:0,rxBytes:488,txBytes:0
06-17 16:22:48.107 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:48.107 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:48.866 627 627 E WifiHAL : enter wifi_get_link_stats
06-17 16:22:48.896 1140 1488 E WificondControl: Noise: -92, Snr: 25, Chload: -1
06-17 16:22:50.110 1140 1487 I TrafficMonitor: update:rxPkts:1,txPkts:0,rxBytes:78,txBytes:0
06-17 16:22:50.111 1140 1487 I TrafficMonitor: start expired. level:-1
06-17 16:22:50.111 1140 1487 I TrafficMonitor: gettimer:interval=2000
06-17 16:22:50.147 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:50.148 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:50.148 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:50.148 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:50.148 1611 1611 E ndroid.systemu: Invalid ID 0x00000000.
06-17 16:22:50.149 1611 1611 E HwNotchUtils: setIconForView iconId is not found
06-17 16:22:51.106 1140 1487 I TrafficMonitor: expired arrive. level:-1
06-17 16:22:51.106 1140 1487 I TrafficMonitor: during_ms:2004,period_ms:2000
06-17 16:22:51.106 1140 1487 I TrafficMonitor: count:45,rx_sum:1,tx_sum:0,rxBytes:78,during_ms:2004,rx_speed:38.92215568862275,tx_speed:0.0,rto:0.0
06-17 16:22:51.825 1140 1310 I HwBatteryService: quick charge status : 0
06-17 16:22:51.825 1140 1310 I HwBatteryService: handleWirelessTxStatus
06-17 16:22:51.825 1140 1310 I HwBatteryService: switch not open, return, mWirelessTxFlag : 0
06-17 16:22:51.826 1140 1310 I UsbDeviceManager: water_intrused state= 0
06-17 16:22:51.832 1140 1723 I BatteryService: normal charge status : 1
06-17 16:22:51.846 1611 1611 W KeyguardUpdateMonitor: ACTION_BATTERY_CHANGED AMP: 512000; Volt: 5000000
06-17 16:22:51.846 1611 1611 I KgBattery: (last-new)Status: 1 -> 1, chargeState=1 * branch=5
06-17 16:22:51.846 1611 1611 W KeyguardUpdateMonitor: ChargingSpeed Wattage: 2560000 ST: 5000000 --> 7500000
06-17 16:22:51.846 1611 1611 I KgBattery: BatteryInfo updated: 5lPC0
06-17 16:22:51.846 1611 1611 W AppHandler: sendMessage with obj100
06-17 16:22:51.846 1611 1611 I ColorPickUitls: type = 2
06-17 16:22:51.847 1611 1611 W KG_FDT : isFaceUnlockEnabled 0
06-17 16:22:51.847 1611 1611 W HwKgdBArea_BTY: updateBatteryInfo : false true
06-17 16:22:51.847 1611 1611 W HwLockScreenPanel: refreshBatteryInfo: null
06-17 16:22:51.847 1611 1611 W HwKgdBArea_BTY: updateBatteryInfo : false true
06-17 16:22:51.847 1611 1611 W HwLockScreenPanel: Handle message: 100
06-17 16:22:51.848 1611 1796 I KeyguardUtils: fpPath=sys/class/hw_power/charger/charge_data/cha'
at Binary._processEntry (/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/adbkit-logcat/lib/logcat/parser/binary.js:77:26)
at Binary.parse (/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/adbkit-logcat/lib/logcat/parser/binary.js:54:14)
at LineTransform. (/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/adbkit-logcat/lib/logcat/reader.js:84:33)
at emitOne (events.js:125:13)
at LineTransform.emit (events.js:221:7)
at addChunk (_stream_readable.js:265:12)
at readableAddChunk (_stream_readable.js:252:11)
at LineTransform.Readable.push (_stream_readable.js:209:10)
at LineTransform.Transform.push (_stream_transform.js:146:32)
at LineTransform._nullTransform (/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/adbkit/lib/adb/linetransform.js:23:10)
at LineTransform._transform (/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/adbkit/lib/adb/linetransform.js:47:19)
at LineTransform.Transform._read (_stream_transform.js:185:10)
at LineTransform.Transform._write (_stream_transform.js:173:12)
at doWrite (_stream_writable.js:388:12)
at writeOrBuffer (_stream_writable.js:374:5)
at LineTransform.Writable.write (_stream_writable.js:291:11)
at Socket.ondata (_stream_readable.js:642:20)
at emitOne (events.js:125:13)
at Socket.emit (events.js:221:7)
at addChunk (_stream_readable.js:265:12)
at readableAddChunk (_stream_readable.js:252:11)
at Socket.Readable.push (_stream_readable.js:209:10)
2019-06-17T08:22:54.622Z INF/provider 254323 [] Cleaning up device worker "UBV7N18410004543"
2019-06-17T08:22:54.622Z ERR/provider 254323 [] Device worker "UBV7N18410004543" died with code 1
2019-06-17T08:22:54.622Z INF/provider 254323 [*] Restarting device worker "UBV7N18410004543"
2019-06-17T08:22:55.578Z INF/device:support:push 270977 [UBV7N18410004543] Sending output to "tcp://127.0.0.1:7116"
2019-06-17T08:22:55.582Z INF/device 270977 [UBV7N18410004543] Preparing device
2019-06-17T08:22:56.058Z INF/device:support:sub 270977 [UBV7N18410004543] Receiving input from "tcp://127.0.0.1:7114"
2019-06-17T08:22:56.060Z INF/device:support:sub 270977 [UBV7N18410004543] Subscribing to permanent channel "*ALL"
2019-06-17T08:22:56.126Z INF/device:support:properties 270977 [UBV7N18410004543] Loading properties
2019-06-17T08:22:56.164Z INF/device:support:sdk 270977 [UBV7N18410004543] Supports SDK 28
2019-06-17T08:22:56.166Z INF/device:support:abi 270977 [UBV7N18410004543] Supports ABIs arm64-v8a, armeabi-v7a, armeabi
2019-06-17T08:22:56.347Z INF/device:resources:minicap 270977 [UBV7N18410004543] Installing "/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/minicap-prebuilt/prebuilt/arm64-v8a/bin/minicap" as "/data/local/tmp/minicap"
2019-06-17T08:22:56.349Z INF/device:resources:minicap 270977 [UBV7N18410004543] Installing "/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/minicap-prebuilt/prebuilt/arm64-v8a/lib/android-28/minicap.so" as "/data/local/tmp/minicap.so"
2019-06-17T08:22:56.446Z INF/device:resources:service 270977 [UBV7N18410004543] Checking whether we need to install STFService
2019-06-17T08:22:56.519Z INF/device:resources:service 270977 [UBV7N18410004543] Running version check
2019-06-17T08:22:57.028Z INF/device:resources:service 270977 [UBV7N18410004543] STFService up to date
2019-06-17T08:22:57.032Z INF/device:plugins:service 270977 [UBV7N18410004543] Launching agent
2019-06-17T08:22:57.616Z INF/device:plugins:service 270977 [UBV7N18410004543] Agent says: "Listening on @stfagent"
2019-06-17T08:22:57.704Z INF/device:plugins:service 270977 [UBV7N18410004543] Launching service
2019-06-17T08:22:57.717Z INF/device:plugins:service 270977 [UBV7N18410004543] Agent says: "InputClient started"
2019-06-17T08:22:57.802Z INF/device:plugins:display 270977 [UBV7N18410004543] Reading display info
2019-06-17T08:22:57.849Z INF/device:plugins:phone 270977 [UBV7N18410004543] Fetching phone info
2019-06-17T08:22:57.870Z INF/device:plugins:identity 270977 [UBV7N18410004543] Solving identity
2019-06-17T08:22:57.872Z INF/device:plugins:solo 270977 [UBV7N18410004543] Subscribing to permanent channel "JFR8ZLytPJaKrDIlWGKj0JWd86E="
2019-06-17T08:22:57.876Z INF/device:plugins:screen:stream 270977 [UBV7N18410004543] Starting WebSocket server on port 7428
2019-06-17T08:22:58.056Z INF/device:resources:minitouch 270977 [UBV7N18410004543] Installing "/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/node_modules/minitouch-prebuilt/prebuilt/arm64-v8a/bin/minitouch" as "/data/local/tmp/minitouch"
2019-06-17T08:22:58.101Z WRN/device:plugins:data 270977 [UBV7N18410004543] Unable to find device data { serial: 'UBV7N18410004543',
platform: 'Android',
manufacturer: 'HUAWEI',
operator: ',',
model: 'EML-L29',
version: '9',
abi: 'arm64-v8a',
sdk: '28',
product: 'EML-L29',
cpuPlatform: 'kirin970',
openGLESVersion: '3.2',
display:
{ id: 0,
width: 1080,
height: 2244,
xdpi: 428.625,
ydpi: 428.5530090332031,
fps: 60.000003814697266,
density: 3,
rotation: 0,
secure: true,
size: 5.810926384296112,
url: 'ws://stf.ict888.net:7428' },
phone: { imei: '867128039931259', network: 'UMTS' } }
2019-06-17T08:22:58.108Z INF/device:plugins:touch 270977 [UBV7N18410004543] Touch origin is top left
2019-06-17T08:22:58.109Z INF/device:plugins:touch 270977 [UBV7N18410004543] Requesting touch consumer to start
2019-06-17T08:22:58.110Z INF/device:plugins:touch 270977 [UBV7N18410004543] Launching touch service
2019-06-17T08:22:58.118Z INF/device:plugins:touch 270977 [UBV7N18410004543] Connecting to minitouch service
2019-06-17T08:22:58.167Z INF/device:plugins:touch 270977 [UBV7N18410004543] minitouch says: "Note: device /dev/input/mouse0 is not supported by libevdev"
2019-06-17T08:22:58.311Z INF/device:plugins:touch 270977 [UBV7N18410004543] minitouch says: "Note: device /dev/input/mice is not supported by libevdev"
2019-06-17T08:22:58.407Z INF/device:plugins:touch 270977 [UBV7N18410004543] minitouch says: "Type A touch device input_mt_wrapper (1079x2243 with 10 contacts) detected on /dev/input/event4 (score 21555)"
2019-06-17T08:22:58.434Z INF/device:plugins:touch 270977 [UBV7N18410004543] Reading minitouch banner
2019-06-17T08:22:58.436Z INF/device:plugins:touch 270977 [UBV7N18410004543] minitouch says: "Connection established"
2019-06-17T08:22:58.482Z INF/device:plugins:vnc 270977 [UBV7N18410004543] Starting VNC server on port 7430
2019-06-17T08:22:58.486Z INF/device:plugins:browser 270977 [UBV7N18410004543] Loading browser list
2019-06-17T08:22:58.518Z INF/device:plugins:browser 270977 [UBV7N18410004543] Updating browser list
2019-06-17T08:22:58.521Z INF/device:plugins:mute 270977 [UBV7N18410004543] Will not mute master volume
2019-06-17T08:22:58.723Z INF/device:resources:minirev 270977 [UBV7N18410004543] Installing "/usr/local/node/node-v9.0.0-linux-x64/lib/node_modules/stf/vendor/minirev/arm64-v8a/minirev" as "/data/local/tmp/minirev"
2019-06-17T08:22:58.769Z INF/device:plugins:forward 270977 [UBV7N18410004543] Launching reverse port forwarding service
2019-06-17T08:22:58.781Z INF/device:plugins:forward 270977 [UBV7N18410004543] Connecting to reverse port forwarding service
2019-06-17T08:22:58.984Z INF/device:plugins:connect 270977 [UBV7N18410004543] Listening on port 7429
2019-06-17T08:22:58.987Z INF/device 270977 [UBV7N18410004543] Fully operational

Please run stf doctor and paste the output here.
[root@localhost bin]# ./stf doctor
INF/cli:doctor 452224 [] OS Arch: x64
INF/cli:doctor 452224 [] OS Platform: linux
INF/cli:doctor 452224 [] OS Platform: 3.10.0-957.5.1.el7.x86_64
INF/cli:doctor 452224 [] Using Node 9.0.0
INF/cli:doctor 452224 [] Using ZeroMQ 4.2.2
INF/cli:doctor 452224 [] Using RethinkDB 2.3.6
INF/cli:doctor 452224 [] Using GraphicsMagick 1.3.31
INF/cli:doctor 452224 [] Using ADB 1.0.40
INF/cli:doctor 452224 [*] Using ProtoBuf 3.5.0
@issue-label-bot
Copy link

Issue-Label Bot is automatically applying the label bug to this issue, with a confidence of 0.70. Please mark this comment with 👍 or 👎 to give our bot feedback!

Links: app homepage, dashboard and code for this bot.

@issue-label-bot issue-label-bot bot added the bug label Jun 24, 2019
@histronger
Copy link

Same problem with you. Have you already found the solution? Thanks!

@Richard1ybb
Copy link

I encountered the same problem

@TachunLin
Copy link
Author

Same problem with you. Have you already found the solution? Thanks!

Hi @histronger ,
Not yet. It still exists

@histronger
Copy link

histronger commented Aug 27, 2019 •
edited
Loading

I think this problem is the same with openstf/adbkit-logcat#6 @sorccu @TachunLin

@songke
Copy link

songke commented Oct 21, 2019 •
edited
Loading

It seems stf use logcat with binary mode, but huawei disable logcat binary mode when android os greater than 9.0

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@songke @histronger @Richard1ybb @TachunLin