TinyPilot Pro log dump https://tinypilotkvm.com Timestamp: 2023-11-02T10:25:58+00:00 Software versions TinyPilot version: 2.5.4 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: c03114 Model: Raspberry Pi 4 Model B Rev 1.4 TinyPilot state Read-only filesystem: off SSH access: enabled temp=56.9'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_h264_bitrate: 1000 ustreamer_persistent: true ustreamer_port: 8001 ustreamer_quality: 13 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 Thu 2023-11-02 07:37:39 GMT, end at Thu 2023-11-02 10:25:58 GMT. -- Nov 02 07:37:57 tinypilot systemd[1]: Started TinyPilot - RPi-based virtual KVM. Nov 02 07:38:00 tinypilot python[643]: 2023-11-02 07:38:00.531 __main__ INFO Starting app Nov 02 07:38:07 tinypilot python[643]: 2023-11-02 07:38:07.787 db.store INFO Loading database migrations from /opt/tinypilot/app/db/migrations/*.sql Nov 02 07:38:07 tinypilot python[643]: 2023-11-02 07:38:07.800 db.store INFO Read 7 database migrations from disk Nov 02 07:38:11 tinypilot python[643]: 2023-11-02 07:38:11.418 socket_api INFO Client 75j_0AEkbfDfjupCAAAC connected Nov 02 08:00:25 tinypilot python[643]: 2023-11-02 08:00:25.472 socket_api INFO Client 75j_0AEkbfDfjupCAAAC disconnected Nov 02 10:25:51 tinypilot python[643]: 2023-11-02 10:25:51.004 socket_api INFO Client Va1jUJxxOiNrhALGAAAE connected Nov 02 10:25:58 tinypilot sudo[5815]: tinypilot : TTY=unknown ; PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/scripts/collect-debug-logs -q Nov 02 10:25:58 tinypilot sudo[5815]: 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_desired_fps: 10 ustreamer_drop_same_frames: 30 ustreamer_encoder: omx ustreamer_format: uyvy ustreamer_h264_bitrate: 1000 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_quality: 13 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_h264_bitrate: 1000 ustreamer_persistent: true ustreamer_port: 8001 ustreamer_quality: 13 ustreamer_use_dv_timings: true ustreamer_workers: 3 uStreamer logs -- Logs begin at Thu 2023-11-02 07:37:39 GMT, end at Thu 2023-11-02 10:25:58 GMT. -- Nov 02 07:37:57 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Nov 02 07:37:59 tinypilot launch[638]: + exec /opt/ustreamer/ustreamer --host 127.0.0.1 --port 8001 --encoder omx --format uyvy --desired-fps 10 --workers 3 --quality 13 --drop-same-frames 30 --persistent --dv-timings --h264-sink tinypilot::ustreamer::h264 --h264-sink-mode 777 --h264-sink-rm --h264-bitrate 1000 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.597 main] -- Using internal blank placeholder Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.598 main] -- Using H264-sink: tinypilot::ustreamer::h264 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.600 main] -- Listening HTTP on [127.0.0.1]:8001 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.600 stream] -- Using V4L2 device: /dev/video0 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.600 stream] -- Using desired FPS: 10 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.600 http] -- Starting HTTP eventloop ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.601 stream] -- H264: Initializing MMAL encoder ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.601 stream] -- H264: Using bitrate: 1000 Kbps Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.601 stream] -- H264: Using GOP: 30 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.614 stream] -- H264: Configuring MMAL encoder: zero_copy=0 ... Nov 02 07:37:59 tinypilot launch[638]: ================================================================================ Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.640 stream] -- Device fd=12 opened Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.640 stream] -- Using input channel: 0 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.647 stream] -- Got new DV-timings: 1920x1080p30.00, pixclk=79872960, vsync=22, hsync=496 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.684 stream] -- Using resolution: 1920x1080 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.684 stream] -- Using pixelformat: UYVY Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.684 stream] -- Querying HW FPS changing is not supported Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.684 stream] -- Using IO method: MMAP Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.695 stream] -- Requested 5 device buffers, got 5 Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.703 stream] -- Capturing started Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.703 stream] -- Initializing OMX encoder ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.705 stream] -- Initializing OMX encoder ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.707 stream] -- Initializing OMX encoder ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.716 stream] -- Using JPEG quality: 13% Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.716 stream] -- Creating pool JPEG with 3 workers ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.717 stream] -- Capturing ... Nov 02 07:37:59 tinypilot launch[638]: -- INFO [23.734 stream] -- H264: Configuring MMAL encoder: zero_copy=1 ... Nov 02 07:38:11 tinypilot launch[638]: -- INFO [35.406 http] -- HTTP: Registered client: [100.104.69.37]:48214, id=edc752138214be86; clients now: 1 Nov 02 07:38:32 tinypilot launch[638]: -- INFO [44.264 http] -- HTTP: Disconnected client: [100.104.69.37]:48214, id=edc752138214be86, Resource temporarily unavailable (reading,eof); clients now: 0 Nov 02 10:25:50 tinypilot launch[638]: -- INFO [10082.842 http] -- HTTP: Registered client: [100.104.69.37]:48258, id=d1fe1c17fd57c077; clients now: 1 Nov 02 10:25:58 tinypilot launch[638]: -- INFO [10090.893 http] -- HTTP: Disconnected client: [100.104.69.37]:48258, id=d1fe1c17fd57c077, Resource temporarily unavailable (reading,eof); clients now: 0 nginx logs -- Logs begin at Thu 2023-11-02 07:37:39 GMT, end at Thu 2023-11-02 10:25:58 GMT. -- Nov 02 07:37:57 tinypilot systemd[1]: Starting A high performance web server and a reverse proxy server... Nov 02 07:37:58 tinypilot systemd[1]: Started A high performance web server and a reverse proxy server. 2023/11/02 03:20:10 [error] 670#670: *31 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "100.92.228.109", referrer: "https://100.92.228.109/" 2023/11/02 05:26:06 [error] 695#695: *47 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "100.92.228.109", referrer: "https://100.92.228.109/" 2023/11/02 05:26:34 [error] 669#669: *1 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "GET /socket.io/?EIO=4&transport=polling&t=OkEVmw- HTTP/2.0", upstream: "http://127.0.0.1:8000/socket.io/?EIO=4&transport=polling&t=OkEVmw-", host: "100.92.228.109", referrer: "https://100.92.228.109/" 2023/11/02 05:26:34 [error] 669#669: *1 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "GET /socket.io/?EIO=4&transport=polling&t=OkEVsBG HTTP/2.0", upstream: "http://127.0.0.1:8000/socket.io/?EIO=4&transport=polling&t=OkEVsBG", host: "100.92.228.109", referrer: "https://100.92.228.109/" 2023/11/02 05:26:34 [error] 669#669: *1 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "100.92.228.109", referrer: "https://100.92.228.109/" 2023/11/02 06:13:04 [error] 670#670: *54 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "100.92.228.109", referrer: "https://100.92.228.109/" 2023/11/02 07:37:36 [error] 679#679: *40 upstream prematurely closed connection while reading response header from upstream, client: 100.104.69.37, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "100.92.228.109", referrer: "https://100.92.228.109/" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /js/app.js HTTP/2.0" 200 14235 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /third-party/webrtc-adapter/8.1.1/adapter.min.js HTTP/2.0" 200 59549 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /third-party/janus-gateway/1.0.0/janus.js HTTP/2.0" 200 134759 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /js/webrtc-video.js HTTP/2.0" 200 6645 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /third-party/fonts/fonts.css HTTP/2.0" 200 1812 "https://100.92.228.109/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /css/button.css HTTP/2.0" 200 1976 "https://100.92.228.109/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:09 +0000] "GET /css/cursors.css HTTP/2.0" 200 618 "https://100.92.228.109/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /third-party/fonts/overpass-light.woff2 HTTP/2.0" 200 26568 "https://100.92.228.109/third-party/fonts/fonts.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /js/keycodes.js HTTP/2.0" 200 3208 "https://100.92.228.109/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /js/keyboardstate.js HTTP/2.0" 200 3164 "https://100.92.228.109/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /js/keystrokes.js HTTP/2.0" 200 362 "https://100.92.228.109/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /js/overlays.js HTTP/2.0" 200 495 "https://100.92.228.109/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /css/icons.css HTTP/2.0" 200 841 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /css/toggle.css HTTP/2.0" 200 1005 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /img/clipboard-icon.svg HTTP/2.0" 200 855 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /img/logo.svg HTTP/2.0" 200 5550 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /third-party/fonts/overpass-mono-light.woff2 HTTP/2.0" 200 50112 "https://100.92.228.109/third-party/fonts/fonts.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /third-party/fonts/overpass-semibold.woff2 HTTP/2.0" 200 25032 "https://100.92.228.109/third-party/fonts/fonts.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /img/video-stream-icon.svg HTTP/2.0" 200 840 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /img/warning-icon.svg HTTP/2.0" 200 2177 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:10 +0000] "GET /img/external-link-icon.svg HTTP/2.0" 200 1223 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:11 +0000] "GET /socket.io/?EIO=4&transport=polling&t=OkE-0mM HTTP/2.0" 200 96 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:11 +0000] "POST /socket.io/?EIO=4&transport=polling&t=OkE-0sG&sid=zlEyN24NjdenmxH3AAAB HTTP/2.0" 200 2 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:11 +0000] "GET /socket.io/?EIO=4&transport=polling&t=OkE-0sH&sid=zlEyN24NjdenmxH3AAAB HTTP/2.0" 200 32 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:11 +0000] "GET /favicon.ico HTTP/2.0" 200 1213 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:11 +0000] "GET /favicon-32x32.png HTTP/2.0" 200 833 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:27 +0000] "GET /socket.io/?EIO=4&transport=polling&t=OkE-0yL&sid=zlEyN24NjdenmxH3AAAB HTTP/2.0" 200 1 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:07:38:32 +0000] "GET /stream?advance_headers=1 HTTP/2.0" 200 553031 "https://100.92.228.109/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:08:00:25 +0000] "GET /janus/ws HTTP/1.1" 101 9374 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-" 100.104.69.37 - - [02/Nov/2023:08:00:25 +0000] "GET /socket.io/?EIO=4&transport=websocket&sid=zlEyN24NjdenmxH3AAAB HTTP/1.1" 101 35313 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/119.0.0.0 Safari/537.36" "-"