2026-05-18 09:10:21,979 [INFO] startup_status: startup status listening on http://127.0.0.1:8299/status
2026-05-18 09:10:21,979 [INFO] main: LAN_IP=192.168.31.73 HTTP_PORT=8200 UUID=7364d75b-07ec-5e14-88cc-3ae4b2912c96
2026-05-18 09:10:21,979 [INFO] main: VIDEO_DIRS=E:\VR_Video_Passthrough_Server\videos
2026-05-18 09:10:21,979 [INFO] main: MODEL_PATH=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx (exists=True)
2026-05-18 09:10:21,979 [INFO] main: GPU_RUNTIME_CACHE=GpuRuntimeCacheEnv(runtime_cache_dir='E:\\VR_Video_Passthrough_Server\\runtime_cache', cuda_cache_path='E:\\VR_Video_Passthrough_Server\\runtime_cache\\cuda_compute_cache', cupy_cache_dir='E:\\VR_Video_Passthrough_Server\\runtime_cache\\cupy', ort_cache_dir='E:\\VR_Video_Passthrough_Server\\runtime_cache\\ort', marker_path='E:\\VR_Video_Passthrough_Server\\runtime_cache\\gpu_warmup_marker.json')
2026-05-18 09:10:24,131 [INFO] main: warmup prediction: cold=False reason=cache_hit known_slow=False eta=1.0s gpu=NVIDIA GeForce RTX 4070 Laptop GPU cc=8.9 ort=1.25.1
2026-05-18 09:10:24,131 [INFO] main: startup GPU warmup begin: force=False timeout=300.0s runs_per_shape=3
2026-05-18 09:10:25,051 [INFO] main: startup GPU warmup done: elapsed=0.921s marker_elapsed=0.000s verified_second_pass=0.000s cache_files=11 cache_size=348073
[matting] CuPy 14.0.1 detected, GPU composite enabled.
2026-05-18 09:10:25,780 [INFO] matting: CuPy detected: 14.0.1, GPU composite enabled.
[matting] options: input_size=1024 square=False split_sbs=True sbs_batch=True alpha_stride=1 alpha_mode=reuse alpha_cutoff=0.0 alpha_hard_edge=False alpha_contrast=1.0
2026-05-18 09:10:25,781 [INFO] main: PIPELINE: HWACCEL=cuda DECODE_MAX_SIDE=0 DECODE_PIX_FMT=nv12 PASSTHROUGH_MAX_FPS=60.00 ALPHA_STRIDE=1 MATTING_INPUT_SIZE=1024 WARMUP_RUNS=1 USE_PYNV=True VCODEC=hevc_nvenc HEVC_BITRATE=50M HEVC_BF=0 CONTAINER=mp4 SEEK_MODE=bytes OUTPUT_MODE=alpha MAX_CONCURRENT=1 PAD_TO_LENGTH=True COMPOSITE_DEVICE=gpu PYNV_10BIT=True PYNV_10BIT_SHIFT=8
2026-05-18 09:10:26,266 [INFO] firewall: firewall rules ok
2026-05-18 09:10:26,269 [INFO] ssdp: SSDP listening on 239.255.255.250:1900 (iface 192.168.31.73)
2026-05-18 09:10:26,612 [INFO] uvicorn.server: Started server process [5128]
2026-05-18 09:10:26,613 [INFO] uvicorn.server: Waiting for application startup.
2026-05-18 09:10:26,613 [INFO] uvicorn.server: Application startup complete.
2026-05-18 09:10:26,613 [INFO] uvicorn.server: Uvicorn running on http://0.0.0.0:8200 (Press CTRL+C to quit)
2026-05-18 09:12:15,846 [INFO] media: passthrough_live[1] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52766)
2026-05-18 09:12:15,846 [INFO] media: passthrough_live[1] start: SAVR-902_4_8k.mp4 @ 0.00s from Address(host='192.168.31.106', port=52766) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:12:16,072 [INFO] matting: [DIAG] ONNX providers wanted=['CUDAExecutionProvider', 'CPUExecutionProvider'] available=['TensorrtExecutionProvider', 'CUDAExecutionProvider', 'CPUExecutionProvider'] selected=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:12:16.1460310 [W:onnxruntime:, transformer_memcpy.cc:111 onnxruntime::MemcpyTransformer::ApplyImpl] 1 Memcpy nodes are added to the graph torch-jit-export for CUDAExecutionProvider. It might have negative impact on performance (including unable to run CUDA graph). Set session_options.log_severity_level=1 to see the detail logs before this message.
2026-05-18 09:12:16,299 [INFO] matting: Matting model loaded: kind=rvm input=src shape=['batch_size', 3, 'height', 'width'] type=tensor(float) output=fgr wanted=['CUDAExecutionProvider', 'CPUExecutionProvider'] active=['CUDAExecutionProvider', 'CPUExecutionProvider'] composite_device=gpu batch2=True model_batch2=True rvm_iobinding=True
[matting] model kind=rvm input=src shape=['batch_size', 3, 'height', 'width'] type=tensor(float) batch2=True model_batch2=True rvm_iobinding=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider'] inputs=['src', 'r1i', 'r2i', 'r3i', 'r4i', 'downsample_ratio'] outputs=['fgr', 'pha', 'r1o', 'r2o', 'r3o', 'r4o']
2026-05-18 09:12:16,940 [INFO] matting: [DIAG] alpha #1: frame=1024x1024 input_shape=(1, 3, 1024, 1024) preprocess=10.9ms ort_run=629.8ms square=False split_sbs=True split_active=False providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:12:17,123 [INFO] matting: [DIAG] matting warmup: runs=1 elapsed=823.9ms device=gpu
2026-05-18 09:12:17,124 [INFO] media: passthrough_live[1] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=9128219904 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '9128219904', 'TimeSeekRange.dlna.org': 'npt=0.000-1691.968/1691.968', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1691.968', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:12:17,125 [INFO] pynv_stream: [PYNV][1] worker start src=SAVR-902_4_8k.mp4 path=E:\VR_Video_Passthrough_Server\videos\SAVR-902_4_8k.mp4 start=0.000
2026-05-18 09:12:17,125 [INFO] pynv_stream: [PYNV][1] iter start
2026-05-18 09:12:17,125 [INFO] pynv_stream: [PYNV][1] VRAM worker_start: used=1439.5/8187.5MiB cupy_pool=18.0/18.0MiB pinned_free_blocks=0
2026-05-18 09:12:17,131 [INFO] PyNvVideoCodec: Supported NVENC Driver Version: 208
2026-05-18 09:12:17,131 [INFO] PyNvVideoCodec: Using NVENC 13.0 version
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] VRAM decoder_metadata_created: used=1439.5/8187.5MiB cupy_pool=18.0/18.0MiB pinned_free_blocks=0
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] decoder created mode=simple
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] VRAM decoder_created: used=1439.5/8187.5MiB cupy_pool=18.0/18.0MiB pinned_free_blocks=0
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] source meta: codec=hevc profile=Main pix_fmt=yuv420p bit_depth=8 level=183 size=8192x4096 process_size=8192x4096 output_size=8192x4096 source_fps=59.940 output_fps=59.940 fps_cap=60.000 duration=1691.924 frames=101414 color=tv/bt709/bt709/bt709 container=mpegts output_mode=alpha
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=59.940 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:12:17,163 [INFO] pynv_stream: [PYNV][1] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:12:17,467 [INFO] pynv_stream: [PYNV][1] encoder created 8192x4096 source=8192x4096 fps=59.940 target=101414 kwargs={'codec': 'hevc', 'bitrate': '39960718', 'fps': '59.940060', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=source_x2 configured_bitrate=50M
2026-05-18 09:12:17,467 [INFO] pynv_stream: [PYNV][1] VRAM encoder_created: used=1874.4/8187.5MiB cupy_pool=18.0/18.0MiB pinned_free_blocks=0
2026-05-18 09:12:17,468 [INFO] pynv_stream: [PYNV][1] audio cache hit: d2cfb473897afd5751b1.aac bytes=54698154 total_elapsed=0.000s
2026-05-18 09:12:17,469 [INFO] pynv_stream: [PYNV][1] pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 59.940060 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*1502:dts=N*1502 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:12:17,469 [INFO] pynv_stream: [PYNV][1] pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f mpegts -i - -thread_queue_size 1024 -f aac -t 1691.924 -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\d2cfb473897afd5751b1.aac -map 0:v:0 -map 1:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1691.924 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=aac duration=1691.924
2026-05-18 09:12:17,534 [INFO] pynv_stream: [PYNV][1] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:12:17,534 [INFO] pynv_stream: [PYNV][1] alpha passthrough active: projection=sbs_half_equirect process=8192x4096 output=8192x4096 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:12:17,717 [INFO] pynv_stream: [PYNV][1] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=59.940 source_fps=59.940
2026-05-18 09:12:17,792 [INFO] pynv_stream: [PYNV][1] first real video bitstream written; slate audio may switch
2026-05-18 09:12:17,793 [INFO] pynv_stream: [PYNV][1] VRAM first_real_video_bitstream: used=3308.6/8187.5MiB cupy_pool=152.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:18,701 [INFO] pynv_stream: [PYNV][1] frame 30/101414 fps=25.70 interval_fps=25.70 src_idx=29 bytes=0 out_bps=0.2M stage_avg_ms decode=4.39 composite=23.89 sync=0.01 encode=10.57 mux=0.03 mat_avg_ms pre=0.26 ort=23.10 kernel=0.00 stage_max_ms decode=71.60 composite=110.81 sync=0.02 encode=15.69 mux=0.23 mat_max_ms pre=5.62 ort=97.93 kernel=0.00
2026-05-18 09:12:19,787 [INFO] pynv_stream: [PYNV][1] frame 60/101414 fps=26.64 interval_fps=27.65 src_idx=59 bytes=0 out_bps=0.2M stage_avg_ms decode=1.80 composite=20.65 sync=0.01 encode=13.64 mux=0.03 mat_avg_ms pre=0.07 ort=20.30 kernel=0.00 stage_max_ms decode=2.03 composite=21.52 sync=0.19 encode=14.27 mux=0.06 mat_max_ms pre=0.12 ort=21.13 kernel=0.00
2026-05-18 09:12:21,080 [INFO] pynv_stream: [PYNV][1] frame 90/101414 fps=25.38 interval_fps=23.19 src_idx=89 bytes=0 out_bps=27.6M stage_avg_ms decode=1.51 composite=20.68 sync=0.01 encode=20.56 mux=0.33 mat_avg_ms pre=0.08 ort=20.31 kernel=0.00 stage_max_ms decode=1.88 composite=21.84 sync=0.01 encode=26.80 mux=0.88 mat_max_ms pre=0.12 ort=21.40 kernel=0.00
2026-05-18 09:12:21,322 [WARNING] pynv_stream: [PYNV][1][ffmpeg] [aac @ 000002a594d125c0] Estimating duration from bitrate, this may be inaccurate
2026-05-18 09:12:21,325 [INFO] pynv_stream: [PYNV][1] reader first stdout chunk len=8648
2026-05-18 09:12:21,326 [INFO] pynv_stream: [PYNV][1] iter first chunk len=8648 bytes=8648
2026-05-18 09:12:21,336 [INFO] media: passthrough_live[1] preroll ready: profile=vlc nplayer=False chunks=61 bytes=1057592 target=1048576 elapsed=4.218s
2026-05-18 09:12:21,340 [INFO] media: passthrough_live[1] first chunk: len=8648 sent=8648 stream_bytes=1057592
2026-05-18 09:12:21,424 [INFO] matting: [DIAG] alpha #100 (gpu-pre): frame=8192x4096 input_shape=(2,3,1024,1024) preprocess=0.1ms ort_run=20.2ms square=False split_sbs=True split_active=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:12:21,899 [INFO] media: passthrough_live[1] finally begin: sent=9342472 stream_bytes=9342472 frames=107
2026-05-18 09:12:21,899 [INFO] pynv_stream: [PYNV][1] iter finally bytes=9342472 frames=107
2026-05-18 09:12:21,899 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:12:21,899 [INFO] pynv_stream: [PYNV][1] close begin bytes=9342472 frames=107
2026-05-18 09:12:21,900 [INFO] pynv_stream: [PYNV][1] VRAM close_begin: used=3308.6/8187.5MiB cupy_pool=152.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:21,900 [INFO] pynv_stream: [PYNV][1] mux stdin closed before write at frame=108
2026-05-18 09:12:21,909 [INFO] media: passthrough_live[2] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52768)
2026-05-18 09:12:21,909 [INFO] media: passthrough_live[2] start: SAVR-902_4_8k.mp4 @ 0.00s from Address(host='192.168.31.106', port=52768) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:12:21,916 [INFO] pynv_stream: [PYNV][1] reader done chunks=336 bytes=9343036 stop=True
2026-05-18 09:12:21,923 [INFO] pynv_stream: [PYNV][1] close: waiting thread name=pynv-worker
2026-05-18 09:12:22,058 [INFO] pynv_stream: [PYNV][1] worker closing mux stdin frames=107 stop=True
2026-05-18 09:12:22,080 [INFO] pynv_stream: [PYNV][1] VRAM worker_done: used=2777.6/8187.5MiB cupy_pool=152.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:22,080 [INFO] pynv_stream: [PYNV][1] worker done frames=107 bytes_emitted=9342472 reader_started=True
2026-05-18 09:12:22,080 [INFO] media: passthrough_live[2] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=9128219904
2026-05-18 09:12:22,081 [INFO] pynv_stream: [PYNV][1] sentinel posted
2026-05-18 09:12:22,081 [INFO] pynv_stream: [PYNV][1] decoder stop thread begin
2026-05-18 09:12:22,081 [INFO] pynv_stream: [PYNV][1] decoder stop thread done
2026-05-18 09:12:22,081 [INFO] pynv_stream: [PYNV][1] VRAM decoder_stop_joined: used=2777.6/8187.5MiB cupy_pool=120.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:22,095 [INFO] pynv_stream: [PYNV][1] VRAM close_done: used=2777.6/8187.5MiB cupy_pool=120.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:22,095 [INFO] pynv_stream: [PYNV][1] close done
2026-05-18 09:12:48,865 [INFO] media: passthrough_live[3] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52774)
2026-05-18 09:12:48,865 [INFO] media: passthrough_live[3] start: ST-nidekikibabe-2026_LRF_Full_SBS.mp4 @ 0.00s from Address(host='192.168.31.106', port=52774) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:12:49,102 [INFO] media: passthrough_live[3] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=4381225892 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '4381225892', 'TimeSeekRange.dlna.org': 'npt=0.000-1122.367/1122.367', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1122.367', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:12:49,102 [INFO] pynv_stream: [PYNV][2] worker start src=ST-nidekikibabe-2026_LRF_Full_SBS.mp4 path=E:\VR_Video_Passthrough_Server\videos\ST-nidekikibabe-2026_LRF_Full_SBS.mp4 start=0.000
2026-05-18 09:12:49,102 [INFO] pynv_stream: [PYNV][2] VRAM worker_start: used=1953.5/8187.5MiB cupy_pool=120.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:49,102 [INFO] pynv_stream: [PYNV][2] iter start
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] VRAM decoder_metadata_created: used=1953.5/8187.5MiB cupy_pool=120.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] decoder created mode=simple
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] VRAM decoder_created: used=1953.5/8187.5MiB cupy_pool=120.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] source meta: codec=h264 profile=Constrained Baseline pix_fmt=yuv420p bit_depth=8 level=50 size=2160x1440 process_size=2160x1440 output_size=8192x4096 source_fps=30.000 output_fps=30.000 fps_cap=60.000 duration=1122.367 frames=33671 color=/// container=mpegts output_mode=alpha
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=30.000 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:12:49,117 [INFO] pynv_stream: [PYNV][2] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:12:49,323 [INFO] pynv_stream: [PYNV][2] encoder created 8192x4096 source=2160x1440 fps=30.000 target=33671 kwargs={'codec': 'hevc', 'bitrate': '28911524', 'fps': '30.000000', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=source_x2 configured_bitrate=50M
2026-05-18 09:12:49,323 [INFO] pynv_stream: [PYNV][2] VRAM encoder_created: used=2388.4/8187.5MiB cupy_pool=120.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:49,325 [INFO] pynv_stream: [PYNV][2] slate audio server listening: tcp://127.0.0.1:4145
2026-05-18 09:12:49,325 [INFO] pynv_stream: [PYNV][2] slate audio cache build cmd: .\ffmpeg.EXE -nostdin -hide_banner -loglevel warning -y -probesize 32768 -analyzeduration 0 -vn -sn -dn -i E:\VR_Video_Passthrough_Server\videos\ST-nidekikibabe-2026_LRF_Full_SBS.mp4 -vn -sn -dn -map 0:a:0? -map_metadata -1 -map_chapters -1 -c:a copy -f adts E:\VR_Video_Passthrough_Server\runtime_cache\audio\f2b3ccd0db22f67ada2b.5128.2.tmp.aac
2026-05-18 09:12:49,326 [INFO] pynv_stream: [PYNV][2] slate pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 30.000000 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*3000:dts=N*3000 -color_range tv -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:12:49,326 [INFO] pynv_stream: [PYNV][2] slate pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f aac -probesize 32768 -analyzeduration 0 -i tcp://127.0.0.1:4145 -thread_queue_size 1024 -f mpegts -probesize 32768 -analyzeduration 0 -i - -map 1:v:0 -map 0:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1122.367 -color_range tv -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=tcp-aac duration=1122.367
2026-05-18 09:12:49,389 [INFO] pynv_stream: [PYNV][2] slate audio silence cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -re -f lavfi -i anullsrc=r=48000:cl=stereo -c:a aac -ar 48000 -ac 2 -b:a 192k -f adts -
2026-05-18 09:12:49,463 [INFO] pynv_stream: [PYNV][2] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:12:49,463 [INFO] pynv_stream: [PYNV][2] alpha passthrough active: projection=flat2d process=2160x1440 output=8192x4096 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:12:49,470 [INFO] pynv_stream: [PYNV][2] slate video begin: waiting for audio cache alloc=0.007s
2026-05-18 09:12:49,476 [INFO] pynv_stream: [PYNV][2] slate first encode: bytes=0 elapsed=0.005s
2026-05-18 09:12:49,867 [INFO] pynv_stream: [PYNV][2] slate audio cache ready: f2b3ccd0db22f67ada2b.aac bytes=19088542 ffmpeg_elapsed=0.54s communicate_elapsed=0.004s stat_elapsed=0.000s replace_elapsed=0.001s total_elapsed=0.541s
2026-05-18 09:12:49,877 [INFO] pynv_stream: [PYNV][2] slate audio source ready; waiting for real video start
2026-05-18 09:12:49,877 [INFO] pynv_stream: [PYNV][2] slate audio keeps silence until first real video bitstream
2026-05-18 09:12:49,898 [INFO] pynv_stream: [PYNV][2] slate video end: frames=15 ready=True direct=False failed=False elapsed=0.427s
2026-05-18 09:12:50,137 [INFO] pynv_stream: [PYNV][2] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=30.000 source_fps=30.000
2026-05-18 09:12:50,137 [INFO] pynv_stream: [PYNV][2] first bitstream len=791
2026-05-18 09:12:50,137 [INFO] pynv_stream: [PYNV][2] first real video bitstream written; slate audio may switch
2026-05-18 09:12:50,137 [INFO] pynv_stream: [PYNV][2] VRAM first_real_video_bitstream: used=3053.6/8187.5MiB cupy_pool=150.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:50,146 [INFO] pynv_stream: [PYNV][2] slate audio switching to cached AAC: f2b3ccd0db22f67ada2b.aac
2026-05-18 09:12:50,175 [INFO] pynv_stream: [PYNV][2] slate audio cache stream cmd: .\ffmpeg.EXE -nostdin -hide_banner -loglevel warning -f aac -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\f2b3ccd0db22f67ada2b.aac -c:a copy -f adts -
2026-05-18 09:12:50,503 [INFO] pynv_stream: [PYNV][2] slate audio server done
2026-05-18 09:12:51,109 [INFO] pynv_stream: [PYNV][2] frame 30/33671 fps=24.79 interval_fps=24.79 src_idx=29 bytes=0 out_bps=33.2M stage_avg_ms decode=5.07 composite=17.71 sync=0.01 encode=17.17 mux=0.33 mat_avg_ms pre=0.06 ort=17.35 kernel=0.00 stage_max_ms decode=130.17 composite=108.19 sync=0.22 encode=21.72 mux=0.89 mat_max_ms pre=0.18 ort=106.19 kernel=0.00
2026-05-18 09:12:51,209 [INFO] pynv_stream: [PYNV][2] reader first stdout chunk len=8648
2026-05-18 09:12:51,210 [INFO] pynv_stream: [PYNV][2] iter first chunk len=8648 bytes=8648
2026-05-18 09:12:51,218 [INFO] media: passthrough_live[3] preroll ready: profile=vlc nplayer=False chunks=43 bytes=1055544 target=1048576 elapsed=2.125s
2026-05-18 09:12:51,218 [INFO] media: passthrough_live[3] first chunk: len=8648 sent=8648 stream_bytes=1055544
2026-05-18 09:12:51,644 [INFO] media: passthrough_live[3] finally begin: sent=7301732 stream_bytes=7301732 frames=44
2026-05-18 09:12:51,644 [INFO] pynv_stream: [PYNV][2] iter finally bytes=7301732 frames=44
2026-05-18 09:12:51,645 [INFO] pynv_stream: [PYNV][2] close begin bytes=7301732 frames=44
2026-05-18 09:12:51,645 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:12:51,645 [INFO] pynv_stream: [PYNV][2] VRAM close_begin: used=3053.6/8187.5MiB cupy_pool=150.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:51,649 [INFO] pynv_stream: [PYNV][2] mux stdin closed before write at frame=45
2026-05-18 09:12:51,651 [INFO] media: passthrough_live[4] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52776)
2026-05-18 09:12:51,651 [INFO] media: passthrough_live[4] start: ST-nidekikibabe-2026_LRF_Full_SBS.mp4 @ 0.00s from Address(host='192.168.31.106', port=52776) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:12:51,660 [INFO] pynv_stream: [PYNV][2] reader done chunks=260 bytes=7302296 stop=True
2026-05-18 09:12:51,667 [INFO] pynv_stream: [PYNV][2] close: waiting thread name=pynv-worker
2026-05-18 09:12:51,784 [INFO] pynv_stream: [PYNV][2] worker closing mux stdin frames=44 stop=True
2026-05-18 09:12:51,801 [INFO] pynv_stream: [PYNV][2] VRAM worker_done: used=2522.6/8187.5MiB cupy_pool=150.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:51,801 [INFO] pynv_stream: [PYNV][2] worker done frames=44 bytes_emitted=7301732 reader_started=True
2026-05-18 09:12:51,802 [INFO] pynv_stream: [PYNV][2] decoder stop thread begin
2026-05-18 09:12:51,802 [INFO] pynv_stream: [PYNV][2] decoder stop thread done
2026-05-18 09:12:51,802 [INFO] pynv_stream: [PYNV][2] VRAM decoder_stop_joined: used=2522.6/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:51,817 [INFO] pynv_stream: [PYNV][2] VRAM close_done: used=2522.6/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:12:51,817 [INFO] pynv_stream: [PYNV][2] close done
2026-05-18 09:12:51,823 [INFO] media: passthrough_live[4] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=4381225892
2026-05-18 09:12:51,823 [INFO] pynv_stream: [PYNV][2] sentinel posted
2026-05-18 09:13:55,966 [INFO] media: passthrough_live[5] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52782)
2026-05-18 09:13:55,966 [INFO] media: passthrough_live[5] start: ST-nidekikibabe-2026_LRF_Full_SBS.mp4 @ 0.00s from Address(host='192.168.31.106', port=52782) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:13:56,117 [INFO] media: passthrough_live[5] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=4381225892 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '4381225892', 'TimeSeekRange.dlna.org': 'npt=0.000-1122.367/1122.367', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1122.367', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:13:56,117 [INFO] pynv_stream: [PYNV][3] worker start src=ST-nidekikibabe-2026_LRF_Full_SBS.mp4 path=E:\VR_Video_Passthrough_Server\videos\ST-nidekikibabe-2026_LRF_Full_SBS.mp4 start=0.000
2026-05-18 09:13:56,117 [INFO] pynv_stream: [PYNV][3] iter start
2026-05-18 09:13:56,117 [INFO] pynv_stream: [PYNV][3] VRAM worker_start: used=2465.5/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:56,128 [INFO] pynv_stream: [PYNV][3] VRAM decoder_metadata_created: used=2465.5/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:56,128 [INFO] pynv_stream: [PYNV][3] decoder created mode=simple
2026-05-18 09:13:56,129 [INFO] pynv_stream: [PYNV][3] VRAM decoder_created: used=2465.5/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:56,129 [INFO] pynv_stream: [PYNV][3] source meta: codec=h264 profile=Constrained Baseline pix_fmt=yuv420p bit_depth=8 level=50 size=2160x1440 process_size=2160x1440 output_size=8192x4096 source_fps=30.000 output_fps=30.000 fps_cap=60.000 duration=1122.367 frames=33671 color=/// container=mpegts output_mode=alpha
2026-05-18 09:13:56,129 [INFO] pynv_stream: [PYNV][3] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=30.000 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:13:56,129 [INFO] pynv_stream: [PYNV][3] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:13:56,129 [INFO] pynv_stream: [PYNV][3] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:13:56,357 [INFO] pynv_stream: [PYNV][3] encoder created 8192x4096 source=2160x1440 fps=30.000 target=33671 kwargs={'codec': 'hevc', 'bitrate': '28911524', 'fps': '30.000000', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=source_x2 configured_bitrate=50M
2026-05-18 09:13:56,357 [INFO] pynv_stream: [PYNV][3] VRAM encoder_created: used=2900.4/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:56,358 [INFO] pynv_stream: [PYNV][3] audio cache hit: f2b3ccd0db22f67ada2b.aac bytes=19088542 total_elapsed=0.000s
2026-05-18 09:13:56,358 [INFO] pynv_stream: [PYNV][3] pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 30.000000 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*3000:dts=N*3000 -color_range tv -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:13:56,358 [INFO] pynv_stream: [PYNV][3] pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f mpegts -i - -thread_queue_size 1024 -f aac -t 1122.367 -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\f2b3ccd0db22f67ada2b.aac -map 0:v:0 -map 1:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1122.367 -color_range tv -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=aac duration=1122.367
2026-05-18 09:13:56,426 [INFO] pynv_stream: [PYNV][3] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:13:56,426 [INFO] pynv_stream: [PYNV][3] alpha passthrough active: projection=flat2d process=2160x1440 output=8192x4096 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:13:56,457 [INFO] pynv_stream: [PYNV][3] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=30.000 source_fps=30.000
2026-05-18 09:13:56,546 [INFO] pynv_stream: [PYNV][3] first real video bitstream written; slate audio may switch
2026-05-18 09:13:56,546 [INFO] pynv_stream: [PYNV][3] VRAM first_real_video_bitstream: used=3053.6/8187.5MiB cupy_pool=102.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:57,446 [INFO] pynv_stream: [PYNV][3] frame 30/33671 fps=29.41 interval_fps=29.41 src_idx=29 bytes=0 out_bps=28.9M stage_avg_ms decode=1.02 composite=14.20 sync=0.01 encode=5.86 mux=0.98 mat_avg_ms pre=0.06 ort=13.91 kernel=0.00 stage_max_ms decode=16.83 composite=15.53 sync=0.01 encode=21.80 mux=19.62 mat_max_ms pre=0.14 ort=15.14 kernel=0.00
2026-05-18 09:13:57,886 [WARNING] pynv_stream: [PYNV][3][ffmpeg] [aac @ 000001e98529d740] Estimating duration from bitrate, this may be inaccurate
2026-05-18 09:13:57,893 [INFO] pynv_stream: [PYNV][3] reader first stdout chunk len=32768
2026-05-18 09:13:57,893 [INFO] pynv_stream: [PYNV][3] iter first chunk len=32768 bytes=32768
2026-05-18 09:13:57,901 [INFO] media: passthrough_live[5] preroll ready: profile=vlc nplayer=False chunks=42 bytes=1051784 target=1048576 elapsed=1.781s
2026-05-18 09:13:57,901 [INFO] media: passthrough_live[5] first chunk: len=32768 sent=32768 stream_bytes=1051784
2026-05-18 09:13:57,970 [INFO] matting: [DIAG] alpha #200 (gpu-pre): frame=2160x1440 input_shape=(1,3,1024,1536) preprocess=0.1ms ort_run=13.6ms square=False split_sbs=True split_active=False providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:13:58,429 [INFO] media: passthrough_live[5] finally begin: sent=8046776 stream_bytes=8046776 frames=57
2026-05-18 09:13:58,429 [INFO] pynv_stream: [PYNV][3] iter finally bytes=8046776 frames=57
2026-05-18 09:13:58,429 [INFO] pynv_stream: [PYNV][3] close begin bytes=8046776 frames=57
2026-05-18 09:13:58,429 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:13:58,429 [INFO] pynv_stream: [PYNV][3] VRAM close_begin: used=3053.6/8187.5MiB cupy_pool=102.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:58,444 [INFO] media: passthrough_live[6] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52788)
2026-05-18 09:13:58,445 [INFO] media: passthrough_live[6] start: ST-nidekikibabe-2026_LRF_Full_SBS.mp4 @ 0.00s from Address(host='192.168.31.106', port=52788) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:13:58,456 [INFO] pynv_stream: [PYNV][3] reader done chunks=288 bytes=8047340 stop=True
2026-05-18 09:13:58,462 [INFO] pynv_stream: [PYNV][3] close: waiting thread name=pynv-worker
2026-05-18 09:13:58,560 [INFO] pynv_stream: [PYNV][3] worker closing mux stdin frames=57 stop=True
2026-05-18 09:13:58,578 [INFO] pynv_stream: [PYNV][3] VRAM worker_done: used=2522.6/8187.5MiB cupy_pool=102.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:58,578 [INFO] pynv_stream: [PYNV][3] worker done frames=57 bytes_emitted=8046776 reader_started=True
2026-05-18 09:13:58,578 [INFO] pynv_stream: [PYNV][3] decoder stop thread begin
2026-05-18 09:13:58,578 [INFO] pynv_stream: [PYNV][3] decoder stop thread done
2026-05-18 09:13:58,579 [INFO] pynv_stream: [PYNV][3] VRAM decoder_stop_joined: used=2522.6/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:58,595 [INFO] pynv_stream: [PYNV][3] VRAM close_done: used=2522.6/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:13:58,596 [INFO] pynv_stream: [PYNV][3] close done
2026-05-18 09:13:58,610 [INFO] media: passthrough_live[6] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=4381225892
2026-05-18 09:13:58,610 [INFO] pynv_stream: [PYNV][3] sentinel posted
2026-05-18 09:14:11,527 [INFO] media: passthrough_live[7] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52792)
2026-05-18 09:14:11,527 [INFO] media: passthrough_live[7] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52792) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:14:11,788 [INFO] media: passthrough_live[7] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=12807065607 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '12807065607', 'TimeSeekRange.dlna.org': 'npt=0.000-1897.259/1897.259', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1897.259', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:14:11,788 [INFO] pynv_stream: [PYNV][4] worker start src=ebvr-061-2.mp4 path=E:\VR_Video_Passthrough_Server\videos\ebvr-061-2.mp4 start=0.000
2026-05-18 09:14:11,788 [INFO] pynv_stream: [PYNV][4] iter start
2026-05-18 09:14:11,788 [INFO] pynv_stream: [PYNV][4] VRAM worker_start: used=2465.5/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] VRAM decoder_metadata_created: used=2465.5/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] decoder created mode=simple
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] VRAM decoder_created: used=2465.5/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] source meta: codec=h264 profile=High pix_fmt=yuv420p bit_depth=8 level=52 size=4096x2048 process_size=4096x2048 output_size=4096x2048 source_fps=59.938 output_fps=59.938 fps_cap=60.000 duration=1897.197 frames=113715 color=tv/bt709/bt709/bt709 container=mpegts output_mode=alpha
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=59.938 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:14:11,811 [INFO] pynv_stream: [PYNV][4] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:14:12,015 [INFO] pynv_stream: [PYNV][4] encoder created 4096x2048 source=4096x2048 fps=59.938 target=113714 kwargs={'codec': 'hevc', 'bitrate': '50000000', 'fps': '59.938427', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=default configured_bitrate=50M
2026-05-18 09:14:12,015 [INFO] pynv_stream: [PYNV][4] VRAM encoder_created: used=2574.2/8187.5MiB cupy_pool=70.4/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:12,015 [INFO] pynv_stream: [PYNV][4] audio cache hit: 2ddb0ff8fbf3c8b98de3.aac bytes=61334816 total_elapsed=0.000s
2026-05-18 09:14:12,016 [INFO] pynv_stream: [PYNV][4] pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 59.938427 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*1502:dts=N*1502 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:14:12,016 [INFO] pynv_stream: [PYNV][4] pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f mpegts -i - -thread_queue_size 1024 -f aac -t 1897.197 -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\2ddb0ff8fbf3c8b98de3.aac -map 0:v:0 -map 1:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1897.197 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=aac duration=1897.197
2026-05-18 09:14:12,085 [INFO] pynv_stream: [PYNV][4] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:14:12,085 [INFO] pynv_stream: [PYNV][4] alpha passthrough active: projection=sbs_half_equirect process=4096x2048 output=4096x2048 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:14:12,212 [INFO] pynv_stream: [PYNV][4] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=59.938 source_fps=59.938
2026-05-18 09:14:12,277 [INFO] pynv_stream: [PYNV][4] first real video bitstream written; slate audio may switch
2026-05-18 09:14:12,277 [INFO] pynv_stream: [PYNV][4] VRAM first_real_video_bitstream: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:12,803 [INFO] pynv_stream: [PYNV][4] frame 30/113714 fps=41.79 interval_fps=41.79 src_idx=29 bytes=0 out_bps=0.1M stage_avg_ms decode=1.82 composite=21.74 sync=0.01 encode=0.32 mux=0.02 mat_avg_ms pre=0.07 ort=21.40 kernel=0.00 stage_max_ms decode=39.31 composite=87.30 sync=0.01 encode=3.17 mux=0.24 mat_max_ms pre=0.10 ort=86.55 kernel=0.00
2026-05-18 09:14:13,406 [INFO] pynv_stream: [PYNV][4] frame 60/113714 fps=45.42 interval_fps=49.76 src_idx=59 bytes=0 out_bps=0.1M stage_avg_ms decode=0.56 composite=19.30 sync=0.01 encode=0.20 mux=0.01 mat_avg_ms pre=0.07 ort=18.99 kernel=0.00 stage_max_ms decode=0.71 composite=21.22 sync=0.01 encode=0.34 mux=0.03 mat_max_ms pre=0.13 ort=20.92 kernel=0.00
2026-05-18 09:14:13,954 [INFO] matting: [DIAG] alpha #300 (gpu-pre): frame=4096x2048 input_shape=(2,3,1024,1024) preprocess=0.1ms ort_run=18.8ms square=False split_sbs=True split_active=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:14:14,015 [INFO] pynv_stream: [PYNV][4] frame 90/113714 fps=46.64 interval_fps=49.30 src_idx=89 bytes=0 out_bps=41.8M stage_avg_ms decode=0.42 composite=19.37 sync=0.01 encode=0.23 mux=0.23 mat_avg_ms pre=0.07 ort=19.04 kernel=0.00 stage_max_ms decode=0.62 composite=19.81 sync=0.01 encode=0.29 mux=0.51 mat_max_ms pre=0.23 ort=19.45 kernel=0.00
2026-05-18 09:14:14,443 [WARNING] pynv_stream: [PYNV][4][ffmpeg] [aac @ 000002482aa42200] Estimating duration from bitrate, this may be inaccurate
2026-05-18 09:14:14,447 [INFO] pynv_stream: [PYNV][4] reader first stdout chunk len=2820
2026-05-18 09:14:14,447 [INFO] pynv_stream: [PYNV][4] iter first chunk len=2820 bytes=2820
2026-05-18 09:14:14,452 [INFO] media: passthrough_live[7] preroll ready: profile=vlc nplayer=False chunks=50 bytes=1051144 target=1048576 elapsed=2.656s
2026-05-18 09:14:14,452 [INFO] media: passthrough_live[7] first chunk: len=2820 sent=2820 stream_bytes=1051144
2026-05-18 09:14:14,624 [INFO] pynv_stream: [PYNV][4] frame 120/113714 fps=47.28 interval_fps=49.34 src_idx=119 bytes=6071084 out_bps=42.5M stage_avg_ms decode=0.40 composite=19.33 sync=0.01 encode=0.25 mux=0.23 mat_avg_ms pre=0.10 ort=18.92 kernel=0.00 stage_max_ms decode=0.57 composite=19.76 sync=0.01 encode=0.38 mux=0.35 mat_max_ms pre=0.23 ort=19.15 kernel=0.00
2026-05-18 09:14:14,702 [INFO] media: passthrough_live[8] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52794)
2026-05-18 09:14:14,702 [INFO] media: passthrough_live[8] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52794) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:14:14,893 [INFO] media: passthrough_live[8] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=12807065607
2026-05-18 09:14:14,894 [INFO] media: passthrough_live[7] finally begin: sent=6519464 stream_bytes=6519840 frames=133
2026-05-18 09:14:14,894 [INFO] pynv_stream: [PYNV][4] iter finally bytes=6519840 frames=133
2026-05-18 09:14:14,894 [INFO] pynv_stream: [PYNV][4] close begin bytes=6519840 frames=133
2026-05-18 09:14:14,894 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:14:14,895 [INFO] pynv_stream: [PYNV][4] VRAM close_begin: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:14,895 [INFO] pynv_stream: [PYNV][4] reader done chunks=251 bytes=6585376 stop=True
2026-05-18 09:14:14,895 [INFO] pynv_stream: [PYNV][4] sentinel posted
2026-05-18 09:14:14,904 [INFO] pynv_stream: [PYNV][4] close: waiting thread name=pynv-worker
2026-05-18 09:14:14,920 [INFO] pynv_stream: [PYNV][4] worker closing mux stdin frames=133 stop=True
2026-05-18 09:14:14,937 [INFO] pynv_stream: [PYNV][4] VRAM worker_done: used=2633.7/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:14,937 [INFO] pynv_stream: [PYNV][4] worker done frames=133 bytes_emitted=6519840 reader_started=True
2026-05-18 09:14:14,937 [INFO] pynv_stream: [PYNV][4] decoder stop thread begin
2026-05-18 09:14:14,937 [INFO] pynv_stream: [PYNV][4] decoder stop thread done
2026-05-18 09:14:14,938 [INFO] pynv_stream: [PYNV][4] VRAM decoder_stop_joined: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:14,951 [INFO] pynv_stream: [PYNV][4] VRAM close_done: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:14:14,952 [INFO] pynv_stream: [PYNV][4] close done
2026-05-18 09:20:41,512 [INFO] media: passthrough_live[9] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52816)
2026-05-18 09:20:41,512 [INFO] media: passthrough_live[9] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52816) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:20:41,685 [INFO] media: passthrough_live[9] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=12807065607 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '12807065607', 'TimeSeekRange.dlna.org': 'npt=0.000-1897.259/1897.259', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1897.259', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:20:41,685 [INFO] pynv_stream: [PYNV][5] worker start src=ebvr-061-2.mp4 path=E:\VR_Video_Passthrough_Server\videos\ebvr-061-2.mp4 start=0.000
2026-05-18 09:20:41,686 [INFO] pynv_stream: [PYNV][5] iter start
2026-05-18 09:20:41,686 [INFO] pynv_stream: [PYNV][5] VRAM worker_start: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] VRAM decoder_metadata_created: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] decoder created mode=simple
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] VRAM decoder_created: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] source meta: codec=h264 profile=High pix_fmt=yuv420p bit_depth=8 level=52 size=4096x2048 process_size=4096x2048 output_size=4096x2048 source_fps=59.938 output_fps=59.938 fps_cap=60.000 duration=1897.197 frames=113715 color=tv/bt709/bt709/bt709 container=mpegts output_mode=alpha
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=59.938 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:20:41,711 [INFO] pynv_stream: [PYNV][5] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:20:41,909 [INFO] pynv_stream: [PYNV][5] encoder created 4096x2048 source=4096x2048 fps=59.938 target=113714 kwargs={'codec': 'hevc', 'bitrate': '50000000', 'fps': '59.938427', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=default configured_bitrate=50M
2026-05-18 09:20:41,909 [INFO] pynv_stream: [PYNV][5] VRAM encoder_created: used=2574.2/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:41,910 [INFO] pynv_stream: [PYNV][5] audio cache hit: 2ddb0ff8fbf3c8b98de3.aac bytes=61334816 total_elapsed=0.000s
2026-05-18 09:20:41,910 [INFO] pynv_stream: [PYNV][5] pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 59.938427 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*1502:dts=N*1502 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:20:41,910 [INFO] pynv_stream: [PYNV][5] pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f mpegts -i - -thread_queue_size 1024 -f aac -t 1897.197 -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\2ddb0ff8fbf3c8b98de3.aac -map 0:v:0 -map 1:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1897.197 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=aac duration=1897.197
2026-05-18 09:20:41,978 [INFO] pynv_stream: [PYNV][5] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:20:41,979 [INFO] pynv_stream: [PYNV][5] alpha passthrough active: projection=sbs_half_equirect process=4096x2048 output=4096x2048 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:20:42,032 [INFO] pynv_stream: [PYNV][5] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=59.938 source_fps=59.938
2026-05-18 09:20:42,099 [INFO] pynv_stream: [PYNV][5] first real video bitstream written; slate audio may switch
2026-05-18 09:20:42,099 [INFO] pynv_stream: [PYNV][5] VRAM first_real_video_bitstream: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:42,628 [INFO] pynv_stream: [PYNV][5] frame 30/113714 fps=46.20 interval_fps=46.20 src_idx=29 bytes=0 out_bps=0.1M stage_avg_ms decode=1.59 composite=19.67 sync=0.01 encode=0.32 mux=0.02 mat_avg_ms pre=0.07 ort=19.32 kernel=0.00 stage_max_ms decode=32.98 composite=20.97 sync=0.01 encode=2.78 mux=0.24 mat_max_ms pre=0.10 ort=20.44 kernel=0.00
2026-05-18 09:20:43,090 [INFO] matting: [DIAG] alpha #400 (gpu-pre): frame=4096x2048 input_shape=(2,3,1024,1024) preprocess=0.1ms ort_run=18.8ms square=False split_sbs=True split_active=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:20:43,230 [INFO] pynv_stream: [PYNV][5] frame 60/113714 fps=47.95 interval_fps=49.84 src_idx=59 bytes=0 out_bps=0.1M stage_avg_ms decode=0.53 composite=19.28 sync=0.01 encode=0.21 mux=0.02 mat_avg_ms pre=0.07 ort=18.95 kernel=0.00 stage_max_ms decode=0.67 composite=19.85 sync=0.01 encode=0.30 mux=0.03 mat_max_ms pre=0.11 ort=19.46 kernel=0.00
2026-05-18 09:20:43,841 [INFO] pynv_stream: [PYNV][5] frame 90/113714 fps=48.33 interval_fps=49.12 src_idx=89 bytes=0 out_bps=41.6M stage_avg_ms decode=0.42 composite=19.45 sync=0.01 encode=0.22 mux=0.24 mat_avg_ms pre=0.07 ort=19.13 kernel=0.00 stage_max_ms decode=0.61 composite=20.35 sync=0.01 encode=0.27 mux=0.67 mat_max_ms pre=0.11 ort=20.05 kernel=0.00
2026-05-18 09:20:44,269 [WARNING] pynv_stream: [PYNV][5][ffmpeg] [aac @ 0000021dfd302200] Estimating duration from bitrate, this may be inaccurate
2026-05-18 09:20:44,274 [INFO] pynv_stream: [PYNV][5] reader first stdout chunk len=2820
2026-05-18 09:20:44,274 [INFO] pynv_stream: [PYNV][5] iter first chunk len=2820 bytes=2820
2026-05-18 09:20:44,281 [INFO] media: passthrough_live[9] preroll ready: profile=vlc nplayer=False chunks=48 bytes=1051144 target=1048576 elapsed=2.594s
2026-05-18 09:20:44,281 [INFO] media: passthrough_live[9] first chunk: len=2820 sent=2820 stream_bytes=1051144
2026-05-18 09:20:44,456 [INFO] pynv_stream: [PYNV][5] frame 120/113714 fps=48.44 interval_fps=48.82 src_idx=119 bytes=6071084 out_bps=42.1M stage_avg_ms decode=0.43 composite=19.52 sync=0.01 encode=0.24 mux=0.25 mat_avg_ms pre=0.10 ort=19.13 kernel=0.00 stage_max_ms decode=0.75 composite=20.79 sync=0.03 encode=0.51 mux=0.53 mat_max_ms pre=0.34 ort=20.41 kernel=0.00
2026-05-18 09:20:44,638 [INFO] media: passthrough_live[9] finally begin: sent=7241384 stream_bytes=7241384 frames=128
2026-05-18 09:20:44,638 [INFO] pynv_stream: [PYNV][5] iter finally bytes=7241384 frames=128
2026-05-18 09:20:44,639 [INFO] pynv_stream: [PYNV][5] close begin bytes=7241384 frames=128
2026-05-18 09:20:44,639 [INFO] pynv_stream: [PYNV][5] VRAM close_begin: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:44,650 [INFO] pynv_stream: [PYNV][5] worker closing mux stdin frames=128 stop=True
2026-05-18 09:20:44,665 [INFO] pynv_stream: [PYNV][5] VRAM worker_done: used=2633.7/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:44,666 [INFO] pynv_stream: [PYNV][5] worker done frames=128 bytes_emitted=7241384 reader_started=True
2026-05-18 09:20:44,666 [INFO] pynv_stream: [PYNV][5] reader done chunks=275 bytes=7242136 stop=True
2026-05-18 09:20:44,694 [INFO] pynv_stream: [PYNV][5] decoder stop thread begin
2026-05-18 09:20:44,694 [INFO] pynv_stream: [PYNV][5] decoder stop thread done
2026-05-18 09:20:44,694 [INFO] pynv_stream: [PYNV][5] VRAM decoder_stop_joined: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:44,708 [INFO] pynv_stream: [PYNV][5] VRAM close_done: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:20:44,708 [INFO] pynv_stream: [PYNV][5] close done
2026-05-18 09:20:44,717 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:20:44,717 [INFO] pynv_stream: [PYNV][5] sentinel posted
2026-05-18 09:20:44,719 [INFO] media: passthrough_live[10] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52818)
2026-05-18 09:20:44,719 [INFO] media: passthrough_live[10] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52818) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:20:44,895 [INFO] media: passthrough_live[10] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=12807065607
2026-05-18 09:21:46,463 [INFO] media: passthrough_live[11] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52832)
2026-05-18 09:21:46,463 [INFO] media: passthrough_live[11] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52832) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:21:46,657 [INFO] media: passthrough_live[11] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=12807065607 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '12807065607', 'TimeSeekRange.dlna.org': 'npt=0.000-1897.259/1897.259', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1897.259', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:21:46,657 [INFO] pynv_stream: [PYNV][6] worker start src=ebvr-061-2.mp4 path=E:\VR_Video_Passthrough_Server\videos\ebvr-061-2.mp4 start=0.000
2026-05-18 09:21:46,657 [INFO] pynv_stream: [PYNV][6] iter start
2026-05-18 09:21:46,657 [INFO] pynv_stream: [PYNV][6] VRAM worker_start: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] VRAM decoder_metadata_created: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] decoder created mode=simple
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] VRAM decoder_created: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] source meta: codec=h264 profile=High pix_fmt=yuv420p bit_depth=8 level=52 size=4096x2048 process_size=4096x2048 output_size=4096x2048 source_fps=59.938 output_fps=59.938 fps_cap=60.000 duration=1897.197 frames=113715 color=tv/bt709/bt709/bt709 container=mpegts output_mode=alpha
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=59.938 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:21:46,683 [INFO] pynv_stream: [PYNV][6] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:21:47,226 [INFO] pynv_stream: [PYNV][6] encoder created 4096x2048 source=4096x2048 fps=59.938 target=113714 kwargs={'codec': 'hevc', 'bitrate': '50000000', 'fps': '59.938427', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=default configured_bitrate=50M
2026-05-18 09:21:47,226 [INFO] pynv_stream: [PYNV][6] VRAM encoder_created: used=2574.2/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:47,227 [INFO] pynv_stream: [PYNV][6] audio cache hit: 2ddb0ff8fbf3c8b98de3.aac bytes=61334816 total_elapsed=0.000s
2026-05-18 09:21:47,227 [INFO] pynv_stream: [PYNV][6] pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 59.938427 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*1502:dts=N*1502 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:21:47,227 [INFO] pynv_stream: [PYNV][6] pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f mpegts -i - -thread_queue_size 1024 -f aac -t 1897.197 -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\2ddb0ff8fbf3c8b98de3.aac -map 0:v:0 -map 1:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1897.197 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=aac duration=1897.197
2026-05-18 09:21:47,295 [INFO] pynv_stream: [PYNV][6] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:21:47,295 [INFO] pynv_stream: [PYNV][6] alpha passthrough active: projection=sbs_half_equirect process=4096x2048 output=4096x2048 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:21:47,345 [INFO] pynv_stream: [PYNV][6] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=59.938 source_fps=59.938
2026-05-18 09:21:47,411 [INFO] pynv_stream: [PYNV][6] first real video bitstream written; slate audio may switch
2026-05-18 09:21:47,411 [INFO] pynv_stream: [PYNV][6] VRAM first_real_video_bitstream: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:47,817 [INFO] matting: [DIAG] alpha #500 (gpu-pre): frame=4096x2048 input_shape=(2,3,1024,1024) preprocess=0.1ms ort_run=18.9ms square=False split_sbs=True split_active=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:21:47,937 [INFO] pynv_stream: [PYNV][6] frame 30/113714 fps=46.72 interval_fps=46.72 src_idx=29 bytes=0 out_bps=0.1M stage_avg_ms decode=1.52 composite=19.54 sync=0.01 encode=0.29 mux=0.02 mat_avg_ms pre=0.06 ort=19.23 kernel=0.00 stage_max_ms decode=29.68 composite=20.87 sync=0.01 encode=2.60 mux=0.27 mat_max_ms pre=0.09 ort=20.42 kernel=0.00
2026-05-18 09:21:48,543 [INFO] pynv_stream: [PYNV][6] frame 60/113714 fps=48.11 interval_fps=49.59 src_idx=59 bytes=0 out_bps=0.1M stage_avg_ms decode=0.55 composite=19.37 sync=0.01 encode=0.20 mux=0.01 mat_avg_ms pre=0.07 ort=19.04 kernel=0.00 stage_max_ms decode=0.66 composite=20.66 sync=0.01 encode=0.30 mux=0.02 mat_max_ms pre=0.12 ort=20.24 kernel=0.00
2026-05-18 09:21:49,157 [INFO] pynv_stream: [PYNV][6] frame 90/113714 fps=48.35 interval_fps=48.85 src_idx=89 bytes=0 out_bps=41.4M stage_avg_ms decode=0.40 composite=19.52 sync=0.01 encode=0.25 mux=0.27 mat_avg_ms pre=0.06 ort=19.19 kernel=0.00 stage_max_ms decode=0.58 composite=21.25 sync=0.01 encode=0.40 mux=0.97 mat_max_ms pre=0.09 ort=20.89 kernel=0.00
2026-05-18 09:21:49,583 [WARNING] pynv_stream: [PYNV][6][ffmpeg] [aac @ 000001f7ea7e2200] Estimating duration from bitrate, this may be inaccurate
2026-05-18 09:21:49,587 [INFO] pynv_stream: [PYNV][6] reader first stdout chunk len=2820
2026-05-18 09:21:49,587 [INFO] pynv_stream: [PYNV][6] iter first chunk len=2820 bytes=2820
2026-05-18 09:21:49,596 [INFO] media: passthrough_live[11] preroll ready: profile=vlc nplayer=False chunks=48 bytes=1051144 target=1048576 elapsed=2.937s
2026-05-18 09:21:49,597 [INFO] media: passthrough_live[11] first chunk: len=2820 sent=2820 stream_bytes=1051144
2026-05-18 09:21:49,758 [INFO] pynv_stream: [PYNV][6] frame 120/113714 fps=48.73 interval_fps=49.91 src_idx=119 bytes=6071084 out_bps=43.0M stage_avg_ms decode=0.37 composite=19.21 sync=0.01 encode=0.21 mux=0.21 mat_avg_ms pre=0.09 ort=18.87 kernel=0.00 stage_max_ms decode=0.48 composite=19.54 sync=0.04 encode=0.33 mux=0.35 mat_max_ms pre=0.27 ort=19.08 kernel=0.00
2026-05-18 09:21:49,838 [INFO] matting: [DIAG] alpha #600 (gpu-pre): frame=4096x2048 input_shape=(2,3,1024,1024) preprocess=0.2ms ort_run=18.8ms square=False split_sbs=True split_active=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:21:50,094 [INFO] media: passthrough_live[11] finally begin: sent=7960108 stream_bytes=7960108 frames=136
2026-05-18 09:21:50,094 [INFO] pynv_stream: [PYNV][6] iter finally bytes=7960108 frames=136
2026-05-18 09:21:50,094 [INFO] pynv_stream: [PYNV][6] close begin bytes=7960108 frames=136
2026-05-18 09:21:50,094 [INFO] pynv_stream: [PYNV][6] VRAM close_begin: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:50,108 [INFO] pynv_stream: [PYNV][6] worker closing mux stdin frames=136 stop=True
2026-05-18 09:21:50,122 [INFO] pynv_stream: [PYNV][6] reader done chunks=307 bytes=7992876 stop=True
2026-05-18 09:21:50,123 [INFO] pynv_stream: [PYNV][6] VRAM worker_done: used=2633.7/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:50,123 [INFO] pynv_stream: [PYNV][6] worker done frames=136 bytes_emitted=7960108 reader_started=True
2026-05-18 09:21:50,140 [INFO] pynv_stream: [PYNV][6] decoder stop thread begin
2026-05-18 09:21:50,140 [INFO] pynv_stream: [PYNV][6] decoder stop thread done
2026-05-18 09:21:50,140 [INFO] pynv_stream: [PYNV][6] VRAM decoder_stop_joined: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:50,155 [INFO] pynv_stream: [PYNV][6] VRAM close_done: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:21:50,155 [INFO] pynv_stream: [PYNV][6] close done
2026-05-18 09:21:50,163 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:21:50,164 [INFO] pynv_stream: [PYNV][6] sentinel posted
2026-05-18 09:21:50,166 [INFO] media: passthrough_live[12] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52834)
2026-05-18 09:21:50,166 [INFO] media: passthrough_live[12] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52834) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:21:50,339 [INFO] media: passthrough_live[12] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=12807065607
2026-05-18 09:31:49,979 [INFO] media: passthrough_live[13] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range=None time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52862)
2026-05-18 09:31:50,012 [INFO] media: passthrough_live[13] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52862) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:31:50,341 [INFO] media: passthrough_live[13] response: profile=vlc status=200 backend=pynv_hevc verdict=pynv_hevc ignored_range=None live_total_est=12807065607 send_bps=100000000 send_pacing=True headers={'Content-Type': 'video/MP2T', 'Cache-Control': 'no-store', 'transferMode.dlna.org': 'Streaming', 'X-Passthrough-Mode': 'live-mpegts-alpha', 'X-Passthrough-Seek-Time': '0.000', 'X-Passthrough-Backend': 'pynv_hevc', 'X-Passthrough-Backend-Verdict': 'pynv_hevc', 'X-Passthrough-Estimated-Size': '12807065607', 'TimeSeekRange.dlna.org': 'npt=0.000-1897.259/1897.259', 'X-AvailableSeekRange.dlna.org': '1 npt=0.000-1897.259', 'X-Passthrough-FrameRate': '60', 'Accept-Ranges': 'none', 'contentFeatures.dlna.org': 'DLNA.ORG_PN=HEVC_TS_NA_ISO;DLNA.ORG_OP=10;DLNA.ORG_CI=1;DLNA.ORG_FLAGS=41700000000000000000000000000000'}
2026-05-18 09:31:50,341 [INFO] pynv_stream: [PYNV][7] worker start src=ebvr-061-2.mp4 path=E:\VR_Video_Passthrough_Server\videos\ebvr-061-2.mp4 start=0.000
2026-05-18 09:31:50,341 [INFO] pynv_stream: [PYNV][7] VRAM worker_start: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:50,342 [INFO] pynv_stream: [PYNV][7] iter start
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] VRAM decoder_metadata_created: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] decoder created mode=simple
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] VRAM decoder_created: used=2465.5/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] source meta: codec=h264 profile=High pix_fmt=yuv420p bit_depth=8 level=52 size=4096x2048 process_size=4096x2048 output_size=4096x2048 source_fps=59.938 output_fps=59.938 fps_cap=60.000 duration=1897.197 frames=113715 color=tv/bt709/bt709/bt709 container=mpegts output_mode=alpha
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] runtime config: alpha_stride=1 alpha_mode=reuse model=E:\VR_Video_Passthrough_Server\models\rvm_mobilenetv3_fp32.onnx decoder=simple worker_mode=serial output_codec=hevc producer_realtime_pacing=True send_realtime_pacing=True max_fps=60.000 output_fps=59.938 rvm_bypass_alpha=False nv12_slots=3
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] alpha decoder detail: effective_decoder=simple batch=1 buffer=2 owned_copy=False allow_threaded=False
2026-05-18 09:31:50,365 [INFO] pynv_stream: [PYNV][7] subtitle lookup config: enabled=False exts=('.ass', '.srt')
2026-05-18 09:31:50,567 [INFO] pynv_stream: [PYNV][7] encoder created 4096x2048 source=4096x2048 fps=59.938 target=113714 kwargs={'codec': 'hevc', 'bitrate': '50000000', 'fps': '59.938427', 'gop': '60', 'bf': '0', 'preset': 'P4', 'tuning_info': 'ultra_low_latency', 'rc': 'cbr'} source_bitrate=default configured_bitrate=50M
2026-05-18 09:31:50,567 [INFO] pynv_stream: [PYNV][7] VRAM encoder_created: used=2574.2/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:50,568 [INFO] pynv_stream: [PYNV][7] audio cache hit: 2ddb0ff8fbf3c8b98de3.aac bytes=61334816 total_elapsed=0.000s
2026-05-18 09:31:50,568 [INFO] pynv_stream: [PYNV][7] pipe_ts video mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f hevc -raw_packet_size 65536 -framerate 59.938427 -i - -map 0:v:0 -an -c:v copy -bsf:v hevc_metadata=aud=insert,setts=time_base=1/90000:pts=N*1502:dts=N*1502 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts -
2026-05-18 09:31:50,568 [INFO] pynv_stream: [PYNV][7] pipe_ts final mux cmd: .\ffmpeg.EXE -hide_banner -loglevel warning -fflags +genpts -thread_queue_size 1024 -f mpegts -i - -thread_queue_size 1024 -f aac -t 1897.197 -i E:\VR_Video_Passthrough_Server\runtime_cache\audio\2ddb0ff8fbf3c8b98de3.aac -map 0:v:0 -map 1:a:0? -c:v copy -c:a aac -ar 48000 -ac 2 -b:a 192k -t 1897.197 -color_range tv -color_primaries bt709 -color_trc bt709 -colorspace bt709 -max_interleave_delta 500000000 -flush_packets 1 -muxdelay 0 -muxpreload 0 -mpegts_flags +resend_headers+pat_pmt_at_frames -pat_period 0.1 -sdt_period 0.5 -pcr_period 20 -f mpegts - audio=aac duration=1897.197
2026-05-18 09:31:50,848 [INFO] pynv_stream: [PYNV][7] RVM recurrent state reset at 0.000s; first frames after seek may show alpha warmup jitter
2026-05-18 09:31:50,848 [INFO] pynv_stream: [PYNV][7] alpha passthrough active: projection=sbs_half_equirect process=4096x2048 output=4096x2048 scale=0.400 radius=1.000 layout=alpha-packer-6block flat2d_fov=80.0 flat2d_disparity=10.0px
2026-05-18 09:31:50,908 [INFO] pynv_stream: [PYNV][7] seek start: requested_sec=0.000 out_idx=0 src_idx=0 fps=59.938 source_fps=59.938
2026-05-18 09:31:50,974 [INFO] pynv_stream: [PYNV][7] first real video bitstream written; slate audio may switch
2026-05-18 09:31:50,974 [INFO] pynv_stream: [PYNV][7] VRAM first_real_video_bitstream: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:51,498 [INFO] pynv_stream: [PYNV][7] frame 30/113714 fps=46.14 interval_fps=46.14 src_idx=29 bytes=0 out_bps=0.1M stage_avg_ms decode=1.89 composite=19.44 sync=0.01 encode=0.30 mux=0.02 mat_avg_ms pre=0.06 ort=19.14 kernel=0.00 stage_max_ms decode=40.21 composite=20.34 sync=0.01 encode=3.30 mux=0.22 mat_max_ms pre=0.11 ort=19.87 kernel=0.00
2026-05-18 09:31:52,098 [INFO] pynv_stream: [PYNV][7] frame 60/113714 fps=48.01 interval_fps=50.05 src_idx=59 bytes=0 out_bps=0.1M stage_avg_ms decode=0.57 composite=19.18 sync=0.01 encode=0.19 mux=0.01 mat_avg_ms pre=0.06 ort=18.89 kernel=0.00 stage_max_ms decode=0.62 composite=20.38 sync=0.01 encode=0.24 mux=0.04 mat_max_ms pre=0.19 ort=19.99 kernel=0.00
2026-05-18 09:31:52,665 [INFO] matting: [DIAG] alpha #700 (gpu-pre): frame=4096x2048 input_shape=(2,3,1024,1024) preprocess=0.1ms ort_run=18.9ms square=False split_sbs=True split_active=True providers=['CUDAExecutionProvider', 'CPUExecutionProvider']
2026-05-18 09:31:52,704 [INFO] pynv_stream: [PYNV][7] frame 90/113714 fps=48.48 interval_fps=49.45 src_idx=89 bytes=0 out_bps=41.9M stage_avg_ms decode=0.41 composite=19.37 sync=0.01 encode=0.21 mux=0.21 mat_avg_ms pre=0.06 ort=19.07 kernel=0.00 stage_max_ms decode=0.57 composite=20.16 sync=0.01 encode=0.24 mux=0.45 mat_max_ms pre=0.10 ort=19.87 kernel=0.00
2026-05-18 09:31:53,131 [WARNING] pynv_stream: [PYNV][7][ffmpeg] [aac @ 000001ac0dfd2200] Estimating duration from bitrate, this may be inaccurate
2026-05-18 09:31:53,135 [INFO] pynv_stream: [PYNV][7] reader first stdout chunk len=2820
2026-05-18 09:31:53,136 [INFO] pynv_stream: [PYNV][7] iter first chunk len=2820 bytes=2820
2026-05-18 09:31:53,141 [INFO] media: passthrough_live[13] preroll ready: profile=vlc nplayer=False chunks=49 bytes=1051144 target=1048576 elapsed=2.797s
2026-05-18 09:31:53,141 [INFO] media: passthrough_live[13] first chunk: len=2820 sent=2820 stream_bytes=1051144
2026-05-18 09:31:53,309 [INFO] pynv_stream: [PYNV][7] frame 120/113714 fps=48.76 interval_fps=49.66 src_idx=119 bytes=6071084 out_bps=42.8M stage_avg_ms decode=0.40 composite=19.23 sync=0.01 encode=0.23 mux=0.23 mat_avg_ms pre=0.08 ort=18.88 kernel=0.00 stage_max_ms decode=0.51 composite=19.51 sync=0.05 encode=0.32 mux=0.40 mat_max_ms pre=0.19 ort=19.05 kernel=0.00
2026-05-18 09:31:53,470 [INFO] media: passthrough_live[13] finally begin: sent=6937388 stream_bytes=6937388 frames=127
2026-05-18 09:31:53,471 [INFO] pynv_stream: [PYNV][7] iter finally bytes=6937388 frames=127
2026-05-18 09:31:53,471 [INFO] pynv_stream: [PYNV][7] close begin bytes=6937388 frames=127
2026-05-18 09:31:53,471 [INFO] media: passthrough active slot released: active=0 owner=('live', '192.168.31.106', 'vlc')
2026-05-18 09:31:53,471 [INFO] pynv_stream: [PYNV][7] VRAM close_begin: used=2766.4/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:53,481 [INFO] pynv_stream: [PYNV][7] worker closing mux stdin frames=127 stop=True
2026-05-18 09:31:53,496 [INFO] pynv_stream: [PYNV][7] VRAM worker_done: used=2633.7/8187.5MiB cupy_pool=56.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:53,496 [INFO] pynv_stream: [PYNV][7] worker done frames=127 bytes_emitted=6937388 reader_started=True
2026-05-18 09:31:53,498 [INFO] media: passthrough_live[14] request headers: ua='Dalvik/2.1.0 (Linux; U; Android 10; A821C Build/5.13.7)' accept='*/*' range='bytes=1072693248-1073741823' time_seek=None transfer=None getfeatures=None x_av=None x_dlna=None host='192.168.31.73:8200' client=Address(host='192.168.31.106', port=52864)
2026-05-18 09:31:53,498 [INFO] media: passthrough_live[14] start: ebvr-061-2.mp4 @ 0.00s from Address(host='192.168.31.106', port=52864) requested_t=0.00s mode=alpha requested_mode='alpha' time_seek=None
2026-05-18 09:31:53,500 [INFO] pynv_stream: [PYNV][7] reader done chunks=266 bytes=6970156 stop=True
2026-05-18 09:31:53,516 [INFO] pynv_stream: [PYNV][7] decoder stop thread begin
2026-05-18 09:31:53,516 [INFO] pynv_stream: [PYNV][7] decoder stop thread done
2026-05-18 09:31:53,516 [INFO] pynv_stream: [PYNV][7] VRAM decoder_stop_joined: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:53,532 [INFO] pynv_stream: [PYNV][7] VRAM close_done: used=2633.7/8187.5MiB cupy_pool=48.0/242.0MiB pinned_free_blocks=0
2026-05-18 09:31:53,532 [INFO] pynv_stream: [PYNV][7] close done
2026-05-18 09:31:53,673 [INFO] media: passthrough_live[14] return 416 for vlc non-start live range before stream: range='bytes=1072693248-1073741823' total=12807065607
2026-05-18 09:31:53,673 [INFO] pynv_stream: [PYNV][7] sentinel posted
