TinyPilot Pro log dump https://tinypilotkvm.com Timestamp: 2022-06-17T02:26:57+01:00 Software versions TinyPilot version: 2.4.1 3893245 uStreamer version: v4.13 61ab2a8 OS version: Linux tinypilot 5.10.63-v7l+ #1496 SMP Wed Dec 1 15:58:56 GMT 2021 armv7l GNU/Linux TinyPilot state temp=44.3'C throttled=0x0 voltage logs 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=/opt/tinypilot/app_settings.cfg Restart=always [Install] WantedBy=multi-user.target TinyPilot logs -- Logs begin at Fri 2022-06-17 02:25:47 BST, end at Fri 2022-06-17 02:26:57 BST. -- Jun 17 02:26:01 tinypilot systemd[1]: Started TinyPilot - RPi-based virtual KVM. Jun 17 02:26:04 tinypilot python[595]: 2022-06-17 02:26:04.019 __main__ INFO Starting app Jun 17 02:26:08 tinypilot python[595]: 2022-06-17 02:26:08.968 socket_api INFO Client wS3DN3Z8VJSQr5ptAAAB connected Jun 17 02:26:10 tinypilot python[595]: 2022-06-17 02:26:10.362 socket_api INFO Client ajf78Sm9s2eaayaAAAAD connected Jun 17 02:26:21 tinypilot sudo[629]: tinypilot : TTY=unknown ; PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/eject-mass-storage Jun 17 02:26:21 tinypilot sudo[629]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 02:26:21 tinypilot sudo[629]: pam_unix(sudo:session): session closed for user root Jun 17 02:26:34 tinypilot sudo[640]: tinypilot : TTY=unknown ; PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/mount-mass-storage -m CDROM /home/tinypilot/mass-storage/backing-files/TinyCore-current.iso Jun 17 02:26:34 tinypilot sudo[640]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 17 02:26:34 tinypilot sudo[640]: pam_unix(sudo:session): session closed for user root Jun 17 02:26:35 tinypilot python[595]: 2022-06-17 02:26:35.931 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:36 tinypilot python[595]: 2022-06-17 02:26:36.459 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:36 tinypilot python[595]: 2022-06-17 02:26:36.989 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:37 tinypilot python[595]: 2022-06-17 02:26:37.520 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:38 tinypilot python[595]: 2022-06-17 02:26:38.048 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:38 tinypilot python[595]: 2022-06-17 02:26:38.577 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:39 tinypilot python[595]: 2022-06-17 02:26:39.147 socket_api ERROR [SENSITIVE] Failed to write key: ArrowDown (keycode=ArrowDown). Failed to write to HID interface: /dev/hidg0. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:39 tinypilot python[595]: 2022-06-17 02:26:39.667 socket_api ERROR [SENSITIVE] Failed to write key: ArrowDown (keycode=ArrowDown). Failed to write to HID interface: /dev/hidg0. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:40 tinypilot python[595]: 2022-06-17 02:26:40.194 socket_api ERROR [SENSITIVE] Failed to write key: ArrowDown (keycode=ArrowDown). Failed to write to HID interface: /dev/hidg0. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:40 tinypilot python[595]: 2022-06-17 02:26:40.724 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:41 tinypilot python[595]: 2022-06-17 02:26:41.252 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:43 tinypilot python[595]: 2022-06-17 02:26:43.004 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:50 tinypilot python[595]: 2022-06-17 02:26:50.148 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:52 tinypilot python[595]: 2022-06-17 02:26:52.152 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:52 tinypilot python[595]: 2022-06-17 02:26:52.659 socket_api INFO Client wS3DN3Z8VJSQr5ptAAAB disconnected Jun 17 02:26:55 tinypilot python[595]: 2022-06-17 02:26:55.216 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] Jun 17 02:26:57 tinypilot python[595]: 2022-06-17 02:26:57.221 socket_api ERROR [SENSITIVE] Failed to forward mouse event: Failed to write to HID interface: /dev/hidg1. Is USB cable connected? [/SENSITIVE] TinyPilot update logs uStreamer configuration [Unit] Description=uStreamer - Lightweight, optimized video encoder After=syslog.target network.target [Service] Type=simple User=ustreamer WorkingDirectory=/opt/ustreamer ExecStart=/opt/ustreamer/ustreamer \ --port 8001 \ --encoder omx \ --format uyvy \ --workers 3 \ --drop-same-frames 30 \ --persistent \ --dv-timings \ && : # This last line is just to end the multi-line command because the line # before is ending with backslash and so expects to be continued. Restart=always [Install] WantedBy=multi-user.target uStreamer logs -- Logs begin at Fri 2022-06-17 02:25:47 BST, end at Fri 2022-06-17 02:26:57 BST. -- Jun 17 02:26:01 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.138 main] -- Using internal blank placeholder Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.140 main] -- Listening HTTP on [127.0.0.1]:8001 Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.140 stream] -- Using V4L2 device: /dev/video0 Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.140 stream] -- Using desired FPS: 0 Jun 17 02:26:01 tinypilot ustreamer[590]: ================================================================================ Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.141 stream] -- Device fd=9 opened Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.141 stream] -- Using input channel: 0 Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.145 http] -- Starting HTTP eventloop ... Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.148 stream] -- Got new DV-timings: 1280x720p60.00, pixclk=74250000, vsync=30, hsync=370 Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.186 stream] -- Using resolution: 1280x720 Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.186 stream] -- Using pixelformat: UYVY Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.186 stream] -- Querying HW FPS changing is not supported Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.186 stream] -- Using IO method: MMAP Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.194 stream] -- Requested 5 device buffers, got 5 Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.201 stream] -- Capturing started Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.201 stream] -- Initializing OMX encoder ... Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.202 stream] -- Initializing OMX encoder ... Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.204 stream] -- Initializing OMX encoder ... Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.210 stream] -- Using JPEG quality: 80% Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.210 stream] -- Creating pool JPEG with 3 workers ... Jun 17 02:26:01 tinypilot ustreamer[590]: -- INFO [18.210 stream] -- Capturing ... Jun 17 02:26:10 tinypilot ustreamer[590]: -- INFO [26.327 http] -- HTTP: Registered client: [192.168.3.6]:34452, id=1e2760000684e529; clients now: 1 nginx logs -- Logs begin at Fri 2022-06-17 02:25:47 BST, end at Fri 2022-06-17 02:26:57 BST. -- Jun 17 02:26:01 tinypilot systemd[1]: Starting A high performance web server and a reverse proxy server... Jun 17 02:26:01 tinypilot systemd[1]: Started A high performance web server and a reverse proxy server. 2022/06/17 02:24:09 [error] 600#600: *166 upstream prematurely closed connection while reading response header from upstream, client: 192.168.3.6, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "192.168.11.75", referrer: "https://192.168.11.75/" 2022/06/17 02:24:24 [error] 600#600: *1 connect() failed (111: Connection refused) while connecting to upstream, client: 192.168.3.6, server: tinypilot, request: "GET /socket.io/?EIO=4&transport=polling&t=O5lGmFg HTTP/2.0", upstream: "http://127.0.0.1:8000/socket.io/?EIO=4&transport=polling&t=O5lGmFg", host: "192.168.11.75", referrer: "https://192.168.11.75/" 2022/06/17 02:25:46 [error] 600#600: *1 upstream prematurely closed connection while reading response header from upstream, client: 192.168.3.6, server: tinypilot, request: "POST /api/restart HTTP/2.0", upstream: "http://127.0.0.1:8000/api/restart", host: "192.168.11.75", referrer: "https://192.168.11.75/" 2022/06/17 02:26:03 [error] 600#600: *1 connect() failed (111: Connection refused) while connecting to upstream, client: 192.168.3.6, server: tinypilot, request: "GET /socket.io/?EIO=4&transport=polling&t=O5lH8EJ HTTP/2.0", upstream: "http://127.0.0.1:8000/socket.io/?EIO=4&transport=polling&t=O5lH8EJ", host: "192.168.11.75", referrer: "https://192.168.11.75/" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/clipboard.js HTTP/2.0" 200 633 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/logs.js HTTP/2.0" 200 1484 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/settings.js HTTP/2.0" 200 1877 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/wake-on-lan.js HTTP/2.0" 200 551 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /third-party/socket.io/3.1.3/socket.io.min.js HTTP/2.0" 200 61701 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/paste.js HTTP/2.0" 200 2076 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/app.js HTTP/2.0" 200 12804 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /third-party/fonts/fonts.css HTTP/2.0" 200 1812 "https://192.168.11.75/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /css/button.css HTTP/2.0" 200 1690 "https://192.168.11.75/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /css/cursors.css HTTP/2.0" 200 671 "https://192.168.11.75/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/keycodes.js HTTP/2.0" 200 3190 "https://192.168.11.75/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/keyboardstate.js HTTP/2.0" 200 2829 "https://192.168.11.75/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/keystrokes.js HTTP/2.0" 200 362 "https://192.168.11.75/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /js/overlays.js HTTP/2.0" 200 495 "https://192.168.11.75/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /css/icons.css HTTP/2.0" 200 841 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /css/toggle.css HTTP/2.0" 200 1005 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /socket.io/?EIO=4&transport=polling&t=O5lGojj HTTP/2.0" 200 96 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "POST /socket.io/?EIO=4&transport=polling&t=O5lGokb&sid=5zrhlkTmmon9gtK2AAAC HTTP/2.0" 200 2 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:27 +0100] "GET /socket.io/?EIO=4&transport=polling&t=O5lGokb.0&sid=5zrhlkTmmon9gtK2AAAC HTTP/2.0" 200 32 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:28 +0100] "GET /apple-touch-icon.png HTTP/2.0" 200 3571 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:28 +0100] "GET /favicon-16x16.png HTTP/2.0" 200 468 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:24:28 +0100] "GET /socket.io/?EIO=4&transport=polling&t=O5lGoln&sid=5zrhlkTmmon9gtK2AAAC HTTP/2.0" 200 1 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:20 +0100] "GET /api/massStorage/backingFiles HTTP/2.0" 200 174 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:22 +0100] "PUT /api/massStorage/eject HTTP/2.0" 200 3 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:23 +0100] "PUT /api/massStorage/mount/TinyCore-current.iso?mode=FLASH HTTP/2.0" 200 3 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:23 +0100] "GET /api/massStorage/backingFiles HTTP/2.0" 200 173 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:35 +0100] "GET /api/massStorage/backingFiles HTTP/2.0" 200 173 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:46 +0100] "GET /socket.io/?EIO=4&transport=websocket&sid=5zrhlkTmmon9gtK2AAAC HTTP/1.1" 101 749 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:46 +0100] "POST /api/restart HTTP/2.0" 502 173 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-" 192.168.3.6 - - [17/Jun/2022:02:25:47 +0100] "GET /stream?advance_headers=1 HTTP/2.0" 200 15690490 "https://192.168.11.75/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:101.0) Gecko/20100101 Firefox/101.0" "-"