TinyPilot log dump https://tinypilotkvm.com Timestamp: 2023-10-17T18:05:00-04:00 Software versions TinyPilot version: 1.9.1-22+951b9bf uStreamer version: 5.43 System information OS version: Linux tinypilot 6.1.21-v8+ #1642 SMP PREEMPT Mon Apr 3 17:24:16 BST 2023 aarch64 GNU/Linux Kernel architecture: aarch64 Userland architecture: armhf (32-bit) Distribution name: Raspbian Distribution version: 11 Hardware information Hardware: BCM2835 Revision: b03114 Model: Raspberry Pi 4 Model B Rev 1.4 TinyPilot state Read-only filesystem: off SSH access: enabled Network interfaces: eth0 (up) lo (unknown) wlan0 (down) Mouse jiggler: disabled CPU Temperature: 36.5'C CPU throttled since boot: no Voltage issues: no TinyPilot settings.yml TinyPilot configuration [Unit] Description=TinyPilot - RPi-based virtual KVM BindsTo=nginx.service After=syslog.target network.target nginx.service StartLimitIntervalSec=0 [Service] Type=simple User=tinypilot WorkingDirectory=/opt/tinypilot ExecStart=/opt/tinypilot/venv/bin/python app/main.py Environment=APP_SETTINGS_FILE=/home/tinypilot/app_settings.cfg Restart=always [Install] WantedBy=multi-user.target TinyPilot logs -- Journal begins at Tue 2023-05-02 20:25:51 EDT, ends at Tue 2023-10-17 18:05:00 EDT. -- Oct 17 18:03:19 tinypilot systemd[1]: Started TinyPilot - RPi-based virtual KVM. Oct 17 18:03:21 tinypilot python[3044]: 2023-10-17 18:03:21.219 __main__ INFO Starting app Oct 17 18:03:21 tinypilot python[3044]: 2023-10-17 18:03:21.223 secret_key INFO No flask secret key found Oct 17 18:03:21 tinypilot python[3044]: 2023-10-17 18:03:21.223 secret_key INFO Creating new flask secret key at /home/tinypilot/.flask-secret-key Oct 17 18:03:23 tinypilot systemd[1]: Stopping TinyPilot - RPi-based virtual KVM... Oct 17 18:03:23 tinypilot systemd[1]: tinypilot.service: Succeeded. Oct 17 18:03:23 tinypilot systemd[1]: Stopped TinyPilot - RPi-based virtual KVM. Oct 17 18:03:23 tinypilot systemd[1]: tinypilot.service: Consumed 2.034s CPU time. -- Boot 0265295e62b04054894cd4b3e92afd9e -- Oct 17 18:03:27 tinypilot systemd[1]: Started TinyPilot - RPi-based virtual KVM. Oct 17 18:03:29 tinypilot python[580]: 2023-10-17 18:03:29.696 __main__ INFO Starting app Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.729 db.store INFO Loading database migrations from /opt/tinypilot/app/db/migrations/*.sql Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.741 db.store INFO Read 7 database migrations from disk Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.763 db.store INFO Migration counter: 0/7 (actual/total) Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.784 db.store INFO Applied migration, counter is now at 1 Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.799 db.store INFO Applied migration, counter is now at 2 Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.813 db.store INFO Applied migration, counter is now at 3 Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.827 db.store INFO Applied migration, counter is now at 4 Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.837 db.store INFO Applied migration, counter is now at 5 Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.863 db.store INFO Applied migration, counter is now at 6 Oct 17 18:04:51 tinypilot python[580]: 2023-10-17 18:04:51.871 db.store INFO Applied migration, counter is now at 7 Oct 17 18:04:52 tinypilot python[580]: 2023-10-17 18:04:52.180 socket_api INFO Client 8BlYDeC7Q5uKL6JNAAAB connected [SENSITIVE DATA REDACTED] [SENSITIVE DATA REDACTED] Oct 17 18:04:54 tinypilot python[580]: 2023-10-17 18:04:54.080 socket_api INFO Client 8BlYDeC7Q5uKL6JNAAAB disconnected Oct 17 18:04:54 tinypilot python[580]: 2023-10-17 18:04:54.111 socket_api INFO Client IMbqt9F9yZYEpXEfAAAD connected [SENSITIVE DATA REDACTED] [SENSITIVE DATA REDACTED] [SENSITIVE DATA REDACTED] [SENSITIVE DATA REDACTED] Oct 17 18:05:00 tinypilot sudo[820]: tinypilot : PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/scripts/collect-debug-logs -q Oct 17 18:05:00 tinypilot sudo[820]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=111) TinyPilot update logs uStreamer configuration ==> /opt/ustreamer-launcher/configs.d/000-defaults.yml <== # Settings for an HDMI to USB video capture dongle. --- ustreamer_encoder: hw ustreamer_format: jpeg ustreamer_resolution: "1920x1080" ==> /opt/ustreamer-launcher/configs.d/100-tinypilot.yml <== uStreamer logs -- Journal begins at Tue 2023-05-02 20:25:51 EDT, ends at Tue 2023-10-17 18:05:00 EDT. -- Oct 17 18:03:22 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot launch[3100]: /opt/ustreamer-launcher/launch: line 46: yq: command not found Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Main process exited, code=exited, status=127/n/a Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Failed with result 'exit-code'. Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Scheduled restart job, restart counter is at 1. Oct 17 18:03:22 tinypilot systemd[1]: Stopped uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot launch[3104]: /opt/ustreamer-launcher/launch: line 46: yq: command not found Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Main process exited, code=exited, status=127/n/a Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Failed with result 'exit-code'. Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Scheduled restart job, restart counter is at 2. Oct 17 18:03:22 tinypilot systemd[1]: Stopped uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot launch[3110]: /opt/ustreamer-launcher/launch: line 46: yq: command not found Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Main process exited, code=exited, status=127/n/a Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Failed with result 'exit-code'. Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Scheduled restart job, restart counter is at 3. Oct 17 18:03:22 tinypilot systemd[1]: Stopped uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot launch[3112]: /opt/ustreamer-launcher/launch: line 46: yq: command not found Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Main process exited, code=exited, status=127/n/a Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Failed with result 'exit-code'. Oct 17 18:03:22 tinypilot systemd[1]: ustreamer.service: Scheduled restart job, restart counter is at 4. Oct 17 18:03:22 tinypilot systemd[1]: Stopped uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:22 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:23 tinypilot systemd[1]: Stopping uStreamer - Lightweight, optimized video encoder... Oct 17 18:03:23 tinypilot systemd[1]: ustreamer.service: Succeeded. Oct 17 18:03:23 tinypilot systemd[1]: Stopped uStreamer - Lightweight, optimized video encoder. -- Boot 0265295e62b04054894cd4b3e92afd9e -- Oct 17 18:03:27 tinypilot systemd[1]: Started uStreamer - Lightweight, optimized video encoder. Oct 17 18:03:28 tinypilot launch[496]: + exec /opt/ustreamer/ustreamer --port 48001 --persistent --h264-sink tinypilot::ustreamer::h264 --h264-sink-mode 777 --h264-sink-rm --encoder hw --format jpeg --resolution 1920x1080 Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.673 main] -- Starting PiKVM uStreamer 5.43 ... Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.673 main] -- Using internal blank placeholder Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.673 main] -- Using H264-sink: tinypilot::ustreamer::h264 Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.674 main] -- Listening HTTP on [127.0.0.1]:48001 Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.674 stream] -- Using V4L2 device: /dev/video0 Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.674 stream] -- Using desired FPS: 0 Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.674 http] -- Starting HTTP eventloop ... Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.675 stream] -- H264: Initializing encoder ... Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.682 stream] -- H264: Configuring encoder: DMA=0 ... Oct 17 18:03:28 tinypilot launch[496]: ================================================================================ Oct 17 18:03:28 tinypilot launch[496]: -- ERROR [8.703 stream] -- Can't access device: No such file or directory Oct 17 18:03:28 tinypilot launch[496]: -- INFO [8.704 stream] -- Waiting for the device access ... Oct 17 18:04:52 tinypilot launch[496]: -- INFO [76.927 http] -- HTTP: NEW client (now=1): [172.16.30.5]:52904, id=73f73f654c2f10d7 Oct 17 18:04:54 tinypilot launch[496]: -- INFO [78.854 http] -- HTTP: NEW client (now=2): [172.16.30.5]:52908, id=142930e51740723b Oct 17 18:04:54 tinypilot launch[496]: -- INFO [79.215 http] -- HTTP: DEL client (now=1): [172.16.30.5]:52904, id=73f73f654c2f10d7, Resource temporarily unavailable (reading,eof) nginx logs -- Journal begins at Tue 2023-05-02 20:25:51 EDT, ends at Tue 2023-10-17 18:05:00 EDT. -- Oct 17 18:02:56 tinypilot systemd[1]: Starting A high performance web server and a reverse proxy server... Oct 17 18:02:56 tinypilot systemd[1]: Started A high performance web server and a reverse proxy server. Oct 17 18:03:15 tinypilot systemd[1]: Reloading A high performance web server and a reverse proxy server. Oct 17 18:03:15 tinypilot systemd[1]: Reloaded A high performance web server and a reverse proxy server. Oct 17 18:03:23 tinypilot systemd[1]: Stopping A high performance web server and a reverse proxy server... Oct 17 18:03:23 tinypilot systemd[1]: nginx.service: Succeeded. Oct 17 18:03:23 tinypilot systemd[1]: Stopped A high performance web server and a reverse proxy server. -- Boot 0265295e62b04054894cd4b3e92afd9e -- Oct 17 18:03:26 tinypilot systemd[1]: Starting A high performance web server and a reverse proxy server... Oct 17 18:03:27 tinypilot systemd[1]: Started A high performance web server and a reverse proxy server. 2023/10/17 18:03:08 [notice] 2654#2654: using inherited sockets from "6;7;" 2023/10/17 18:03:15 [notice] 2961#2961: signal process started 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /third-party/fonts/fonts.css HTTP/1.1" 200 1812 "http://172.16.20.24/css/style.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /css/button.css HTTP/1.1" 200 1976 "http://172.16.20.24/css/style.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /css/cursors.css HTTP/1.1" 200 618 "http://172.16.20.24/css/style.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /js/keycodes.js HTTP/1.1" 200 822 "http://172.16.20.24/js/app.js" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /js/keystrokes.js HTTP/1.1" 200 362 "http://172.16.20.24/js/app.js" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /js/keyboardstate.js HTTP/1.1" 200 3164 "http://172.16.20.24/js/app.js" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /js/settings.js HTTP/1.1" 200 1215 "http://172.16.20.24/js/app.js" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /js/overlays.js HTTP/1.1" 200 495 "http://172.16.20.24/js/app.js" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /socket.io/?EIO=4&transport=websocket&sid=_6YpQ5qsQBlqwxXTAAAA HTTP/1.1" 101 86 "-" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /img/video-stream-icon.svg HTTP/1.1" 200 840 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /css/icons.css HTTP/1.1" 200 419 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /img/warning-icon.svg HTTP/1.1" 200 2177 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /css/toggle.css HTTP/1.1" 200 1005 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /img/logo.svg HTTP/1.1" 200 2936 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /third-party/fonts/overpass-light.woff2 HTTP/1.1" 200 26568 "http://172.16.20.24/third-party/fonts/fonts.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /third-party/fonts/overpass-mono-light.woff2 HTTP/1.1" 200 50112 "http://172.16.20.24/third-party/fonts/fonts.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /img/clipboard-icon.svg HTTP/1.1" 200 855 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /img/angle-down-icon.svg HTTP/1.1" 200 516 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /socket.io/?EIO=4&transport=polling&t=Oi_gv-7 HTTP/1.1" 200 97 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /third-party/fonts/overpass-semibold.woff2 HTTP/1.1" 200 25032 "http://172.16.20.24/third-party/fonts/fonts.css" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /img/external-link-icon.svg HTTP/1.1" 200 1223 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "POST /socket.io/?EIO=4&transport=polling&t=Oi_gv_1&sid=TGS4gaKrnXD5XZpNAAAC HTTP/1.1" 200 2 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /socket.io/?EIO=4&transport=polling&t=Oi_gv_1.0&sid=TGS4gaKrnXD5XZpNAAAC HTTP/1.1" 200 32 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /socket.io/?EIO=4&transport=polling&t=Oi_gv_C&sid=TGS4gaKrnXD5XZpNAAAC HTTP/1.1" 200 1 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /socket.io/?EIO=4&transport=polling&t=Oi_gv_I&sid=TGS4gaKrnXD5XZpNAAAC HTTP/1.1" 200 1 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /favicon-32x32.png HTTP/1.1" 200 833 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /apple-touch-icon.png HTTP/1.1" 200 3571 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:54 -0400] "GET /stream?advance_headers=1 HTTP/1.1" 200 69767 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:57 -0400] "GET /api/latestRelease HTTP/1.1" 200 62 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0" 172.16.30.5 - - [17/Oct/2023:18:04:57 -0400] "GET /api/version HTTP/1.1" 200 31 "http://172.16.20.24/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10.15; rv:109.0) Gecko/20100101 Firefox/118.0"