search

openstf / stf

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

Logcat works only once when get button is pressed, once stopped cannot get again. #866

Open lalith-b opened 6 years ago

lalith-b commented 6 years ago

What is the issue or idea you have?

Does it only happen on a specific device? Please run adb devices -l and paste the corresponding row. No it happens on most of the devices I have.

Please provide the steps to reproduce the issue.

What is the expected behavior? The log should be retrieved when pressing the get logs button in the UI and the stf backend should return the logs from the entryListener().

Do you see errors or warnings in the stf local output? If so, please paste them or the full log here. 2018-05-03T17:26:39.324Z INF/util:procutil 74777 []lalith Forking "/Users/lalith/Desktop/stf/lib/cli migrate" 2018-05-03T17:26:39.515Z INF/db 74778 [] Connecting to 127.0.0.1:28015 2018-05-03T17:26:39.529Z INF/db:setup 74778 [] Database "stf" already exists 2018-05-03T17:26:39.533Z INF/db:setup 74778 [] Table "users" already exists 2018-05-03T17:26:39.533Z INF/db:setup 74778 [] Table "accessTokens" already exists 2018-05-03T17:26:39.533Z INF/db:setup 74778 [] Table "vncauth" already exists 2018-05-03T17:26:39.533Z INF/db:setup 74778 [] Table "devices" already exists 2018-05-03T17:26:39.533Z INF/db:setup 74778 [] Table "logs" already exists 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Index "users"."adbKeys" already exists 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Index "accessTokens"."email" already exists 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Index "vncauth"."response" already exists 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Index "vncauth"."responsePerDevice" already exists 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Waiting for index "users"."adbKeys" 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Waiting for index "accessTokens"."email" 2018-05-03T17:26:39.541Z INF/db:setup 74778 [] Waiting for index "vncauth"."response" 2018-05-03T17:26:39.542Z INF/db:setup 74778 [] Waiting for index "vncauth"."responsePerDevice" 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Index "devices"."owner" already exists 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Index "devices"."present" already exists 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Index "devices"."providerChannel" already exists 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Index "users"."adbKeys" is ready 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Waiting for index "devices"."owner" 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Waiting for index "devices"."present" 2018-05-03T17:26:39.544Z INF/db:setup 74778 [] Waiting for index "devices"."providerChannel" 2018-05-03T17:26:39.546Z INF/db:setup 74778 [] Index "accessTokens"."email" is ready 2018-05-03T17:26:39.546Z INF/db:setup 74778 [] Index "vncauth"."response" is ready 2018-05-03T17:26:39.546Z INF/db:setup 74778 [] Index "vncauth"."responsePerDevice" is ready 2018-05-03T17:26:39.546Z INF/db:setup 74778 [] Index "devices"."owner" is ready 2018-05-03T17:26:39.546Z INF/db:setup 74778 [] Index "devices"."present" is ready 2018-05-03T17:26:39.547Z INF/db:setup 74778 [] Index "devices"."providerChannel" is ready 2018-05-03T17:26:39.552Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli triproxy app001 --bind-pub tcp://127.0.0.1:7111 --bind-dealer tcp://127.0.0.1:7112 --bind-pull tcp://127.0.0.1:7113" 2018-05-03T17:26:39.554Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli triproxy dev001 --bind-pub tcp://127.0.0.1:7114 --bind-dealer tcp://127.0.0.1:7115 --bind-pull tcp://127.0.0.1:7116" 2018-05-03T17:26:39.555Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli processor proc001 --connect-app-dealer tcp://127.0.0.1:7112 --connect-dev-dealer tcp://127.0.0.1:7115" 2018-05-03T17:26:39.557Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli processor proc002 --connect-app-dealer tcp://127.0.0.1:7112 --connect-dev-dealer tcp://127.0.0.1:7115" 2018-05-03T17:26:39.558Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli reaper reaper001 --connect-push tcp://127.0.0.1:7116 --connect-sub tcp://127.0.0.1:7111" 2018-05-03T17:26:39.559Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli provider --name 2030007493 --min-port 7400 --max-port 7700 --connect-sub tcp://127.0.0.1:7114 --connect-push tcp://127.0.0.1:7116 --group-timeout 900 --public-ip localhost --storage-url http://localhost:7100/ --adb-host 127.0.0.1 --adb-port 5037 --vnc-initial-size 600x800 --mute-master never" 2018-05-03T17:26:39.561Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli auth-mock --port 7120 --secret kute kittykat --app-url http://localhost:7100/" 2018-05-03T17:26:39.563Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli app --port 7105 --secret kute kittykat --auth-url http://localhost:7100/auth/mock/ --websocket-url http://localhost:7110/" 2018-05-03T17:26:39.566Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli api --port 7106 --secret kute kittykat --connect-push tcp://127.0.0.1:7113 --connect-sub tcp://127.0.0.1:7111" 2018-05-03T17:26:39.573Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli websocket --port 7110 --secret kute kittykat --storage-url http://localhost:7100/ --connect-sub tcp://127.0.0.1:7111 --connect-push tcp://127.0.0.1:7113" 2018-05-03T17:26:39.585Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli storage-temp --port 7102" 2018-05-03T17:26:39.600Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli storage-plugin-image --port 7103 --storage-url http://localhost:7100/" 2018-05-03T17:26:39.629Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli storage-plugin-apk --port 7104 --storage-url http://localhost:7100/" 2018-05-03T17:26:39.670Z INF/util:procutil 74777 [] Forking "/Users/lalith/Desktop/stf/lib/cli poorxy --port 7100 --app-url http://localhost:7105/ --auth-url http://localhost:7120/ --api-url http://localhost:7106/ --websocket-url http://localhost:7110/ --storage-url http://localhost:7102/ --storage-plugin-image-url http://localhost:7103/ --storage-plugin-apk-url http://localhost:7104/" 2018-05-03T17:26:39.930Z INF/triproxy 74780 [dev001] PUB socket bound on tcp://127.0.0.1:7114 2018-05-03T17:26:39.934Z INF/triproxy 74780 [dev001] DEALER socket bound on tcp://127.0.0.1:7115 2018-05-03T17:26:39.934Z INF/triproxy 74780 [dev001] PULL socket bound on tcp://127.0.0.1:7116 2018-05-03T17:26:40.068Z INF/triproxy 74779 [app001] PUB socket bound on tcp://127.0.0.1:7111 2018-05-03T17:26:40.071Z INF/triproxy 74779 [app001] DEALER socket bound on tcp://127.0.0.1:7112 2018-05-03T17:26:40.072Z INF/triproxy 74779 [app001] PULL socket bound on tcp://127.0.0.1:7113 2018-05-03T17:26:40.215Z INF/db 74782 [] Connecting to 127.0.0.1:28015 2018-05-03T17:26:40.233Z INF/processor 74782 [proc002] App dealer connected to "tcp://127.0.0.1:7112" 2018-05-03T17:26:40.244Z INF/processor 74782 [proc002] Device dealer connected to "tcp://127.0.0.1:7115" 2018-05-03T17:26:40.285Z INF/reaper 74783 [reaper001] Subscribing to permanent channel "ALL" 2018-05-03T17:26:40.285Z INF/db 74781 [] Connecting to 127.0.0.1:28015 2018-05-03T17:26:40.288Z INF/reaper 74783 [reaper001] Reaping devices with no heartbeat 2018-05-03T17:26:40.294Z INF/db 74783 [reaper001] Connecting to 127.0.0.1:28015 2018-05-03T17:26:40.300Z INF/reaper 74783 [reaper001] Receiving input from "tcp://127.0.0.1:7111" 2018-05-03T17:26:40.300Z INF/reaper 74783 [reaper001] Sending output to "tcp://127.0.0.1:7116" 2018-05-03T17:26:40.301Z INF/poorxy 74792 [] Listening on port 7100 2018-05-03T17:26:40.312Z INF/processor 74781 [proc001] App dealer connected to "tcp://127.0.0.1:7112" 2018-05-03T17:26:40.313Z INF/processor 74781 [proc001] Device dealer connected to "tcp://127.0.0.1:7115" 2018-05-03T17:26:40.396Z INF/auth-mock 74785 [] Listening on port 7120 2018-05-03T17:26:40.435Z INF/storage:plugins:image 74790 [] Listening on port 7103 2018-05-03T17:26:40.436Z INF/provider 74784 [] Subscribing to permanent channel "3X2HD/6QR2aC+d5Cc/loCw==" 2018-05-03T17:26:40.467Z INF/provider 74784 [] Sending output to "tcp://127.0.0.1:7116" 2018-05-03T17:26:40.468Z INF/provider 74784 [] Receiving input from "tcp://127.0.0.1:7114" 2018-05-03T17:26:40.474Z INF/provider 74784 [] Tracking devices 2018-05-03T17:26:40.476Z INF/provider 74784 [] Found device "98885135314e35354e" (device) 2018-05-03T17:26:40.494Z INF/storage:plugins:apk 74791 [] Listening on port 7104 2018-05-03T17:26:40.517Z INF/storage:temp 74789 [] Listening on port 7102 2018-05-03T17:26:40.527Z INF/provider 74784 [] Registered device "98885135314e35354e" 2018-05-03T17:26:40.528Z INF/reaper 74783 [reaper001] Device "98885135314e35354e" is present 2018-05-03T17:26:40.626Z INF/app 74786 [] Using pre-built resources 2018-05-03T17:26:40.643Z INF/app 74786 [] Listening on port 7105 2018-05-03T17:26:40.645Z INF/db 74786 [] Connecting to 127.0.0.1:28015 2018-05-03T17:26:40.698Z INF/websocket 74788 [] Subscribing to permanent channel "ALL" 2018-05-03T17:26:40.702Z INF/websocket 74788 [] Listening on port 7110 2018-05-03T17:26:40.704Z INF/db 74788 [] Connecting to 127.0.0.1:28015 2018-05-03T17:26:40.707Z INF/websocket 74788 [] Sending output to "tcp://127.0.0.1:7113" 2018-05-03T17:26:40.708Z INF/websocket 74788 [] Receiving input from "tcp://127.0.0.1:7111" 2018-05-03T17:26:40.890Z INF/api 74787 [] Subscribing to permanent channel "ALL" 2018-05-03T17:26:40.904Z INF/device:support:push 74798 [98885135314e35354e] Sending output to "tcp://127.0.0.1:7116" 2018-05-03T17:26:40.907Z INF/device 74798 [98885135314e35354e] Preparing device 2018-05-03T17:26:40.927Z INF/api 74787 [] Listening on port 7106 2018-05-03T17:26:40.957Z INF/api 74787 [] Sending output to "tcp://127.0.0.1:7113" 2018-05-03T17:26:40.958Z INF/api 74787 [] Receiving input from "tcp://127.0.0.1:7111" 2018-05-03T17:26:41.066Z INF/db 74787 [] Connecting to 127.0.0.1:28015 2018-05-03T17:26:41.234Z INF/device:support:sub 74798 [98885135314e35354e] Receiving input from "tcp://127.0.0.1:7114" 2018-05-03T17:26:41.235Z INF/device:support:sub 74798 [98885135314e35354e] Subscribing to permanent channel "*ALL" 2018-05-03T17:26:41.270Z INF/device:support:properties 74798 [98885135314e35354e] Loading properties 2018-05-03T17:26:41.301Z INF/device:support:sdk 74798 [98885135314e35354e] Supports SDK 26 2018-05-03T17:26:41.302Z INF/device:support:abi 74798 [98885135314e35354e] Supports ABIs arm64-v8a, armeabi-v7a, armeabi 2018-05-03T17:26:41.381Z INF/device:resources:minicap 74798 [98885135314e35354e] Installing "/Users/lalith/Desktop/stf/node_modules/minicap-prebuilt/prebuilt/arm64-v8a/bin/minicap" as "/data/local/tmp/minicap" 2018-05-03T17:26:41.382Z INF/device:resources:minicap 74798 [98885135314e35354e] Installing "/Users/lalith/Desktop/stf/node_modules/minicap-prebuilt/prebuilt/arm64-v8a/lib/android-26/minicap.so" as "/data/local/tmp/minicap.so" 2018-05-03T17:26:41.448Z INF/device:resources:service 74798 [98885135314e35354e] Checking whether we need to install STFService 2018-05-03T17:26:41.902Z INF/device:resources:service 74798 [98885135314e35354e] Running version check 2018-05-03T17:26:42.329Z INF/device:resources:service 74798 [98885135314e35354e] STFService up to date 2018-05-03T17:26:42.331Z INF/device:plugins:service 74798 [98885135314e35354e] Launching agent 2018-05-03T17:26:42.804Z INF/device:plugins:service 74798 [98885135314e35354e] Agent says: "Listening on @stfagent" 2018-05-03T17:26:42.862Z INF/device:plugins:service 74798 [98885135314e35354e] Launching service 2018-05-03T17:26:42.863Z INF/device:plugins:service 74798 [98885135314e35354e] Agent says: "InputClient started" 2018-05-03T17:26:42.909Z INF/device:plugins:display 74798 [98885135314e35354e] Reading display info 2018-05-03T17:26:42.935Z INF/device:plugins:phone 74798 [98885135314e35354e] Fetching phone info 2018-05-03T17:26:42.955Z INF/device:plugins:identity 74798 [98885135314e35354e] Solving identity 2018-05-03T17:26:42.956Z INF/device:plugins:solo 74798 [98885135314e35354e] Subscribing to permanent channel "ny4xLYJddNEKWFjMUpX1P23su5Y=" 2018-05-03T17:26:42.958Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Starting WebSocket server on port 7400 2018-05-03T17:26:43.031Z INF/device:resources:minitouch 74798 [98885135314e35354e] Installing "/Users/lalith/Desktop/stf/node_modules/minitouch-prebuilt/prebuilt/arm64-v8a/bin/minitouch" as "/data/local/tmp/minitouch" 2018-05-03T17:26:43.065Z WRN/device:plugins:data 74798 [98885135314e35354e] Unable to find device data { serial: '98885135314e35354e', platform: 'Android', manufacturer: 'SAMSUNG', operator: 'AT&T', model: 'SM-G955U', version: '8.0.0', abi: 'arm64-v8a', sdk: '26', product: 'dream2qltesq', cpuPlatform: 'msm8998', openGLESVersion: '3.2', display: { id: 0, width: 1080, height: 2220, xdpi: 391.885498046875, ydpi: 394.321533203125, fps: 60.000003814697266, density: 2.625, rotation: 0, secure: true, size: 6.268258154652902, url: 'ws://localhost:7400' }, phone: { imei: '**', I removed these for privacy purposes imsi: '**',I removed these for privacy purposes phoneNumber: '+****',I removed these for privacy purposes iccid: '*****',I removed these for privacy purposes network: 'LTE' } } 2018-05-03T17:26:43.069Z INF/device:plugins:touch 74798 [98885135314e35354e] Touch origin is top left 2018-05-03T17:26:43.069Z INF/device:plugins:touch 74798 [98885135314e35354e] Requesting touch consumer to start 2018-05-03T17:26:43.070Z INF/device:plugins:touch 74798 [98885135314e35354e] Launching touch service 2018-05-03T17:26:43.076Z INF/device:plugins:touch 74798 [98885135314e35354e] Connecting to minitouch service 2018-05-03T17:26:43.086Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Note: device /dev/input/mouse2 is not supported by libevdev" 2018-05-03T17:26:43.087Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Note: device /dev/input/mice is not supported by libevdev" 2018-05-03T17:26:43.087Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Note: device /dev/input/mouse1 is not supported by libevdev" 2018-05-03T17:26:43.087Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Note: device /dev/input/mouse0 is not supported by libevdev" 2018-05-03T17:26:43.088Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Note: device /dev/input/event3 was outscored by /dev/input/event2 (2109 >= 1109)" 2018-05-03T17:26:43.088Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Type B touch device sec_touchscreen (1439x2959 with 10 contacts) detected on /dev/input/event2 (score 2109)" 2018-05-03T17:26:43.190Z INF/device:plugins:touch 74798 [98885135314e35354e] Reading minitouch banner 2018-05-03T17:26:43.194Z INF/device:plugins:touch 74798 [98885135314e35354e] minitouch says: "Connection established" 2018-05-03T17:26:43.195Z INF/device:plugins:vnc 74798 [98885135314e35354e] Starting VNC server on port 7402 2018-05-03T17:26:43.197Z INF/device:plugins:browser 74798 [98885135314e35354e] Loading browser list 2018-05-03T17:26:43.226Z INF/device:plugins:browser 74798 [98885135314e35354e] Updating browser list 2018-05-03T17:26:43.229Z INF/device:plugins:mute 74798 [98885135314e35354e] Will not mute master volume 2018-05-03T17:26:43.298Z INF/device:resources:minirev 74798 [98885135314e35354e] Installing "/Users/lalith/Desktop/stf/vendor/minirev/arm64-v8a/minirev" as "/data/local/tmp/minirev" 2018-05-03T17:26:43.339Z INF/device:plugins:forward 74798 [98885135314e35354e] Launching reverse port forwarding service 2018-05-03T17:26:43.344Z INF/device:plugins:forward 74798 [98885135314e35354e] Connecting to reverse port forwarding service 2018-05-03T17:26:43.899Z INF/device:plugins:connect 74798 [98885135314e35354e] Listening on port 7401 2018-05-03T17:26:43.902Z INF/device 74798 [98885135314e35354e] Fully operational 2018-05-03T17:26:50.487Z INF/provider 74784 [*] Providing all 1 device(s) 2018-05-03T17:26:53.842Z IMP/device:plugins:group 74798 [98885135314e35354e] Now owned by "test@test.com" 2018-05-03T17:26:53.842Z INF/device:plugins:group 74798 [98885135314e35354e] Subscribing to group channel "BxeBwZ+rSCGKJn1ANpi8BA==" 2018-05-03T17:26:54.004Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Setting frame producer projection to 862x1370 2018-05-03T17:26:54.004Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Requesting frame producer to start 2018-05-03T17:26:54.006Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Launching screen service 2018-05-03T17:26:54.008Z IMP/device:plugins:connect 74798 [98885135314e35354e] Remote Connect Started for device "98885135314e35354e" at "localhost:7401" 2018-05-03T17:26:54.092Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "PID: 30161" 2018-05-03T17:26:54.092Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: Using projection 1080x2220@666x1370/0" 2018-05-03T17:26:54.093Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:241) Creating SurfaceComposerClient" 2018-05-03T17:26:54.095Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:244) Performing SurfaceComposerClient init check" 2018-05-03T17:26:54.095Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:255) Creating virtual display" 2018-05-03T17:26:54.096Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:261) Creating buffer queue" 2018-05-03T17:26:54.096Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:264) Setting buffer options" 2018-05-03T17:26:54.097Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:268) Creating CPU consumer" 2018-05-03T17:26:54.097Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:272) Creating frame waiter" 2018-05-03T17:26:54.097Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (external/MY_minicap/src/minicap_26.cpp:276) Publishing virtual display" 2018-05-03T17:26:54.098Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Connecting to minicap service 2018-05-03T17:26:54.107Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (jni/minicap/JpgEncoder.cpp:64) Allocating 7261188 bytes for JPG encoder" 2018-05-03T17:26:54.211Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] minicap says: "INFO: (jni/minicap/minicap.cpp:448) New client connection" 2018-05-03T17:26:54.212Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Reading minicap banner 2018-05-03T17:27:12.280Z INF/device:plugins:logcat 74798 [98885135314e35354e] Starting logcat 2018-05-03T17:27:12.372Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:11.489Z, pid: 30196, tid: 30196, priority: 6, tag: 'propClient', message: 'PropClient failed to load' } 2018-05-03T17:27:12.565Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:11.686Z, pid: 631, tid: 761, priority: 4, tag: 'qti_sensors_hal', message: 'processReportInd: Accel - X: 0.166 Y: 0.107 Z: 9.731' } 2018-05-03T17:27:12.648Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:11.769Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'pcf : 0x1012' } 2018-05-03T17:27:12.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:11.769Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'thread id : 1, preenroll_flag : 0, nd cnt : 0, cso : 0' } 2018-05-03T17:27:12.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:11.769Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'FPBAuthService, 7733' } 2018-05-03T17:27:13.111Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.232Z, pid: 3897, tid: 4521, priority: 4, tag: 'WifiTrafficPoller', message: 'mCpuCoreBooster Lock' } 2018-05-03T17:27:13.137Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.260Z, pid: 661, tid: 661, priority: 4, tag: 'HWComposer', message: 'getActiveConfigs: Attempted to access invalid display -1' } 2018-05-03T17:27:13.282Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.403Z, pid: 3897, tid: 4641, priority: 3, tag: 'WifiConnectivityMonitor', message: ' [] [|203]' } 2018-05-03T17:27:13.371Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.492Z, pid: 12179, tid: 12551, priority: 3, tag: 'com.facebook.appevents.AppEventsLogger', message: 'Got unexpected exception: java.io.WriteAbortedException: writing aborted; java.io.NotSerializableException: org.json.JSONObject' } 2018-05-03T17:27:13.647Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.769Z, pid: 5600, tid: 5600, priority: 3, tag: 'io_stats', message: '!@ 8,0 r 3492961 113468096 w 1495977 27724392 d 107024 8681360 f 585906 624927 iot 2501000 1479910 th 307200 0 0 pt 0 inp 0 0 96096.398' } 2018-05-03T17:27:13.647Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.770Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'pcf : 0x1012' } 2018-05-03T17:27:13.648Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.770Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'thread id : 1, preenroll_flag : 0, nd cnt : 0, cso : 0' } 2018-05-03T17:27:13.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:12.770Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'FPBAuthService, 7733' } 2018-05-03T17:27:14.024Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.148Z, pid: 3897, tid: 8725, priority: 3, tag: 'SSRM:B', message: 'SIOP:: AP = 310, PST = 314 (W:15), BAT = 294, USB = 288, CHG = 320, CP = 307' } 2018-05-03T17:27:14.112Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.234Z, pid: 3897, tid: 4521, priority: 4, tag: 'WifiTrafficPoller', message: 'mCpuCoreBooster Lock' } 2018-05-03T17:27:14.137Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.261Z, pid: 661, tid: 661, priority: 4, tag: 'HWComposer', message: 'getActiveConfigs: Attempted to access invalid display -1' } 2018-05-03T17:27:14.648Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.770Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'pcf : 0x1012' } 2018-05-03T17:27:14.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.770Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'thread id : 1, preenroll_flag : 0, nd cnt : 0, cso : 0' } 2018-05-03T17:27:14.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.770Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'FPBAuthService, 7733' } 2018-05-03T17:27:14.713Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:13.833Z, pid: 29498, tid: 29655, priority: 3, tag: 'com.facebook.appevents.AppEventsLogger', message: 'Got unexpected exception: java.io.WriteAbortedException: writing aborted; java.io.NotSerializableException: org.json.JSONObject' } 2018-05-03T17:27:15.115Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:14.237Z, pid: 3897, tid: 4521, priority: 4, tag: 'WifiTrafficPoller', message: 'mCpuCoreBooster Lock' } 2018-05-03T17:27:15.138Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:14.262Z, pid: 661, tid: 661, priority: 4, tag: 'HWComposer', message: 'getActiveConfigs: Attempted to access invalid display -1' } 2018-05-03T17:27:15.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:14.771Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'pcf : 0x1012' } 2018-05-03T17:27:15.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:14.771Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'thread id : 1, preenroll_flag : 0, nd cnt : 0, cso : 0' } 2018-05-03T17:27:15.649Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:14.771Z, pid: 3642, tid: 6128, priority: 3, tag: 'bauth_FPBAuthService', message: 'FPBAuthService, 7733' } 2018-05-03T17:27:16.118Z INF/device:plugins:logcat 74798 [98885135314e35354e] Log Entry:entryListener ::: Entry { date: 2018-05-03T17:27:15.239Z, pid: 3897, tid: 4521, priority: 4, tag: 'WifiTrafficPoller', message: 'mCpuCoreBooster Lock' } 2018-05-03T17:27:16.444Z INF/device:plugins:logcat 74798 [98885135314e35354e] Stopping logcat 2018-05-03T17:27:31.969Z INF/device:plugins:logcat 74798 [98885135314e35354e] Starting logcat 2018-05-03T17:27:36.223Z INF/device:plugins:logcat 74798 [98885135314e35354e] Stopping logcat 2018-05-03T17:27:39.913Z INF/device:plugins:logcat 74798 [98885135314e35354e] Starting logcat 2018-05-03T17:27:46.907Z INF/device:plugins:logcat 74798 [98885135314e35354e] Stopping logcat 2018-05-03T17:27:52.640Z INF/util:lifecycle 74786 [] Winding down for graceful exit 2018-05-03T17:27:52.640Z INF/util:lifecycle 74785 [] Winding down for graceful exit 2018-05-03T17:27:52.640Z INF/util:lifecycle 74798 [98885135314e35354e] Winding down for graceful exit 2018-05-03T17:27:52.640Z INF/cli:local 74777 [] Received SIGINT, waiting for processes to terminate 2018-05-03T17:27:52.640Z INF/auth-mock 74785 [] Waiting for client connections to end 2018-05-03T17:27:52.641Z INF/util:lifecycle 74783 [reaper001] Winding down for graceful exit 2018-05-03T17:27:52.643Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Requesting frame producer to stop 2018-05-03T17:27:52.643Z INF/util:lifecycle 74782 [proc002] Winding down for graceful exit 2018-05-03T17:27:52.643Z INF/util:lifecycle 74786 [] Winding down for graceful exit 2018-05-03T17:27:52.643Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Disconnecting from minicap service 2018-05-03T17:27:52.644Z INF/util:lifecycle 74781 [proc001] Winding down for graceful exit 2018-05-03T17:27:52.645Z INF/util:lifecycle 74780 [dev001] Winding down for graceful exit 2018-05-03T17:27:52.645Z INF/util:lifecycle 74779 [app001] Winding down for graceful exit 2018-05-03T17:27:52.645Z INF/device:plugins:screen:stream 74798 [98885135314e35354e] Requesting frame producer to stop 2018-05-03T17:27:52.645Z INF/util:lifecycle 74788 [] Winding down for graceful exit 2018-05-03T17:27:52.640Z INF/util:lifecycle 74784 [] Winding down for graceful exit 2018-05-03T17:27:52.646Z INF/util:lifecycle 74787 [] Winding down for graceful exit 2018-05-03T17:27:52.647Z IMP/device:plugins:group 74798 [98885135314e35354e] No longer owned by "test@test.com" 2018-05-03T17:27:52.647Z INF/device:plugins:group 74798 [98885135314e35354e] Unsubscribing from group channel "BxeBwZ+rSCGKJn1ANpi8BA==" 2018-05-03T17:27:52.648Z INF/util:lifecycle 74788 [] Winding down for graceful exit 2018-05-03T17:27:52.649Z INF/util:lifecycle 74787 [] Winding down for graceful exit 2018-05-03T17:27:52.649Z WRN/db 74782 [proc002] Connection closed 2018-05-03T17:27:52.650Z INF/util:lifecycle 74784 [] Winding down for graceful exit 2018-05-03T17:27:52.650Z INF/provider 74784 [] Cleaning up device worker "98885135314e35354e" 2018-05-03T17:27:52.651Z INF/provider 74784 [] Gracefully killing device worker "98885135314e35354e" 2018-05-03T17:27:52.651Z WRN/db 74786 [] Connection closed 2018-05-03T17:27:52.651Z WRN/db 74783 [reaper001] Connection closed 2018-05-03T17:27:52.652Z WRN/db 74781 [proc001] Connection closed 2018-05-03T17:27:52.652Z WRN/db 74787 [] Connection closed 2018-05-03T17:27:52.653Z WRN/db 74788 [] Connection closed 2018-05-03T17:27:52.663Z INF/provider 74784 [*] Device worker "98885135314e35354e" has retired

Please run stf doctor and paste the output here. 2018-05-03T17:21:28.235Z INF/cli:doctor 70520 [] OS Arch: x64 2018-05-03T17:21:28.237Z INF/cli:doctor 70520 [] OS Platform: darwin 2018-05-03T17:21:28.237Z INF/cli:doctor 70520 [] OS Platform: 15.2.0 2018-05-03T17:21:28.237Z INF/cli:doctor 70520 [] Using Node 8.9.1 2018-05-03T17:21:28.261Z INF/cli:doctor 70520 [] Using ZeroMQ 4.2.2 2018-05-03T17:21:28.293Z INF/cli:doctor 70520 [] Using ADB 1.0.39 2018-05-03T17:21:28.309Z INF/cli:doctor 70520 [] Using GraphicsMagick 1.3.26 2018-05-03T17:21:28.314Z INF/cli:doctor 70520 [] Using RethinkDB 2.3.5 2018-05-03T17:21:28.350Z INF/cli:doctor 70520 [*] Using ProtoBuf 3.3.2

thinkhy commented 6 years ago

logcat works after refreshing your page in web browser? (it will force websocket connection recreated) Also check how many logcat processed started on your device when you restart logcat on web UI.

lalith-b commented 6 years ago

no, it works only once the button ('Get') is pressed and once ('Stop') is pressed it stops and ('Get') never works again. Browser refresh solves nothing. If you'd notice the above logs are from the remote/webserver.

Also I noted that when running 'adb logcat' in command line and then running the above steps will not work even once.