JustPaste.it

3 - Logs - Good video (H.264) - After System - Power - Reboot.txt

TinyPilot Pro log dump
https://tinypilotkvm.com
Timestamp: 2023-05-06T02:41:35+01:00

Software versions
TinyPilot version: 2.5.4
uStreamer version: 5.38
OS version: Linux t 5.15.84-v7l+ #1613 SMP Thu Jan 5 12:01:26 GMT 2023 armv7l GNU/Linux
Distribution name: Raspbian
Distribution version: 11

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=67.6'C
throttled=0x0

voltage logs

TinyPilot settings.yml
tinypilot_enable_mass_storage_gadget: false
ustreamer_persistent: true
ustreamer_port: 8001

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
-- Journal begins at Sat 2023-05-06 02:39:45 BST, ends at Sat 2023-05-06 02:41:36 BST. --
May 06 02:39:50 t systemd[1]: Started TinyPilot - RPi-based virtual KVM.
May 06 02:39:54 t python[566]: 2023-05-06 02:39:54.211 __main__        INFO Starting app
May 06 02:40:01 t python[566]: 2023-05-06 02:40:01.532 db.store        INFO Loading database migrations from /opt/tinypilot/app/db/migrations/*.sql
May 06 02:40:01 t python[566]: 2023-05-06 02:40:01.544 db.store        INFO Read 7 database migrations from disk
May 06 02:40:02 t python[566]: 2023-05-06 02:40:02.126 socket_api      INFO Client M_FGmwYiLaKqhuv0AAAC connected
May 06 02:40:09 t python[566]: 2023-05-06 02:40:09.712 socket_api      INFO Client OYxdnVmixKTFg3iHAAAE connected
May 06 02:40:17 t sudo[901]: tinypilot : PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/scripts/collect-debug-logs -q
May 06 02:40:17 t sudo[901]: PAM bad jump in stack
May 06 02:40:17 t sudo[901]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)
May 06 02:40:18 t python[928]: No journal files were found.
May 06 02:40:18 t sudo[901]: pam_unix(sudo:session): session closed for user root
May 06 02:41:35 t sudo[961]: tinypilot : PWD=/opt/tinypilot ; USER=root ; COMMAND=/opt/tinypilot-privileged/scripts/collect-debug-logs -q
May 06 02:41:35 t sudo[961]: PAM bad jump in stack
May 06 02:41:35 t sudo[961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=110)

TinyPilot update logs

uStreamer configuration
==> /opt/ustreamer-launcher/configs.d/000-defaults.yml <==
---
ustreamer_drop_same_frames: '30'
ustreamer_encoder: m2m-image
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_enable_mass_storage_gadget: false
ustreamer_persistent: true
ustreamer_port: 8001

uStreamer logs
-- Journal begins at Sat 2023-05-06 02:39:45 BST, ends at Sat 2023-05-06 02:41:36 BST. --
May 06 02:39:51 t systemd[1]: Started uStreamer - Lightweight, optimized video encoder.
May 06 02:39:52 t launch[568]: + exec /opt/ustreamer/ustreamer --host 127.0.0.1 --port 8001 --encoder m2m-image --format uyvy --workers 3 --drop-same-frames 30 --persistent --dv-timings --h264-sink tinypilot::ustreamer::h264 --h264-sink-mode 777 --h264-sink-rm
May 06 02:39:52 t launch[568]: -- INFO  [11.727      main] -- Starting PiKVM uStreamer 5.38 ...
May 06 02:39:52 t launch[568]: -- INFO  [11.727      main] -- Using internal blank placeholder
May 06 02:39:52 t launch[568]: -- INFO  [11.727      main] -- Using H264-sink: tinypilot::ustreamer::h264
May 06 02:39:52 t launch[568]: -- INFO  [11.728      main] -- Listening HTTP on [127.0.0.1]:8001
May 06 02:39:52 t launch[568]: -- INFO  [11.728    stream] -- Using V4L2 device: /dev/video0
May 06 02:39:52 t launch[568]: -- INFO  [11.729    stream] -- Using desired FPS: 0
May 06 02:39:52 t launch[568]: -- INFO  [11.729      http] -- Starting HTTP eventloop ...
May 06 02:39:52 t launch[568]: -- INFO  [11.729    stream] -- H264: Initializing encoder ...
May 06 02:39:52 t launch[568]: -- INFO  [11.742    stream] -- H264: Configuring encoder: DMA=0 ...
May 06 02:39:52 t launch[568]: ================================================================================
May 06 02:39:52 t launch[568]: -- INFO  [11.763    stream] -- Device fd=10 opened
May 06 02:39:52 t launch[568]: -- INFO  [11.763    stream] -- Using input channel: 0
May 06 02:39:52 t launch[568]: -- INFO  [11.771    stream] -- Got new DV-timings: 1920x1080p50.00, pixclk=148500000, vsync=45, hsync=720
May 06 02:39:52 t launch[568]: -- INFO  [11.808    stream] -- Using resolution: 1920x1080
May 06 02:39:52 t launch[568]: -- INFO  [11.808    stream] -- Using format: UYVY
May 06 02:39:52 t launch[568]: -- INFO  [11.808    stream] -- Querying HW FPS changing is not supported
May 06 02:39:52 t launch[568]: -- INFO  [11.808    stream] -- Using IO method: MMAP
May 06 02:39:52 t launch[568]: -- INFO  [11.821    stream] -- Requested 5 device buffers, got 5
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- Capturing started
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- JPEG-0: Initializing encoder ...
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- JPEG-1: Initializing encoder ...
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- JPEG-2: Initializing encoder ...
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- Using JPEG quality: 80%
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- Creating pool JPEG with 3 workers ...
May 06 02:39:52 t launch[568]: -- INFO  [11.828    stream] -- Capturing ...
May 06 02:39:52 t launch[568]: -- INFO  [11.840    stream] -- H264: Configuring encoder: DMA=1 ...
May 06 02:39:52 t launch[568]: -- INFO  [11.840      jw-0] -- JPEG-0: Configuring encoder: DMA=0 ...
May 06 02:39:52 t launch[568]: -- INFO  [11.887      jw-1] -- JPEG-1: Configuring encoder: DMA=0 ...
May 06 02:39:52 t launch[568]: -- INFO  [11.913      jw-2] -- JPEG-2: Configuring encoder: DMA=0 ...
May 06 02:40:02 t launch[568]: -- INFO  [21.176      http] -- HTTP: NEW client (now=1): [192.168.2.222]:59994, id=3afe46f0c19dd16a
May 06 02:40:02 t launch[568]: -- INFO  [21.348    stream] -- H264: Requested keyframe by a sink client
May 06 02:40:02 t launch[568]: -- INFO  [21.407    stream] -- H264: Requested keyframe by a sink client
May 06 02:40:02 t launch[568]: -- INFO  [21.429      http] -- HTTP: DEL client (now=0): [192.168.2.222]:59994, id=3afe46f0c19dd16a, Resource temporarily unavailable (reading,eof)

nginx logs
-- Journal begins at Sat 2023-05-06 02:39:45 BST, ends at Sat 2023-05-06 02:41:36 BST. --
May 06 02:39:50 t systemd[1]: Starting A high performance web server and a reverse proxy server...
May 06 02:39:51 t systemd[1]: Started A high performance web server and a reverse proxy server.


2023/05/06 02:02:08 [warn] 586#586: *298 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.111, server: tinypilot, request: "GET /img/logo.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/logo.svg", host: "t", referrer: "http://t/"
2023/05/06 02:02:08 [warn] 586#586: *295 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.111, server: tinypilot, request: "GET /img/video-stream-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/video-stream-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:02:08 [warn] 586#586: *301 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.111, server: tinypilot, request: "GET /img/warning-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/warning-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:02:08 [warn] 586#586: *299 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.111, server: tinypilot, request: "GET /img/clipboard-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/clipboard-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:02:08 [warn] 586#586: *297 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.111, server: tinypilot, request: "GET /img/external-link-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/external-link-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:16:18 [warn] 593#593: *350 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.222, server: tinypilot, request: "GET /img/logo.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/logo.svg", host: "t", referrer: "http://t/"
2023/05/06 02:16:18 [warn] 593#593: *349 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.222, server: tinypilot, request: "GET /img/warning-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/warning-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:16:18 [warn] 586#586: *346 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.222, server: tinypilot, request: "GET /img/video-stream-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/video-stream-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:16:18 [warn] 586#586: *345 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.222, server: tinypilot, request: "GET /img/clipboard-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/clipboard-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:16:18 [warn] 593#593: *351 upstream sent more data than specified in "Content-Length" header while reading upstream, client: 192.168.2.222, server: tinypilot, request: "GET /img/external-link-icon.svg HTTP/1.1", upstream: "http://127.0.0.1:8000/img/external-link-icon.svg", host: "t", referrer: "http://t/"
2023/05/06 02:21:16 [error] 586#586: *373 connect() failed (111: Connection refused) while connecting to upstream, client: 192.168.2.222, server: tinypilot, request: "HEAD /stream HTTP/1.1", upstream: "http://127.0.0.1:8001/stream", host: "t", referrer: "http://t/"
2023/05/06 02:39:33 [error] 586#586: *532 upstream prematurely closed connection while reading response header from upstream, client: 192.168.2.222, server: tinypilot, request: "POST /api/restart HTTP/1.1", upstream: "http://127.0.0.1:8000/api/restart", host: "t", referrer: "http://t/"


192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/poll.js HTTP/1.1" 200 1958 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /third-party/fonts/fonts.css HTTP/1.1" 200 1812 "http://t/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /css/button.css HTTP/1.1" 200 1976 "http://t/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /css/cursors.css HTTP/1.1" 200 618 "http://t/css/style.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/updatelogs.js HTTP/1.1" 200 700 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/wake-on-lan.js HTTP/1.1" 200 551 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/logs.js HTTP/1.1" 200 1484 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/clipboard.js HTTP/1.1" 200 889 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/settings.js HTTP/1.1" 200 1879 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/mouse.js HTTP/1.1" 200 8492 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /third-party/fonts/overpass-light.woff2 HTTP/1.1" 200 26568 "http://t/third-party/fonts/fonts.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/keycodes.js HTTP/1.1" 200 3208 "http://t/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/keyboardstate.js HTTP/1.1" 200 3164 "http://t/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/keystrokes.js HTTP/1.1" 200 362 "http://t/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /js/overlays.js HTTP/1.1" 200 495 "http://t/js/app.js" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /css/icons.css HTTP/1.1" 200 841 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /css/toggle.css HTTP/1.1" 200 1005 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /third-party/fonts/overpass-mono-light.woff2 HTTP/1.1" 200 50112 "http://t/third-party/fonts/fonts.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /third-party/fonts/overpass-semibold.woff2 HTTP/1.1" 200 25032 "http://t/third-party/fonts/fonts.css" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OVkjhEh HTTP/1.1" 200 96 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "POST /socket.io/?EIO=4&transport=polling&t=OVkjhF4&sid=982hYN60QUNa_NXcAAAU HTTP/1.1" 200 2 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OVkjhF5&sid=982hYN60QUNa_NXcAAAU HTTP/1.1" 200 32 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OVkjhFN&sid=982hYN60QUNa_NXcAAAU HTTP/1.1" 200 1 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:22 +0100] "GET /socket.io/?EIO=4&transport=polling&t=OVkjhFU&sid=982hYN60QUNa_NXcAAAU HTTP/1.1" 200 1 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:27 +0100] "GET /api/debugLogs HTTP/1.1" 200 43120 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:33 +0100] "GET /janus/ws HTTP/1.1" 101 2798 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:33 +0100] "POST /api/restart HTTP/1.1" 502 559 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:33 +0100] "GET /socket.io/?EIO=4&transport=websocket&sid=982hYN60QUNa_NXcAAAU HTTP/1.1" 101 67 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.111 - - [06/May/2023:02:39:33 +0100] "GET /socket.io/?EIO=4&transport=websocket&sid=72ieH9towzjZ9N8cAAAI HTTP/1.1" 101 1809 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"
192.168.2.222 - - [06/May/2023:02:39:34 +0100] "GET /stream?advance_headers=1 HTTP/1.1" 200 167331 "http://t/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/112.0.0.0 Safari/537.36" "-"