TinyPilot Pro log dump https://tinypilotkvm.com Timestamp: 2023-03-30T14:12:44+01:00 Software versions TinyPilot version: 2.5.3 uStreamer version: 4.13 OS version: Linux tinypilot 5.10.103-v7l+ #1529 SMP Tue Mar 8 12:24:00 GMT 2022 armv7l GNU/Linux Distribution name: Raspbian Distribution version: 10 Hardware information Hardware: BCM2711 Revision: b03115 Model: Raspberry Pi 4 Model B Rev 1.5 TinyPilot state Read-only filesystem: off SSH access: enabled temp=45.2'C throttled=0x0 voltage logs TinyPilot settings.yml tinypilot_repo_branch: 2.5.0 ustreamer_desired_fps: 10 ustreamer_drop_same_frames: 30 ustreamer_encoder: omx ustreamer_format: uyvy ustreamer_persistent: true ustreamer_port: 8001 ustreamer_use_dv_timings: true ustreamer_workers: 3 TinyPilot configuration [Unit] Description=TinyPilot - RPi-based virtual KVM After=syslog.target network.target StartLimitIntervalSec=0 [Service] Type=simple User=tinypilot WorkingDirectory=/opt/tinypilot ExecStart=/opt/tinypilot/venv/bin/python app/main.py Environment=HOST=127.0.0.1 Environment=PORT=8000 Environment=APP_SETTINGS_FILE=/home/tinypilot/app_settings.cfg Restart=always [Install] WantedBy=multi-user.target TinyPilot logs -- Logs begin at Wed 2023-03-29 17:17:01 BST, end at Thu 2023-03-30 14:12:44 BST. -- Mar 29 17:21:49 tinypilot systemd[1]: Started TinyPilot - RPi-based virtual KVM. Mar 29 17:21:52 tinypilot python[590]: 2023-03-29 17:21:52.229 __main__ INFO Starting app Mar 29 18:22:00 tinypilot python[590]: 2023-03-29 18:22:00.128 db.store INFO Loading database migrations from /opt/tinypilot/app/db/migrations/*.sql Mar 29 18:22:00 tinypilot python[590]: 2023-03-29 18:22:00.135 db.store INFO Read 7 database migrations from disk Mar 29 18:22:00 tinypilot python[590]: 2023-03-29 18:22:00.591 socket_api INFO Client osOD4fKxDlfUAMk-AAAB connected Mar 29 18:22:04 tinypilot python[590]: 2023-03-29 18:22:04.206 socket_api INFO Client n9UxcKbMz6b5oUjcAAAD connected Mar 29 22:08:21 tinypilot python[590]: 2023-03-29 22:08:21.427 socket_api INFO Client osOD4fKxDlfUAMk-AAAB disconnected Mar 30 13:22:39 tinypilot python[590]: 2023-03-30 13:22:39.389 socket_api INFO Client giTeeclBaUs-g4XBAAAF connected Mar 30 14:11:34 tinypilot python[590]: 2023-03-30 14:11:34.290 socket_api INFO Client giTeeclBaUs-g4XBAAAF disconnected Mar 30 14:11:38 tinypilot python[590]: 2023-03-30 14:11:38.925 socket_api INFO Client nSpuPW7WY9MC-FskAAAH connected Mar 30 14:12:44 tinypilot sudo[3627]: tinypilot : TTY=unknown ; PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/scripts/collect-debug-logs -q Mar 30 14:12:44 tinypilot sudo[3627]: pam_unix(sudo:session): session opened for user root by (uid=0) TinyPilot update logs uStreamer configuration ==> /opt/ustreamer-launcher/configs.d/000-defaults.yml <== --- ustreamer_drop_same_frames: 30 ustreamer_encoder: omx ustreamer_format: uyvy ustreamer_h264_sink: tinypilot::ustreamer::h264 ustreamer_h264_sink_mode: 777 ustreamer_h264_sink_rm: true ustreamer_interface: 127.0.0.1 ustreamer_persistent: true ustreamer_port: 8001 ustreamer_use_dv_timings: true ustreamer_workers: 3 ==> /opt/ustreamer-launcher/configs.d/100-tinypilot.yml <== tinypilot_repo_branch: 2.5.0 ustreamer_desired_fps: 10 ustreamer_drop_same_frames: 30 ustreamer_encoder: omx ustreamer_format: uyvy ustreamer_persistent: true ustreamer_port: 8001 ustreamer_use_dv_timings: true ustreamer_workers: 3 uStreamer logs -- Logs begin at Wed 2023-03-29 17:17:01 BST, end at Thu 2023-03-30 14:12:44 BST. -- Mar 29 17:21:49 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Mar 29 17:21:51 tinypilot launch[589]: + exec /opt/ustreamer/ustreamer --host 127.0.0.1 --port 8001 --encoder omx --format uyvy --desired-fps 10 --workers 3 --drop-same-frames 30 --persistent --dv-timings --h264-sink tinypilot::ustreamer::h264 --h264-sink-mode 777 --h264-sink-rm Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.140 main] -- Using internal blank placeholder Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.141 main] -- Using H264-sink: tinypilot::ustreamer::h264 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.143 main] -- Listening HTTP on [127.0.0.1]:8001 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.144 stream] -- Using V4L2 device: /dev/video0 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.144 stream] -- Using desired FPS: 10 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.144 http] -- Starting HTTP eventloop ... Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.144 stream] -- H264: Initializing MMAL encoder ... Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.144 stream] -- H264: Using bitrate: 5000 Kbps Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.144 stream] -- H264: Using GOP: 30 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.158 stream] -- H264: Configuring MMAL encoder: zero_copy=0 ... Mar 29 17:21:51 tinypilot launch[589]: ================================================================================ Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.184 stream] -- Device fd=12 opened Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.184 stream] -- Using input channel: 0 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.205 stream] -- Using resolution: 640x480 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.205 stream] -- Using pixelformat: UYVY Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.205 stream] -- Querying HW FPS changing is not supported Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.205 stream] -- Using IO method: MMAP Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.209 stream] -- Requested 5 device buffers, got 5 Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.216 stream] -- Capturing started Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.216 stream] -- Initializing OMX encoder ... Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.218 stream] -- Initializing OMX encoder ... Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.219 stream] -- Initializing OMX encoder ... Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.225 stream] -- Using JPEG quality: 80% Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.225 stream] -- Creating pool JPEG with 3 workers ... Mar 29 17:21:51 tinypilot launch[589]: -- INFO [24.226 stream] -- Capturing ... Mar 29 17:21:52 tinypilot launch[589]: -- ERROR [25.227 stream] -- Persistent device timeout (unplugged) Mar 29 18:22:00 tinypilot launch[589]: -- INFO [49.138 http] -- HTTP: Registered client: [173.191.212.194]:40690, id=960ba58404432cc1; clients now: 1 Mar 29 18:22:02 tinypilot launch[589]: -- INFO [51.488 stream] -- H264: Configuring MMAL encoder: zero_copy=1 ... Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.775 stream] -- Got V4L2 event Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.775 stream] -- Got V4L2_EVENT_SOURCE_CHANGE: source changed Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.775 stream] -- Destroying workers pool JPEG ... Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.839 stream] -- Capturing stopped Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.840 stream] -- Device fd=12 closed Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.840 stream] -- Changed video frame to BLANK Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.860 stream] -- H264: Configuring MMAL encoder: zero_copy=0 ... Mar 29 18:22:03 tinypilot launch[589]: ================================================================================ Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.880 stream] -- Device fd=12 opened Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.881 stream] -- Using input channel: 0 Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.887 stream] -- Got new DV-timings: 1920x1080p50.00, pixclk=148500000, vsync=45, hsync=720 Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.925 stream] -- Using resolution: 1920x1080 Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.925 stream] -- Using pixelformat: UYVY Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.925 stream] -- Querying HW FPS changing is not supported Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.925 stream] -- Using IO method: MMAP Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.939 stream] -- Requested 5 device buffers, got 5 Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.949 stream] -- Capturing started Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.962 stream] -- Using JPEG quality: 80% Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.962 stream] -- Creating pool JPEG with 3 workers ... Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.962 stream] -- Capturing ... Mar 29 18:22:03 tinypilot launch[589]: -- INFO [51.968 stream] -- H264: Configuring MMAL encoder: zero_copy=1 ... Mar 29 22:08:21 tinypilot launch[589]: -- INFO [13630.153 http] -- HTTP: Disconnected client: [173.191.212.194]:40690, id=960ba58404432cc1, Resource temporarily unavailable (reading,eof); clients now: 0 Mar 30 14:11:38 tinypilot launch[589]: -- INFO [71428.251 http] -- HTTP: Registered client: [173.191.212.194]:40752, id=ee88588b0e0e990d; clients now: 1 nginx logs -- Logs begin at Wed 2023-03-29 17:17:01 BST, end at Thu 2023-03-30 14:12:44 BST. -- Mar 29 17:21:49 tinypilot systemd[1]: Starting A high performance web server and a reverse proxy server... Mar 29 17:21:50 tinypilot systemd[1]: Started A high performance web server and a reverse proxy server. 104.156.155.30 - - [30/Mar/2023:07:20:39 +0100] "GET /admin.cgi HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:40 +0100] "GET /admin.jsp HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:40 +0100] "GET /admin.jhtml HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:40 +0100] "GET /admin.html HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:41 +0100] "GET /index.aspx HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:41 +0100] "GET /base.pl HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:42 +0100] "GET /base.cfm HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:42 +0100] "GET /indice.pl HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:42 +0100] "GET /home.pl HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:43 +0100] "GET / HTTP/1.0" 307 187 "-" "-" "-" 104.156.155.30 - - [30/Mar/2023:07:20:43 +0100] "GET / HTTP/1.1" 307 187 "-" "-" "-" 104.156.155.30 - - [30/Mar/2023:07:20:32 +0100] "GET / HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:32 +0100] "GET / HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:32 +0100] "GET /robots.txt HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:32 +0100] "GET /Portal0000.htm HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:32 +0100] "POST /sdk HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:33 +0100] "GET /HNAP1 HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:33 +0100] "GET /?=PHPB8B5F2A0-3C92-11d3-A3A9-4C7B08C10000 HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:33 +0100] "GET /pools/default/buckets HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:33 +0100] "GET /docs/cplugError.html/ HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:33 +0100] "HEAD / HTTP/1.1" 307 0 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:34 +0100] "GET / HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:36 +0100] "GET /main.asp HTTP/1.1" 307 187 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:36 +0100] "GET / HTTP/1.1" 302 219 "-" "curl/7.54.0" "-" 104.156.155.30 - - [30/Mar/2023:07:20:37 +0100] "GET / HTTP/1.1" 307 187 "-" "Mozilla/5.0 (Windows NT 6.1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/36.0.1985.125 Safari/537.36" "-" 104.156.155.30 - - [30/Mar/2023:07:20:38 +0100] "GET / HTTP/1.1" 302 219 "-" "curl/7.54.0" "-" 173.191.212.194 - - [30/Mar/2023:13:22:37 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OSoU58O HTTP/2.0" 200 96 "https://psshome.ddns.net:8445/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 OPR/95.0.0.0" "-" 173.191.212.194 - - [30/Mar/2023:13:22:39 +0100] "POST /socket.io/?EIO=4&transport=polling&t=OSoU6JT&sid=tKXypl2VfY43FKwLAAAE HTTP/2.0" 200 2 "https://psshome.ddns.net:8445/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 OPR/95.0.0.0" "-" 173.191.212.194 - - [30/Mar/2023:13:22:39 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OSoU6JW&sid=tKXypl2VfY43FKwLAAAE HTTP/2.0" 200 32 "https://psshome.ddns.net:8445/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 OPR/95.0.0.0" "-" 173.191.212.194 - - [30/Mar/2023:13:22:39 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OSoU6K6&sid=tKXypl2VfY43FKwLAAAE HTTP/2.0" 200 1 "https://psshome.ddns.net:8445/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/109.0.0.0 Safari/537.36 OPR/95.0.0.0" "-"