Minicap.apk generates looping warning messages when framerate is lower than ~7.0 (e.g. from 6.0)

[denis99999]

This issue has been identified using the latest version of STF.

minicap/experimental/app/src/main/java/io/devicefarmer/minicap/provider/BaseProvider.kt

Line 93 in 76d6472

log.warn("skipping frame ($currentTime/$previousTimeStamp)")

This issue does not appear when using the minicap binary version.

...
Mar 11 14:49:41 devrdtpi1 docker[404509]: 2025-03-11T13:49:41.741Z INF/device:support:sdk 148 [FA69M0309003] Supports SDK 29
Mar 11 14:49:41 devrdtpi1 docker[404509]: 2025-03-11T13:49:41.743Z INF/device:support:abi 148 [FA69M0309003] Supports ABIs arm64-v8a, armeabi-v7a, armeabi
Mar 11 14:49:42 devrdtpi1 docker[404509]: 2025-03-11T13:49:42.155Z INF/device:resources:minicap 148 [FA69M0309003] Installing "/app/node_modules/@devicefarmer/minicap-prebuilt/prebuilt/arm64-v8a/bin/minicap" as "/data/local/tmp/minicap"
Mar 11 14:49:42 devrdtpi1 docker[404509]: 2025-03-11T13:49:42.156Z INF/device:resources:minicap 148 [FA69M0309003] Installing "/app/node_modules/@devicefarmer/minicap-prebuilt/prebuilt/arm64-v8a/lib/android-29/minicap.so" as "/data/local/tmp/minicap.so"
Mar 11 14:49:42 devrdtpi1 docker[404509]: 2025-03-11T13:49:42.158Z INF/device:resources:minicap 148 [FA69M0309003] Installing "/app/node_modules/@devicefarmer/minicap-prebuilt/prebuilt/noarch/minicap.apk" as "/data/local/tmp/minicap.apk"
Mar 11 14:49:42 devrdtpi1 docker[404509]: 2025-03-11T13:49:42.464Z INF/device:resources:service 148 [FA69M0309003] Checking whether we need to install STFService
Mar 11 14:49:42 devrdtpi1 docker[404509]: 2025-03-11T13:49:42.562Z INF/device:resources:service 148 [FA69M0309003] Running version check
Mar 11 14:49:43 devrdtpi1 docker[404509]: 2025-03-11T13:49:43.004Z WRN/device:resources:service 148 [FA69M0309003] SDK version (29) is lower than 31, permission android.permission.BLUETOOTH_CONNECT not supported
Mar 11 14:49:43 devrdtpi1 docker[404509]: 2025-03-11T13:49:43.005Z INF/device:resources:service 148 [FA69M0309003] Granting permission to STFService: android.permission.SYSTEM_ALERT_WINDOW
Mar 11 14:49:43 devrdtpi1 docker[404509]: 2025-03-11T13:49:43.143Z INF/device:resources:service 148 [FA69M0309003] Permission granted android.permission.SYSTEM_ALERT_WINDOW
Mar 11 14:49:43 devrdtpi1 docker[404509]: 2025-03-11T13:49:43.144Z INF/device:resources:service 148 [FA69M0309003] STFService up to date
Mar 11 14:49:43 devrdtpi1 docker[404509]: 2025-03-11T13:49:43.146Z INF/device:plugins:service 148 [FA69M0309003] Launching agent
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.005Z INF/device:plugins:service 148 [FA69M0309003] Agent says: "Starting minitouch agent"
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.133Z INF/device:plugins:service 148 [FA69M0309003] Launching service
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.134Z INF/device:plugins:service 148 [FA69M0309003] using 'start-foreground-service' command for API 29
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.150Z INF/device:plugins:service 148 [FA69M0309003] Agent says: "Listening on @stfagent"
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.152Z INF/device:plugins:service 148 [FA69M0309003] Agent says: "InputClient started"
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.220Z INF/device:plugins:display 148 [FA69M0309003] Reading display info
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.270Z INF/device:plugins:phone 148 [FA69M0309003] Fetching phone info
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.288Z INF/device:plugins:identity 148 [FA69M0309003] Solving identity
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.294Z INF/device:plugins:solo 148 [FA69M0309003] Subscribing to permanent channel "8k6RuFjo4Hf4uGnMmx/ewCzr9uA="
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.295Z INF/device:plugins:screen:stream 148 [FA69M0309003] ScreenGrabber option set to minicap-apk
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.295Z INF/device:plugins:screen:stream 148 [FA69M0309003] ScreenFrameRate option set to 6
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.296Z INF/device:plugins:screen:stream 148 [FA69M0309003] Starting WebSocket server on port 15000
Mar 11 14:49:44 devrdtpi1 docker[404509]: 2025-03-11T13:49:44.299Z INF/device:plugins:screen:stream 148 [FA69M0309003] creating FrameProducer: minicap-apk
...
Mar 11 14:51:13 devrdtpi1 docker[404509]: 2025-03-11T13:51:13.807Z INF/device:plugins:screen:stream 148 [FA69M0309003] Setting frame producer projection to 692x692
Mar 11 14:51:13 devrdtpi1 docker[404509]: 2025-03-11T13:51:13.809Z INF/device:plugins:screen:stream 148 [FA69M0309003] Requesting frame producer to start
Mar 11 14:51:13 devrdtpi1 docker[404509]: 2025-03-11T13:51:13.810Z INF/device:plugins:screen:stream 148 [FA69M0309003] Launching screen service minicap-apk
Mar 11 14:51:13 devrdtpi1 docker[404509]: 2025-03-11T13:51:13.811Z INF/device:resources:minicap 148 [FA69M0309003] CLASSPATH=/data/local/tmp/minicap.apk app_process /system/bin io.devicefarmer.minicap.Main -S -r 6 -Q 80 -P 1080x1920@692x692/0
Mar 11 14:51:13 devrdtpi1 docker[404509]: 2025-03-11T13:51:13.913Z INF/device:plugins:connect 148 [FA69M0309003] Listening on port 15001
Mar 11 14:51:13 devrdtpi1 docker[404509]: 2025-03-11T13:51:13.915Z IMP/device:plugins:connect 148 [FA69M0309003] Remote Connect Started for device "FA69M0309003" at "192.168.1.26:15001"
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.590Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:47.457 [main] INFO  BaseProvider - framePeriodMs: 166"
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.591Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "PID: 11801"
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.592Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "INFO: 1080x1920@389x692/0"
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.594Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:47.461 [main] INFO  Main - Listening on socket : minicap"
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.595Z INF/device:plugins:screen:stream 148 [FA69M0309003] Connecting to minicap service
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.600Z INF/device:plugins:screen:stream 148 [FA69M0309003] Reading minicap banner
Mar 11 14:51:14 devrdtpi1 docker[404509]: 2025-03-11T13:51:14.773Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:47.641 [main] WARN  BaseProvider - skipping frame (1741701047641/1741701047518)"
Mar 11 14:51:15 devrdtpi1 docker[404509]: 2025-03-11T13:51:15.259Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:48.128 [main] WARN  BaseProvider - skipping frame (1741701048128/1741701047977)"
Mar 11 14:51:15 devrdtpi1 docker[404509]: 2025-03-11T13:51:15.959Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:48.825 [main] WARN  BaseProvider - skipping frame (1741701048824/1741701048658)"
Mar 11 14:51:16 devrdtpi1 docker[404509]: 2025-03-11T13:51:16.612Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:49.479 [main] WARN  BaseProvider - skipping frame (1741701049478/1741701049328)"
Mar 11 14:51:17 devrdtpi1 docker[404509]: 2025-03-11T13:51:17.277Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:50.145 [main] WARN  BaseProvider - skipping frame (1741701050145/1741701050000)"
Mar 11 14:51:18 devrdtpi1 docker[404509]: 2025-03-11T13:51:18.124Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:50.990 [main] WARN  BaseProvider - skipping frame (1741701050990/1741701050825)"
Mar 11 14:51:18 devrdtpi1 docker[404509]: 2025-03-11T13:51:18.793Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:51.660 [main] WARN  BaseProvider - skipping frame (1741701051659/1741701051494)"
Mar 11 14:51:20 devrdtpi1 docker[404509]: 2025-03-11T13:51:20.137Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:53.003 [main] WARN  BaseProvider - skipping frame (1741701053003/1741701052838)"
Mar 11 14:51:20 devrdtpi1 docker[404509]: 2025-03-11T13:51:20.808Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:53.673 [main] WARN  BaseProvider - skipping frame (1741701053673/1741701053508)"
Mar 11 14:51:21 devrdtpi1 docker[404509]: 2025-03-11T13:51:21.313Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:54.178 [main] WARN  BaseProvider - skipping frame (1741701054178/1741701054015)"
Mar 11 14:51:21 devrdtpi1 docker[404509]: 2025-03-11T13:51:21.647Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:54.512 [main] WARN  BaseProvider - skipping frame (1741701054512/1741701054348)"
Mar 11 14:51:22 devrdtpi1 docker[404509]: 2025-03-11T13:51:22.486Z INF/device:plugins:screen:stream 148 [FA69M0309003] minicap says: "14:50:55.353 [main] WARN  BaseProvider - skipping frame (1741701055352/1741701055188)"
...