search

openstf / stf

Control and manage Android devices from your browser.
https://openstf.io
Other
13.37k stars 2.79k forks source link

cannot control tablet running OS 4.0.3 sdk 15 armeabi-v7a #1105

Open pgrandin opened 5 years ago

pgrandin commented 5 years ago 
$ stf --version 
3.4.1

What is the issue or idea you have? Tablet device running OS 4.0.3 cannot be used. The device is listed as available, but when trying to take control of the screen, after a few seconds openstf says that the device was stolen by someone. At that point, the remote debug connection string will change (the port will be different after each attempt).

Does it only happen on a specific device? Please run adb devices -l and paste the corresponding row. Yes. I have 3 phones and 1 tablet, the 3 phones are working correctly.

Please provide the steps to reproduce the issue. Factory-reset the tablet Connect to stf Once device is listed, try to take control via the UI

What is the expected behavior? Being able to take control of the tablet via the UI, and having a stable adb remote debug connection string

Do you see errors or warnings in the stf local output? If so, please paste them or the full log here.

2019-09-28T20:27:18.906Z IMP/device:plugins:group 149 [275501000070896] Now owned by "pierre@me.com"
2019-09-28T20:27:18.908Z INF/device:plugins:group 149 [275501000070896] Subscribing to group channel "W+bTelZ1QPSM/71GC0LYjQ=="
2019-09-28T20:27:19.032Z IMP/device:plugins:connect 149 [275501000070896] Remote Connect Started for device "275501000070896" at "xxx:7445"
2019-09-28T20:27:19.142Z INF/device:plugins:screen:stream 149 [275501000070896] Setting frame producer projection to 321x1668
2019-09-28T20:27:19.148Z INF/device:plugins:screen:stream 149 [275501000070896] Requesting frame producer to start
2019-09-28T20:27:19.151Z INF/device:plugins:screen:stream 149 [275501000070896] Launching screen service
2019-09-28T20:27:19.217Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "PID: 4441"
2019-09-28T20:27:19.218Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: Using projection 800x1280@321x514/3"
2019-09-28T20:27:19.220Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 3074052 bytes for JPG encoder"
2019-09-28T20:27:19.228Z INF/device:plugins:screen:stream 149 [275501000070896] Connecting to minicap service
2019-09-28T20:27:19.242Z INF/device:plugins:screen:stream 149 [275501000070896] Reading minicap banner
2019-09-28T20:27:19.248Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection"
2019-09-28T20:27:19.933Z WRN/device:plugins:screen:stream 149 [275501000070896] Shell keeping minicap running ended unexpectedly
2019-09-28T20:27:19.936Z INF/device:plugins:screen:stream 149 [275501000070896] Disconnecting from minicap service
2019-09-28T20:27:19.945Z INF/device:plugins:screen:stream 149 [275501000070896] Stopping minicap service
2019-09-28T20:27:19.947Z INF/device:plugins:screen:stream 149 [275501000070896] Launching screen service
2019-09-28T20:27:19.997Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "PID: 4445"
2019-09-28T20:27:20.002Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: Using projection 800x1280@321x514/3"
2019-09-28T20:27:20.003Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 3074052 bytes for JPG encoder"
2019-09-28T20:27:20.005Z INF/device:plugins:screen:stream 149 [275501000070896] Connecting to minicap service
2019-09-28T20:27:20.025Z INF/device:plugins:screen:stream 149 [275501000070896] Reading minicap banner
2019-09-28T20:27:20.030Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection"
2019-09-28T20:27:20.699Z WRN/device:plugins:screen:stream 149 [275501000070896] Shell keeping minicap running ended unexpectedly
2019-09-28T20:27:20.702Z INF/device:plugins:screen:stream 149 [275501000070896] Disconnecting from minicap service
2019-09-28T20:27:20.706Z INF/device:plugins:screen:stream 149 [275501000070896] Stopping minicap service
2019-09-28T20:27:20.713Z INF/device:plugins:screen:stream 149 [275501000070896] Launching screen service
2019-09-28T20:27:20.764Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "PID: 4449"
2019-09-28T20:27:20.766Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: Using projection 800x1280@321x514/3"
2019-09-28T20:27:20.770Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 3074052 bytes for JPG encoder"
2019-09-28T20:27:20.772Z INF/device:plugins:screen:stream 149 [275501000070896] Connecting to minicap service
2019-09-28T20:27:20.784Z INF/device:plugins:screen:stream 149 [275501000070896] Reading minicap banner
2019-09-28T20:27:20.785Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection"
2019-09-28T20:27:21.453Z WRN/device:plugins:screen:stream 149 [275501000070896] Shell keeping minicap running ended unexpectedly
2019-09-28T20:27:21.455Z INF/device:plugins:screen:stream 149 [275501000070896] Disconnecting from minicap service
2019-09-28T20:27:21.459Z INF/device:plugins:screen:stream 149 [275501000070896] Stopping minicap service
2019-09-28T20:27:21.461Z INF/device:plugins:screen:stream 149 [275501000070896] Launching screen service
2019-09-28T20:27:21.513Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "PID: 4453"
2019-09-28T20:27:21.515Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: Using projection 800x1280@321x514/3"
2019-09-28T20:27:21.522Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 3074052 bytes for JPG encoder"
2019-09-28T20:27:21.524Z INF/device:plugins:screen:stream 149 [275501000070896] Connecting to minicap service
2019-09-28T20:27:21.543Z INF/device:plugins:screen:stream 149 [275501000070896] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection"
2019-09-28T20:27:21.544Z INF/device:plugins:screen:stream 149 [275501000070896] Reading minicap banner
2019-09-28T20:27:22.093Z WRN/device:plugins:screen:stream 149 [275501000070896] Shell keeping minicap running ended unexpectedly
2019-09-28T20:27:22.094Z INF/device:plugins:screen:stream 149 [275501000070896] Disconnecting from minicap service
2019-09-28T20:27:22.099Z FTL/device:plugins:screen:stream 149 [275501000070896] Frame producer had an error Error: Failed more than 3 times in 10000ms
    at FrameProducer._failLimitExceeded (/app/lib/units/device/plugins/screen/stream.js:222:26)
    at FailCounter.emit (/app/node_modules/eventemitter3/index.js:117:35)
    at FailCounter.inc (/app/lib/util/failcounter.js:29:10)
    at FrameProducer._outputEnded (/app/lib/units/device/plugins/screen/stream.js:215:24)
    at RiskyStream.emit (/app/node_modules/eventemitter3/index.js:115:35)
    at RiskyStream.<anonymous> (/app/lib/util/riskystream.js:14:12)
    at emitNone (events.js:91:20)
    at Socket.emit (events.js:185:7)
    at endReadableNT (_stream_readable.js:974:12)
    at _combinedTickCallback (internal/process/next_tick.js:80:11)
    at process._tickCallback (internal/process/next_tick.js:104:9)
2019-09-28T20:27:22.101Z FTL/util:lifecycle 149 [275501000070896] Shutting down due to fatal error
2019-09-28T20:27:22.112Z INF/provider 1 [*] Cleaning up device worker "275501000070896"
2019-09-28T20:27:22.113Z ERR/provider 1 [*] Device worker "275501000070896" died with code 1
2019-09-28T20:27:22.113Z INF/provider 1 [*] Restarting device worker "275501000070896"
2019-09-28T20:27:23.936Z INF/device:support:push 161 [275501000070896] Sending output to "tcp://dev-triproxy:7270"
2019-09-28T20:27:23.948Z INF/device 161 [275501000070896] Preparing device
2019-09-28T20:27:25.093Z INF/device:support:sub 161 [275501000070896] Receiving input from "tcp://dev-triproxy:7250"
2019-09-28T20:27:25.097Z INF/device:support:sub 161 [275501000070896] Subscribing to permanent channel "*ALL"
2019-09-28T20:27:25.194Z INF/device:support:properties 161 [275501000070896] Loading properties
2019-09-28T20:27:25.248Z INF/device:support:sdk 161 [275501000070896] Supports SDK 15
2019-09-28T20:27:25.251Z INF/device:support:abi 161 [275501000070896] Supports ABIs armeabi-v7a, armeabi
2019-09-28T20:27:25.316Z INF/device:resources:minicap 161 [275501000070896] Installing "/app/node_modules/minicap-prebuilt/prebuilt/armeabi-v7a/bin/minicap-nopie" as "/data/local/tmp/minicap"
2019-09-28T20:27:25.322Z INF/device:resources:minicap 161 [275501000070896] Installing "/app/node_modules/minicap-prebuilt/prebuilt/armeabi-v7a/lib/android-15/minicap.so" as "/data/local/tmp/minicap.so"
2019-09-28T20:27:25.698Z INF/device:resources:service 161 [275501000070896] Checking whether we need to install STFService
2019-09-28T20:27:26.011Z INF/device:resources:service 161 [275501000070896] Running version check
2019-09-28T20:27:26.584Z INF/device:resources:service 161 [275501000070896] STFService up to date
2019-09-28T20:27:26.591Z INF/device:plugins:service 161 [275501000070896] Launching agent
2019-09-28T20:27:26.982Z INF/device:plugins:service 161 [275501000070896] Agent says: "Listening on @stfagent"
2019-09-28T20:27:27.007Z INF/device:plugins:service 161 [275501000070896] Launching service
2019-09-28T20:27:27.013Z INF/device:plugins:service 161 [275501000070896] Agent says: "InputClient started"
2019-09-28T20:27:27.331Z INF/device:plugins:display 161 [275501000070896] Reading display info
2019-09-28T20:27:27.428Z INF/device:plugins:phone 161 [275501000070896] Fetching phone info
2019-09-28T20:27:27.443Z INF/device:plugins:identity 161 [275501000070896] Solving identity
2019-09-28T20:27:27.448Z INF/device:plugins:solo 161 [275501000070896] Subscribing to permanent channel "9PK9+QaDiDI514briShMwZMt41k="
2019-09-28T20:27:27.455Z INF/device:plugins:screen:stream 161 [275501000070896] Starting WebSocket server on port 7448
2019-09-28T20:27:27.512Z INF/device:resources:minitouch 161 [275501000070896] Installing "/app/node_modules/minitouch-prebuilt/prebuilt/armeabi-v7a/bin/minitouch-nopie" as "/data/local/tmp/minitouch"
2019-09-28T20:27:27.587Z WRN/device:plugins:data 161 [275501000070896] Unable to find device data { serial: '275501000070896',
  platform: 'Android',
  manufacturer: 'SONY',
  operator: null,
  model: ' Tablet S',
  version: '4.0.3',
  abi: 'armeabi-v7a',
  sdk: '15',
  product: 'SYS_001',
  cpuPlatform: 'tegra',
  openGLESVersion: '2.0',
  display:
   { id: 0,
     width: 800,
     height: 1280,
     xdpi: 161.27,
     ydpi: 161.27,
     size: 9.36,
     density: 1,
     fps: 60,
     secure: false,
     rotation: 270,
     url: 'ws://xxx/d/nuc/275501000070896/7448/' },
  phone: { network: 'UNKNOWN' } }
2019-09-28T20:27:27.605Z INF/device:plugins:touch 161 [275501000070896] Touch origin is top left
2019-09-28T20:27:27.608Z INF/device:plugins:touch 161 [275501000070896] Requesting touch consumer to start
2019-09-28T20:27:27.611Z INF/device:plugins:touch 161 [275501000070896] Launching touch service
2019-09-28T20:27:27.625Z INF/device:plugins:touch 161 [275501000070896] Connecting to minitouch service
2019-09-28T20:27:27.781Z INF/device:plugins:touch 161 [275501000070896] minitouch says: "Note: device /dev/input/js3 is not supported by libevdev"
2019-09-28T20:27:28.089Z INF/device:plugins:touch 161 [275501000070896] minitouch says: "Note: device /dev/input/js2 is not supported by libevdev"
2019-09-28T20:27:28.254Z INF/device:plugins:touch 161 [275501000070896] minitouch says: "Note: device /dev/input/js1 is not supported by libevdev"
2019-09-28T20:27:28.468Z INF/device:plugins:touch 161 [275501000070896] minitouch says: "Note: device /dev/input/js0 is not supported by libevdev"
2019-09-28T20:27:28.818Z INF/device:plugins:touch 161 [275501000070896] minitouch says: "Type A touch device cyttsp-i2c (1279x799 with 2 contacts) detected on /dev/input/event7 (score 11010)"
2019-09-28T20:27:29.188Z INF/device:plugins:touch 161 [275501000070896] minitouch says: "Connection established"
2019-09-28T20:27:29.191Z INF/device:plugins:touch 161 [275501000070896] Reading minitouch banner
2019-09-28T20:27:29.255Z INF/device:plugins:vnc 161 [275501000070896] Starting VNC server on port 7450
2019-09-28T20:27:29.263Z INF/device:plugins:browser 161 [275501000070896] Loading browser list
2019-09-28T20:27:29.302Z INF/device:plugins:browser 161 [275501000070896] Updating browser list
2019-09-28T20:27:29.307Z WRN/device:plugins:browser 161 [275501000070896] Unmapped browser "com.sony.nfx.app.browser"
2019-09-28T20:27:29.320Z INF/device:plugins:mute 161 [275501000070896] Will not mute master volume
2019-09-28T20:27:29.388Z INF/device:resources:minirev 161 [275501000070896] Installing "/app/vendor/minirev/armeabi-v7a/minirev-nopie" as "/data/local/tmp/minirev"
2019-09-28T20:27:29.453Z INF/device:plugins:forward 161 [275501000070896] Launching reverse port forwarding service
2019-09-28T20:27:29.466Z INF/device:plugins:forward 161 [275501000070896] Connecting to reverse port forwarding service
2019-09-28T20:27:30.381Z INF/device:plugins:connect 161 [275501000070896] Listening on port 7449
2019-09-28T20:27:30.390Z INF/device 161 [275501000070896] Fully operational

Please run stf doctor and paste the output here.

$ stf doctor
2019-09-28T20:34:00.768Z INF/cli:doctor 212 [*] OS Arch: x64
2019-09-28T20:34:00.773Z INF/cli:doctor 212 [*] OS Platform: linux
2019-09-28T20:34:00.776Z INF/cli:doctor 212 [*] OS Platform: 4.19.72-gentoo
2019-09-28T20:34:00.776Z INF/cli:doctor 212 [*] Using Node 6.11.2
2019-09-28T20:34:00.822Z INF/cli:doctor 212 [*] Using ZeroMQ 4.1.4
2019-09-28T20:34:00.879Z INF/cli:doctor 212 [*] Using GraphicsMagick 1.3.23
2019-09-28T20:34:00.881Z ERR/cli:doctor 212 [*] RethinkDB is not installed (`rethinkdb` is missing)
2019-09-28T20:34:00.881Z ERR/cli:doctor 212 [*] ProtoBuf is not installed (`protoc` is missing)
2019-09-28T20:34:00.881Z ERR/cli:doctor 212 [*] ADB is not installed (`adb` is missing)

but i'm using stf-poc's docker-compose setup, so the components are in a different container ( https://github.com/nikosch86/stf-poc )

Thanks for your help!

issue-label-bot[bot] commented 5 years ago

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

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

pgrandin commented 5 years ago

Tried this : https://github.com/nikosch86/stf-poc/blob/master/fix-lava-iris.txt

but no luck:

/ # adb devices -l
List of devices attached
9201cd8b4482154a       device usb:2-1.7.4 product:j3x3gxx model:SM_J320H device:j3x3g transport_id:10
520004958d47b5dd       device usb:2-1.7.1 product:j6lteub model:SM_J600G device:j6lte transport_id:9
275501600000974        device usb:2-1.1 transport_id:8
1cc1a437               device usb:2-1.2 product:jasmine model:Mi_A2 device:jasmine_sprout transport_id:4

/ # adb -s 275501600000974 shell
shell@android:/ $ am start -n jp.co.cyberagent.stf/.IdentityActivity
Starting: Intent { cmp=jp.co.cyberagent.stf/.IdentityActivity }
shell@android:/ $ am startservice  -n jp.co.cyberagent.stf/.Service
Starting service: Intent { cmp=jp.co.cyberagent.stf/.Service }
shell@android:/ $
koral-- commented 5 years ago

Could you paste output of adb -s 275501600000974 shell cat /proc/cpuinfo

pgrandin commented 5 years ago

Sure thing.

# adb -s 275501600000974 shell cat /proc/cpuinfo
Processor   : ARMv7 Processor rev 0 (v7l)
processor   : 0
BogoMIPS    : 1987.37

processor   : 1
BogoMIPS    : 1987.37

Features    : swp half thumb fastmult vfp edsp vfpv3 vfpv3d16 
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x1
CPU part    : 0xc09
CPU revision    : 0

Hardware    : nbx03
Revision    : 0000
Serial      : 0000000000000000