Skip to content

[Bug]: Vod connections lingering #451

@ffalor

Description

@ffalor

Describe the bug

Vod connections lingering causing connection limit exceeded.

How can we recreate this bug?

Using the vod tab:

  1. /player_api shows 0 active_cons
  2. Open a vod
  3. /player_api shows 1 active_con
  4. Close the tab (sometimes just closing the vod preview works)
  5. Stats page shows an active connection
  6. /player_api still shows 1 active_con
  7. Try to open another vod get Profile Account 2 connection limit exceeded error
Logs from 1-6

2025-09-22 00:53:59,124 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:53:59,124 ERROR apps.vod.api_views Page 7, page_size 24
2025-09-22 00:53:59,124 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 144
2025-09-22 00:53:59,192 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:54:02,693 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/392ddc98-f7c4-446d-b359-d4263afa28b5, session: None, profile: None
2025-09-22 00:54:02,693 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5?stream_id=1078203
2025-09-22 00:54:02,693 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=0-'}
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: None
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=0-
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 0 (~0.0 MB)
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Creating new session: vod_1758502442694_9088
2025-09-22 00:54:02,694 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Redirecting to path-based URL: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/392ddc98-f7c4-446d-b359-d4263afa28b5, session: vod_1758502442694_9088, profile: None
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=0-'}
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: vod_1758502442694_9088
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=0-
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 0 (~0.0 MB)
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [VOD-PARAM] Preferred stream ID: 1078203
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Looking up movie with UUID 392ddc98-f7c4-446d-b359-d4263afa28b5
2025-09-22 00:54:02,696 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Preferred stream ID: 1078203
2025-09-22 00:54:02,707 INFO apps.proxy.vod_proxy.views [CONTENT-FOUND] Movie: EN - Movie 1 (ID: 16389)
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [STREAM-SELECTED] Using specific stream: 1078203 from provider: Trex
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Found content: EN - Movie 1
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-ACCOUNT] Using M3U account: Trex
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-URL] Relation type: M3UMovieRelation
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-URL] Account type: XC
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-URL] Stream ID: 1078203
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-URL] Built URL from get_stream_url(): provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:02,716 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Content URL: provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:02,718 INFO apps.proxy.vod_proxy.views [VOD-PROFILE] Using M3U profile: 10 (max_streams: 1, current: 0)
2025-09-22 00:54:02,718 INFO apps.proxy.vod_proxy.views [VOD-URL] Final stream URL: provider_url/movie/profile_account_2_user/profile_account_2_pass/1078203.mkv
2025-09-22 00:54:02,718 INFO apps.proxy.vod_proxy.views [VOD-STREAM] Calling connection manager to stream content
2025-09-22 00:54:02,718 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Redis-backed streaming request for movie EN - Movie 1
2025-09-22 00:54:02,719 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - No matching idle session found, using new session
2025-09-22 00:54:02,719 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Creating new Redis-backed connection
2025-09-22 00:54:02,719 INFO vod_proxy [PROFILE-CHECK] Profile 10 has 0/1 connections
2025-09-22 00:54:02,723 INFO vod_proxy [vod_1758502442694_9088] Using M3U account user-agent: TiviMate/5.1.6 (Android 12)
2025-09-22 00:54:02,723 INFO vod_proxy [vod_1758502442694_9088] Created new connection state in Redis with consolidated session metadata
2025-09-22 00:54:02,724 INFO vod_proxy [PROFILE-INCR] Profile 10 connections: 1
2025-09-22 00:54:02,724 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Created consolidated connection with session metadata
2025-09-22 00:54:02,724 INFO vod_proxy [vod_1758502442694_9088] Setting Range header: bytes=0-
2025-09-22 00:54:02,724 INFO vod_proxy [vod_1758502442694_9088] Making request #1 to original URL
2025-09-22 00:54:03,891 INFO vod_proxy [vod_1758502442694_9088] Updated connection state: length=1171981567, type=video/x-matroska
2025-09-22 00:54:03,892 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Set Content-Range: bytes 0-1171981566/1171981567, Content-Length: 1171981567
2025-09-22 00:54:03,892 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Redis-backed response ready (status: 206)
2025-09-22 00:54:03,892 INFO apps.proxy.vod_proxy.views [VOD-SUCCESS] Stream response created successfully, type: <class 'django.http.response.StreamingHttpResponse'>
2025-09-22 00:54:03,892 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Starting Redis-backed stream
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/392ddc98-f7c4-446d-b359-d4263afa28b5, session: vod_1758502442694_9088, profile: None
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=1171980288-'}
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: vod_1758502442694_9088
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=1171980288-
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 1171980288 (~1117.7 MB)
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-SEEK] *** ACTUAL SEEK DETECTED *** Position: 1117.7 MB
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [VOD-PARAM] Preferred stream ID: 1078203
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Looking up movie with UUID 392ddc98-f7c4-446d-b359-d4263afa28b5
2025-09-22 00:54:04,078 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Preferred stream ID: 1078203
2025-09-22 00:54:04,087 INFO apps.proxy.vod_proxy.views [CONTENT-FOUND] Movie: EN - Movie 1 (ID: 16389)
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [STREAM-SELECTED] Using specific stream: 1078203 from provider: Trex
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Found content: EN - Movie 1
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-ACCOUNT] Using M3U account: Trex
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-URL] Relation type: M3UMovieRelation
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-URL] Account type: XC
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-URL] Stream ID: 1078203
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-URL] Built URL from get_stream_url(): provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:04,093 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Content URL: provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:04,095 INFO apps.proxy.vod_proxy.views [VOD-PROFILE] Using M3U profile: 10 (max_streams: 1, current: 0)
2025-09-22 00:54:04,095 INFO apps.proxy.vod_proxy.views [VOD-URL] Final stream URL: provider_url/movie/profile_account_2_user/profile_account_2_pass/1078203.mkv
2025-09-22 00:54:04,095 INFO apps.proxy.vod_proxy.views [VOD-STREAM] Calling connection manager to stream content
2025-09-22 00:54:04,095 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed streaming request for movie EN - Movie 1
2025-09-22 00:54:04,096 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - No matching idle session found, using new session
2025-09-22 00:54:04,096 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Using existing Redis-backed connection
2025-09-22 00:54:04,097 INFO vod_proxy [vod_1758502442694_9088] Setting Range header: bytes=1171980288-1171981566
2025-09-22 00:54:04,097 INFO vod_proxy [vod_1758502442694_9088] Making request #2 to final URL
2025-09-22 00:54:04,000 - uwsgi_response_write_body_do(): Broken pipe [core/writer.c line 429] during GET /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203 (client_id)
OSError: write error
2025-09-22 00:54:04,147 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-135 - Client disconnected from Redis-backed stream
2025-09-22 00:54:04,794 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Set Content-Range: bytes 1171980288-1171981566/1171981567, Content-Length: 1279
2025-09-22 00:54:04,795 INFO vod_proxy [vod_1758502442694_9088] *** SEEK INFO STORED *** 100.0% at byte 1,171,980,288/1,171,981,567 (timestamp: 1758502444.7948368)
2025-09-22 00:54:04,795 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed response ready (status: 206)
2025-09-22 00:54:04,795 INFO apps.proxy.vod_proxy.views [VOD-SUCCESS] Stream response created successfully, type: <class 'django.http.response.StreamingHttpResponse'>
2025-09-22 00:54:04,795 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Starting Redis-backed stream
2025-09-22 00:54:04,796 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed stream completed: 1279 bytes sent
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/392ddc98-f7c4-446d-b359-d4263afa28b5, session: vod_1758502442694_9088, profile: None
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=32768-'}
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: vod_1758502442694_9088
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=32768-
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 32768 (~0.0 MB)
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-SEEK] *** ACTUAL SEEK DETECTED *** Position: 0.0 MB
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Rapid request detected (0.7s) - likely seeking
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [VOD-PARAM] Preferred stream ID: 1078203
2025-09-22 00:54:04,798 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Looking up movie with UUID 392ddc98-f7c4-446d-b359-d4263afa28b5
2025-09-22 00:54:04,799 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Preferred stream ID: 1078203
2025-09-22 00:54:04,810 INFO apps.proxy.vod_proxy.views [CONTENT-FOUND] Movie: EN - Movie 1 (ID: 16389)
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [STREAM-SELECTED] Using specific stream: 1078203 from provider: Trex
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Found content: EN - Movie 1
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-ACCOUNT] Using M3U account: Trex
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-URL] Relation type: M3UMovieRelation
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-URL] Account type: XC
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-URL] Stream ID: 1078203
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-URL] Built URL from get_stream_url(): provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:04,819 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Content URL: provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:04,821 INFO apps.proxy.vod_proxy.views [VOD-PROFILE] Using M3U profile: 10 (max_streams: 1, current: 0)
2025-09-22 00:54:04,821 INFO apps.proxy.vod_proxy.views [VOD-URL] Final stream URL: provider_url/movie/profile_account_2_user/profile_account_2_pass/1078203.mkv
2025-09-22 00:54:04,821 INFO apps.proxy.vod_proxy.views [VOD-STREAM] Calling connection manager to stream content
2025-09-22 00:54:04,821 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed streaming request for movie EN - Movie 1
2025-09-22 00:54:04,822 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - No matching idle session found, using new session
2025-09-22 00:54:04,822 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Using existing Redis-backed connection
2025-09-22 00:54:04,823 INFO vod_proxy [vod_1758502442694_9088] Setting Range header: bytes=32768-1171981566
2025-09-22 00:54:04,823 INFO vod_proxy [vod_1758502442694_9088] Making request #3 to final URL
2025-09-22 00:54:05,212 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Set Content-Range: bytes 32768-1171981566/1171981567, Content-Length: 1171948799
2025-09-22 00:54:05,212 INFO vod_proxy [vod_1758502442694_9088] *** SEEK INFO STORED *** 0.0% at byte 32,768/1,171,981,567 (timestamp: 1758502445.212251)
2025-09-22 00:54:05,213 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed response ready (status: 206)
2025-09-22 00:54:05,213 INFO apps.proxy.vod_proxy.views [VOD-SUCCESS] Stream response created successfully, type: <class 'django.http.response.StreamingHttpResponse'>
2025-09-22 00:54:05,213 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Starting Redis-backed stream
2025-09-22 00:54:05,395 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/392ddc98-f7c4-446d-b359-d4263afa28b5, session: vod_1758502442694_9088, profile: None
2025-09-22 00:54:05,395 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203
2025-09-22 00:54:05,395 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:54:05,395 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=1171914752-'}
2025-09-22 00:54:05,395 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: vod_1758502442694_9088
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=1171914752-
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 1171914752 (~1117.6 MB)
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-SEEK] *** ACTUAL SEEK DETECTED *** Position: 1117.6 MB
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Rapid request detected (2.7s) - likely seeking
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [VOD-PARAM] Preferred stream ID: 1078203
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Looking up movie with UUID 392ddc98-f7c4-446d-b359-d4263afa28b5
2025-09-22 00:54:05,396 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Preferred stream ID: 1078203
2025-09-22 00:54:05,406 INFO apps.proxy.vod_proxy.views [CONTENT-FOUND] Movie: EN - Movie 1 (ID: 16389)
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [STREAM-SELECTED] Using specific stream: 1078203 from provider: Trex
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Found content: EN - Movie 1
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-ACCOUNT] Using M3U account: Trex
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-URL] Relation type: M3UMovieRelation
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-URL] Account type: XC
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-URL] Stream ID: 1078203
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-URL] Built URL from get_stream_url(): provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:05,414 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Content URL: provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:05,416 INFO apps.proxy.vod_proxy.views [VOD-PROFILE] Using M3U profile: 10 (max_streams: 1, current: 0)
2025-09-22 00:54:05,416 INFO apps.proxy.vod_proxy.views [VOD-URL] Final stream URL: provider_url/movie/profile_account_2_user/profile_account_2_pass/1078203.mkv
2025-09-22 00:54:05,416 INFO apps.proxy.vod_proxy.views [VOD-STREAM] Calling connection manager to stream content
2025-09-22 00:54:05,416 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - Redis-backed streaming request for movie EN - Movie 1
2025-09-22 00:54:05,417 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - No matching idle session found, using new session
2025-09-22 00:54:05,417 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - Using existing Redis-backed connection
2025-09-22 00:54:05,418 INFO vod_proxy [vod_1758502442694_9088] Setting Range header: bytes=1171914752-1171981566
2025-09-22 00:54:05,418 INFO vod_proxy [vod_1758502442694_9088] Making request #4 to final URL
2025-09-22 00:54:05,000 - uwsgi_response_write_body_do(): Broken pipe [core/writer.c line 429] during GET /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203 (client_id)
OSError: write error
2025-09-22 00:54:05,454 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Client disconnected from Redis-backed stream
2025-09-22 00:54:06,013 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - Set Content-Range: bytes 1171914752-1171981566/1171981567, Content-Length: 66815
2025-09-22 00:54:06,014 INFO vod_proxy [vod_1758502442694_9088] *** SEEK INFO STORED *** 100.0% at byte 1,171,914,752/1,171,981,567 (timestamp: 1758502446.0139112)
2025-09-22 00:54:06,014 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - Redis-backed response ready (status: 206)
2025-09-22 00:54:06,014 INFO apps.proxy.vod_proxy.views [VOD-SUCCESS] Stream response created successfully, type: <class 'django.http.response.StreamingHttpResponse'>
2025-09-22 00:54:06,014 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - Starting Redis-backed stream
2025-09-22 00:54:06,284 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-134 - Redis-backed stream completed: 66815 bytes sent
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/392ddc98-f7c4-446d-b359-d4263afa28b5, session: vod_1758502442694_9088, profile: None
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=65536-'}
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: vod_1758502442694_9088
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=65536-
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 65536 (~0.1 MB)
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-SEEK] *** ACTUAL SEEK DETECTED *** Position: 0.1 MB
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Rapid request detected (1.5s) - likely seeking
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [VOD-PARAM] Preferred stream ID: 1078203
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Looking up movie with UUID 392ddc98-f7c4-446d-b359-d4263afa28b5
2025-09-22 00:54:06,313 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Preferred stream ID: 1078203
2025-09-22 00:54:06,324 INFO apps.proxy.vod_proxy.views [CONTENT-FOUND] Movie: EN - Movie 1 (ID: 16389)
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [STREAM-SELECTED] Using specific stream: 1078203 from provider: Trex
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Found content: EN - Movie 1
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-ACCOUNT] Using M3U account: Trex
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-URL] Relation type: M3UMovieRelation
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-URL] Account type: XC
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-URL] Stream ID: 1078203
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-URL] Built URL from get_stream_url(): provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:06,332 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Content URL: provider_url/movie/profile_account_1_user/profile_account_1_pass/1078203.mkv
2025-09-22 00:54:06,334 INFO apps.proxy.vod_proxy.views [VOD-PROFILE] Using M3U profile: 10 (max_streams: 1, current: 0)
2025-09-22 00:54:06,334 INFO apps.proxy.vod_proxy.views [VOD-URL] Final stream URL: provider_url/movie/profile_account_2_user/profile_account_2_pass/1078203.mkv
2025-09-22 00:54:06,334 INFO apps.proxy.vod_proxy.views [VOD-STREAM] Calling connection manager to stream content
2025-09-22 00:54:06,334 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed streaming request for movie EN - Movie 1
2025-09-22 00:54:06,335 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - No matching idle session found, using new session
2025-09-22 00:54:06,335 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Using existing Redis-backed connection
2025-09-22 00:54:06,336 INFO vod_proxy [vod_1758502442694_9088] Setting Range header: bytes=65536-1171981566
2025-09-22 00:54:06,336 INFO vod_proxy [vod_1758502442694_9088] Making request #5 to final URL
2025-09-22 00:54:07,032 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Set Content-Range: bytes 65536-1171981566/1171981567, Content-Length: 1171916031
2025-09-22 00:54:07,033 INFO vod_proxy [vod_1758502442694_9088] *** SEEK INFO STORED *** 0.0% at byte 65,536/1,171,981,567 (timestamp: 1758502447.0322504)
2025-09-22 00:54:07,033 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Redis-backed response ready (status: 206)
2025-09-22 00:54:07,033 INFO apps.proxy.vod_proxy.views [VOD-SUCCESS] Stream response created successfully, type: <class 'django.http.response.StreamingHttpResponse'>
2025-09-22 00:54:07,033 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Starting Redis-backed stream
2025-09-22 00:54:28,000 - uwsgi_response_write_body_do(): Broken pipe [core/writer.c line 429] during GET /proxy/vod/movie/392ddc98-f7c4-446d-b359-d4263afa28b5/vod_1758502442694_9088?stream_id=1078203 (client_id)
OSError: write error
2025-09-22 00:54:28,157 INFO vod_proxy [vod_1758502442694_9088] Worker 35b59e35bd09-133 - Client disconnected from Redis-backed stream
2025-09-22 00:55:59.016 UTC [76] LOG:  checkpoint starting: time
2025-09-22 00:56:00.341 UTC [76] LOG:  checkpoint complete: wrote 17 buffers (0.1%); 0 WAL file(s) added, 0 removed, 0 recycled; write=1.313 s, sync=0.007 s, total=1.325 s; sync files=16, longest=0.001 s, average=0.001 s; distance=49 kB, estimate=49 kB; lsn=0/B84BC418, redo lsn=0/B84BC3C0
2025-09-22 00:56:28,000 - uwsgi_response_writev_headers_and_body_do(): Broken pipe [core/writer.c line 306] during GET /api/channels/logos/?no_pagination=true (client_id)
OSError: write error
2025-09-22 00:56:34,033 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:34,033 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:34,033 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:34,102 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:39,583 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:39,583 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:39,584 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:39,648 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:39,648 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:39,648 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:39,658 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:39,699 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:39,805 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:39,806 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:39,806 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:39,858 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:39,870 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:39,870 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:39,870 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:39,916 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:39,957 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:39,958 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:39,958 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:40,002 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:40,052 ERROR apps.vod.api_views === UnifiedContentViewSet.list() called ===
2025-09-22 00:56:40,052 ERROR apps.vod.api_views Page 1, page_size 24
2025-09-22 00:56:40,052 ERROR apps.vod.api_views Executing SQL with LIMIT 24 OFFSET 0
2025-09-22 00:56:40,095 ERROR apps.vod.api_views Retrieved 24 results via SQL
2025-09-22 00:56:42,003 INFO core.xtream_codes XC Authentication successful for user profile_account_1_user
2025-09-22 00:56:42,174 INFO core.xtream_codes Successfully retrieved VOD info for vod_id 1389010
2025-09-22 00:56:43,406 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/5ef30c12-96ca-431a-abf6-9f154769ab2d, session: None, profile: None
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/5ef30c12-96ca-431a-abf6-9f154769ab2d?stream_id=1389010
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=0-'}
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: None
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=0-
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 0 (~0.0 MB)
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Creating new session: vod_1758502603407_9856
2025-09-22 00:56:43,407 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Redirecting to path-based URL: /proxy/vod/movie/5ef30c12-96ca-431a-abf6-9f154769ab2d/vod_1758502603407_9856?stream_id=1389010
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Starting VOD stream request: movie/5ef30c12-96ca-431a-abf6-9f154769ab2d, session: vod_1758502603407_9856, profile: None
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Full request path: /proxy/vod/movie/5ef30c12-96ca-431a-abf6-9f154769ab2d/vod_1758502603407_9856?stream_id=1389010
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request method: GET
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-REQUEST] Request headers: {'Content-Type': '', 'Content-Length': '', 'Host': '127.0.0.1:9191', 'Connection': 'keep-alive', 'User-Agent': 'Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36', 'Accept-Encoding': 'identity;q=1, *;q=0', 'Accept': '*/*', 'Referer': 'http://127.0.0.1:9191/vods', 'Accept-Language': 'en-US,en;q=0.9', 'Cookie': 'Range': 'bytes=0-'}
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-TIMESHIFT] Timeshift params - utc_start: None, utc_end: None, offset: None
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-SESSION] Session ID: vod_1758502603407_9856
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-RANGE] Range header: bytes=0-
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-SEEK] Seeking to byte position: 0 (~0.0 MB)
2025-09-22 00:56:43,409 INFO apps.proxy.vod_proxy.views [VOD-CLIENT] Client info - IP: client_id, User-Agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWeb...
2025-09-22 00:56:43,410 INFO apps.proxy.vod_proxy.views [VOD-PARAM] Preferred stream ID: 1389010
2025-09-22 00:56:43,410 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Looking up movie with UUID 5ef30c12-96ca-431a-abf6-9f154769ab2d
2025-09-22 00:56:43,410 INFO apps.proxy.vod_proxy.views [CONTENT-LOOKUP] Preferred stream ID: 1389010
2025-09-22 00:56:43,418 INFO apps.proxy.vod_proxy.views [CONTENT-FOUND] Movie: EN - Movie 2 (ID: 9723)
2025-09-22 00:56:43,423 INFO apps.proxy.vod_proxy.views [STREAM-SELECTED] Using specific stream: 1389010 from provider: Trex
2025-09-22 00:56:43,423 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Found content: EN - Movie 2
2025-09-22 00:56:43,423 INFO apps.proxy.vod_proxy.views [VOD-ACCOUNT] Using M3U account: Trex
2025-09-22 00:56:43,423 INFO apps.proxy.vod_proxy.views [VOD-URL] Relation type: M3UMovieRelation
2025-09-22 00:56:43,423 INFO apps.proxy.vod_proxy.views [VOD-URL] Account type: XC
2025-09-22 00:56:43,423 INFO apps.proxy.vod_proxy.views [VOD-URL] Stream ID: 1389010
2025-09-22 00:56:43,424 INFO apps.proxy.vod_proxy.views [VOD-URL] Built URL from get_stream_url(): provider_url/movie/profile_account_1_user/profile_account_1_pass/1389010.mp4
2025-09-22 00:56:43,424 INFO apps.proxy.vod_proxy.views [VOD-CONTENT] Content URL: provider_url/movie/profile_account_1_user/profile_account_1_pass/1389010.mp4
2025-09-22 00:56:43,425 INFO apps.proxy.vod_proxy.views [VOD-PROFILE] Using M3U profile: 10 (max_streams: 1, current: 0)
2025-09-22 00:56:43,425 INFO apps.proxy.vod_proxy.views [VOD-URL] Final stream URL: provider_url/movie/profile_account_2_user/profile_account_2_pass/1389010.mp4
2025-09-22 00:56:43,425 INFO apps.proxy.vod_proxy.views [VOD-STREAM] Calling connection manager to stream content
2025-09-22 00:56:43,425 INFO vod_proxy [vod_1758502603407_9856] Worker 35b59e35bd09-133 - Redis-backed streaming request for movie EN - Movie 2
2025-09-22 00:56:43,426 INFO vod_proxy [vod_1758502603407_9856] Worker 35b59e35bd09-133 - No matching idle session found, using new session
2025-09-22 00:56:43,426 INFO vod_proxy [vod_1758502603407_9856] Worker 35b59e35bd09-133 - Creating new Redis-backed connection
2025-09-22 00:56:43,426 INFO vod_proxy [PROFILE-CHECK] Profile 10 has 1/1 connections
2025-09-22 00:56:43,426 WARNING vod_proxy [vod_1758502603407_9856] Profile Account 2 connection limit exceeded
2025-09-22 00:56:43,426 INFO apps.proxy.vod_proxy.views [VOD-SUCCESS] Stream response created successfully, type: <class 'django.http.response.HttpResponse'>
2025-09-22 00:56:43,426 WARNING django.request Too Many Requests: /proxy/vod/movie/5ef30c12-96ca-431a-abf6-9f154769ab2d/vod_1758502603407_9856
2025-09-22 00:56:43,000 INFO uwsgi.requests Worker ID: 2 GET 429 /proxy/vod/movie/5ef30c12-96ca-431a-abf6-9f154769ab2d/vod_1758502603407_9856?stream_id=1389010 

  1. Restart dispatcharr
  2. Since we did the restart the vod no longer shows as a active connection in stats
  3. /player_api still shows 1 active_con
  4. Open a live tv session from guide
  5. /player_api still shows 1 active_con
  6. Close the live tv session
  7. /player_api shows 0 active_con
Logs from 7-12

2025-09-22 00:58:31,988 INFO ts_proxy.url_utils Fetching channel ID be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:31,991 INFO ts_proxy.views [client_1758502711991_9156] Requested stream for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:31,991 INFO ts_proxy.views [client_1758502711991_9156] Starting channel be93be21-2554-4924-b832-069dad46424e initialization
2025-09-22 00:58:31,991 INFO ts_proxy.url_utils Fetching channel ID be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,009 INFO ts_proxy.url_utils Generated stream url: provider_url/live/profile_account_1_user/profile_account_1_pass/173081.ts
2025-09-22 00:58:32,011 INFO ts_proxy.views [client_1758502711991_9156] Successfully obtained stream for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,012 INFO ts_proxy.views Channel be93be21-2554-4924-b832-069dad46424e using stream ID 37793, m3u account profile ID 7
2025-09-22 00:58:32,013 INFO ts_proxy Created initial metadata with stream_id 37793 for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,014 INFO ts_proxy.server Set early initializing state for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,015 INFO ts_proxy.server Worker 35b59e35bd09:133 acquired ownership of channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,015 INFO ts_proxy.server Worker 35b59e35bd09:133 is now the owner of channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,015 INFO ts_proxy.server Storing stream_id 37793 in metadata for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,015 INFO ts_proxy.server Verified stream_id 37793 is set in Redis for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,016 INFO ts_proxy.stream_manager Initialized stream manager for channel be93be21-2554-4924-b832-069dad46424e with stream ID 37793
2025-09-22 00:58:32,016 INFO ts_proxy.stream_manager Initialized stream manager for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,017 INFO ts_proxy.server Created StreamManager for channel be93be21-2554-4924-b832-069dad46424e with stream ID 37793
2025-09-22 00:58:32,017 INFO ts_proxy.server Started stream manager thread for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,017 INFO ts_proxy.stream_manager Starting stream for URL: provider_url/live/profile_account_1_user/profile_account_1_pass/173081.ts for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,017 INFO ts_proxy.stream_manager Connection attempt 1/3 for URL: provider_url/live/profile_account_1_user/profile_account_1_pass/173081.ts for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,017 INFO ts_proxy.url_utils Fetching channel ID be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,018 INFO ts_proxy.server Channel be93be21-2554-4924-b832-069dad46424e state transition: initializing -> connecting
2025-09-22 00:58:32,018 INFO ts_proxy.server Channel be93be21-2554-4924-b832-069dad46424e in connecting state - will start grace period after connection
2025-09-22 00:58:32,119 INFO ts_proxy.views [client_1758502711991_9156] Successfully initialized channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,128 INFO ts_proxy.client_manager New client connected: client_1758502711991_9156 (local: 1, total: 1)
2025-09-22 00:58:32,128 INFO ts_proxy.views [client_1758502711991_9156] Client registered with channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:32,129 INFO ts_proxy.stream_generator [client_1758502711991_9156] Stream generator started, channel_ready=False
2025-09-22 00:58:32,129 INFO ts_proxy.stream_generator [client_1758502711991_9156] Channel be93be21-2554-4924-b832-069dad46424e ready, starting normal streaming
2025-09-22 00:58:32,129 INFO ts_proxy.stream_generator [client_1758502711991_9156] Starting stream at index 0 (buffer at 0)
2025-09-22 00:58:32,359 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: libva info: Trying to open /usr/local/lib/x86_64-linux-gnu/dri/iHD_drv_video.so
2025-09-22 00:58:32,388 WARNING ts_proxy.stream_manager FFmpeg stderr for channel be93be21-2554-4924-b832-069dad46424e: -vsync is deprecated. Use -fps_mode
2025-09-22 00:58:34,222 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Input #0, mpegts, from 'provider_url/live/profile_account_1_user/profile_account_1_pass/173081.ts':
2025-09-22 00:58:34,243 INFO ts_proxy Video stream info - Codec: h264, Resolution: 1280x720, Source FPS: 29.97, Pixel Format: yuv420p, Video Bitrate: None kb/s
2025-09-22 00:58:34,243 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream #0:0[0x100]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(progressive), 1280x720 [SAR 1:1 DAR 16:9], 29.97 fps, 29.97 tbr, 90k tbn, start 46910.530333
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream #0:1[0x101]: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp, 64 kb/s, start 46910.472600
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream mapping:
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream #0:0 -> #0:0 (h264 (h264_qsv) -> h264 (h264_qsv))
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream #0:1 -> #0:1 (aac (native) -> aac (native))
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Output #0, mpegts, to 'pipe:1':
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream #0:0: Video: h264, qsv(tv, progressive), 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 4000 kb/s, 29.97 fps, 90k tbn
2025-09-22 00:58:34,247 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 128 kb/s
2025-09-22 00:58:34,527 INFO ts_proxy.stream_manager Buffer threshold reached for channel be93be21-2554-4924-b832-069dad46424e: 10/4 chunks
2025-09-22 00:58:34,536 INFO ts_proxy.stream_manager STREAM MANAGER: Updated channel be93be21-2554-4924-b832-069dad46424e state: connecting -> waiting_for_clients with 10 buffer chunks
2025-09-22 00:58:34,536 INFO ts_proxy.stream_manager Started initial connection grace period (10s) for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:58:35,017 INFO ts_proxy.client_manager No clients for channel be93be21-2554-4924-b832-069dad46424e after 3 consecutive checks and not in shutdown delay, exiting heartbeat thread
2025-09-22 00:58:44,910 INFO ts_proxy.server Grace period expired with 1 clients - marking channel be93be21-2554-4924-b832-069dad46424e as active
2025-09-22 00:58:44,910 INFO ts_proxy.server Channel be93be21-2554-4924-b832-069dad46424e state transition: waiting_for_clients -> active
2025-09-22 00:58:44,910 INFO ts_proxy.server Channel be93be21-2554-4924-b832-069dad46424e activated with 1 clients after grace period
2025-09-22 00:59:00,942 INFO ts_proxy.server Channel be93be21-2554-4924-b832-069dad46424e has 1 clients, state: active
2025-09-22 00:59:19,121 INFO ts_proxy.url_utils Fetching channel ID 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,123 INFO ts_proxy.views [client_1758502759123_7038] Requested stream for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,123 INFO ts_proxy.views [client_1758502759123_7038] Starting channel 804a4e11-cbd4-4f33-958d-75bab8e670ec initialization
2025-09-22 00:59:19,124 INFO ts_proxy.url_utils Fetching channel ID 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,140 INFO ts_proxy.url_utils Generated stream url: provider_url/live/profile_account_2_user/profile_account_2_pass/534381.ts
2025-09-22 00:59:19,141 INFO ts_proxy.views [client_1758502759123_7038] Successfully obtained stream for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,142 INFO ts_proxy.views Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec using stream ID 34260, m3u account profile ID 10
2025-09-22 00:59:19,143 INFO ts_proxy Created initial metadata with stream_id 34260 for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,144 INFO ts_proxy.server Set early initializing state for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,145 INFO ts_proxy.server Worker 35b59e35bd09:132 acquired ownership of channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,145 INFO ts_proxy.server Worker 35b59e35bd09:132 is now the owner of channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,145 INFO ts_proxy.server Storing stream_id 34260 in metadata for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,145 INFO ts_proxy.server Verified stream_id 34260 is set in Redis for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,146 INFO ts_proxy.stream_manager Initialized stream manager for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec with stream ID 34260
2025-09-22 00:59:19,147 INFO ts_proxy.stream_manager Initialized stream manager for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,147 INFO ts_proxy.server Created StreamManager for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec with stream ID 34260
2025-09-22 00:59:19,147 INFO ts_proxy.server Started stream manager thread for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,147 INFO ts_proxy.stream_manager Starting stream for URL: provider_url/live/profile_account_2_user/profile_account_2_pass/534381.ts for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,147 INFO ts_proxy.stream_manager Connection attempt 1/3 for URL: provider_url/live/profile_account_2_user/profile_account_2_pass/534381.ts for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,147 INFO ts_proxy.url_utils Fetching channel ID 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,148 INFO ts_proxy.server Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec state transition: initializing -> connecting
2025-09-22 00:59:19,148 INFO ts_proxy.server Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec in connecting state - will start grace period after connection
2025-09-22 00:59:19,172 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: libva info: Trying to open /usr/local/lib/x86_64-linux-gnu/dri/iHD_drv_video.so
2025-09-22 00:59:19,179 WARNING ts_proxy.stream_manager FFmpeg stderr for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: -vsync is deprecated. Use -fps_mode
2025-09-22 00:59:19,249 INFO ts_proxy.views [client_1758502759123_7038] Successfully initialized channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,261 INFO ts_proxy.client_manager New client connected: client_1758502759123_7038 (local: 1, total: 1)
2025-09-22 00:59:19,261 INFO ts_proxy.views [client_1758502759123_7038] Client registered with channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:19,261 INFO ts_proxy.stream_generator [client_1758502759123_7038] Stream generator started, channel_ready=False
2025-09-22 00:59:19,26INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream #0:0 -> #0:1 (aac (native) -> aac (native))
2025-09-22 00:59:21,109 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Output #0, mpegts, to 'pipe:1':
2025-09-22 00:59:21,109 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream #0:0: Video: h264, qsv(tv, bt709, progressive), 1280x720 [SAR 1:1 DAR 16:9], q=2-31, 4000 kb/s, 59.94 fps, 90k tbn
2025-09-22 00:59:21,109 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream #0:1: Audio: aac (LC), 44100 Hz, stereo, fltp, 128 kb/s
2025-09-22 00:59:21,658 INFO ts_proxy.stream_manager Buffer threshold reached for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: 11/4 chunks
2025-09-22 00:59:21,666 INFO ts_proxy.stream_manager STREAM MANAGER: Updated channel 804a4e11-cbd4-4f33-958d-75bab8e670ec state: connecting -> waiting_for_clients with 11 buffer chunks
2025-09-22 00:59:21,666 INFO ts_proxy.stream_manager Started initial connection grace period (10s) for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:22,147 INFO ts_proxy.client_manager No clients for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec after 3 consecutive checks and not in shutdown delay, exiting heartbeat thread
2025-09-22 00:59:30,000 INFO ts_proxy.server Channel be93be21-2554-4924-b832-069dad46424e has 1 clients, state: active
2025-09-22 00:59:30,757 INFO ts_proxy.server Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec has 1 clients, state: waiting_for_clients
2025-09-22 00:59:31,761 INFO ts_proxy.server Grace period expired with 1 clients - marking channel 804a4e11-cbd4-4f33-958d-75bab8e670ec as active
2025-09-22 00:59:31,761 INFO ts_proxy.server Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec state transition: waiting_for_clients -> active
2025-09-22 00:59:31,761 INFO ts_proxy.server Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec activated with 1 clients after grace period
2025-09-22 00:59:45,000 - uwsgi_response_write_body_do(): Broken pipe [core/writer.c line 429] during GET /proxy/ts/stream/804a4e11-cbd4-4f33-958d-75bab8e670ec (client_id)
OSError: write error
2025-09-22 00:59:45,935 WARNING ts_proxy.client_manager Last client removed: client_1758502759123_7038 - channel may shut down soon
2025-09-22 00:59:45,942 INFO ts_proxy.client_manager Client disconnected: client_1758502759123_7038 (local: 0, total: 0)
2025-09-22 00:59:45,942 INFO ts_proxy.stream_generator [client_1758502759123_7038] Disconnected after 26.67s (local: 0, total: 0)
2025-09-22 00:59:45,943 INFO ts_proxy.server Stopping channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:45,944 INFO ts_proxy.server This worker (35b59e35bd09:132) is the owner - closing provider connection
2025-09-22 00:59:45,944 INFO ts_proxy.stream_manager Stopping stream manager for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:45,974 INFO ts_proxy.stream_manager Ensuring transcode process is terminated in finally block for channel: 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:45,974 ERROR ts_proxy.stream_manager FFmpeg stderr for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: [aost#0:1/aac @ 0x55d0ca643880] Error submitting a packet to the muxer: Immediate exit requested
2025-09-22 00:59:45,974 ERROR ts_proxy.stream_manager FFmpeg stderr for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: [out#0/mpegts @ 0x55d0ca540d00] Error muxing a packet
2025-09-22 00:59:45,975 ERROR ts_proxy.stream_manager FFmpeg stderr for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: [out#0/mpegts @ 0x55d0ca540d00] Task finished with error code: -1414092869 (Immediate exit requested)
2025-09-22 00:59:46,007 ERROR ts_proxy.stream_manager FFmpeg stderr for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: [out#0/mpegts @ 0x55d0ca540d00] Error writing trailer: Immediate exit requested
2025-09-22 00:59:46,007 ERROR ts_proxy.stream_manager FFmpeg stderr for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: [out#0/mpegts @ 0x55d0ca540d00] Error closing file: Immediate exit requested
2025-09-22 00:59:46,007 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: [out#0/mpegts @ 0x55d0ca540d00] video:22631KiB audio:730KiB subtitle:0KiB other streams:0KiB global headers:0KiB muxing overhead: 4.576553%
2025-09-22 00:59:46,038 INFO ts_proxy.server Waiting for stream thread to terminate
2025-09-22 00:59:46,038 INFO ts_proxy.stream_manager Updated channel 804a4e11-cbd4-4f33-958d-75bab8e670ec state to ERROR in Redis after stream failure
2025-09-22 00:59:46,039 INFO ts_proxy.stream_manager Stream manager stopped for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,039 INFO ts_proxy.server Released ownership of channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,039 INFO ts_proxy.server Set stopping signal for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec clients
2025-09-22 00:59:46,039 INFO ts_proxy.server Released ownership of channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,039 INFO ts_proxy.server Removed stream manager for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,039 INFO ts_proxy.stream_buffer Flushed final chunk of 174464 bytes to Redis
2025-09-22 00:59:46,039 INFO ts_proxy.server Removed stream buffer for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,039 INFO ts_proxy.server Removed client manager for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,045 INFO ts_proxy.server Cleaned up 106 Redis keys for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec
2025-09-22 00:59:46,000 - uwsgi_response_write_body_do(): Broken pipe [core/writer.c line 429] during GET /proxy/ts/stream/be93be21-2554-4924-b832-069dad46424e (client_id)
OSError: write error
2025-09-22 00:59:46,874 WARNING ts_proxy.client_manager Last client removed: client_1758502711991_9156 - channel may shut down soon
2025-09-22 00:59:46,878 INFO ts_proxy.client_manager Client disconnected: client_1758502711991_9156 (local: 0, total: 0)
2025-09-22 00:59:46,878 INFO ts_proxy.stream_generator [client_1758502711991_9156] Disconnected after 74.73s (local: 0, total: 0)
2025-09-22 00:59:46,882 INFO ts_proxy.server Stopping channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:46,883 INFO ts_proxy.server This worker (35b59e35bd09:133) is the owner - closing provider connection
2025-09-22 00:59:46,883 INFO ts_proxy.stream_manager Stopping stream manager for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:46,899 ERROR ts_proxy.stream_manager Error in fetch_chunk: read of closed file
2025-09-22 00:59:46,900 ERROR ts_proxy.stream_manager FFmpeg stderr for channel be93be21-2554-4924-b832-069dad46424e: [vost#0:0/h264_qsv @ 0x5567f2ed5b80] Error submitting a packet to the muxer: Broken pipe
2025-09-22 00:59:46,900 ERROR ts_proxy.stream_manager FFmpeg stderr for channel be93be21-2554-4924-b832-069dad46424e: [out#0/mpegts @ 0x5567f2ec6d00] Error muxing a packet
2025-09-22 00:59:46,900 ERROR ts_proxy.stream_manager FFmpeg stderr for channel be93be21-2554-4924-b832-069dad46424e: [out#0/mpegts @ 0x5567f2ec6d00] Task finished with error code: -32 (Broken pipe)
2025-09-22 00:59:46,925 ERROR ts_proxy.stream_manager FFmpeg stderr for channel be93be21-2554-4924-b832-069dad46424e: [out#0/mpegts @ 0x5567f2ec6d00] Error writing trailer: Broken pipe
2025-09-22 00:59:46,925 ERROR ts_proxy.stream_manager FFmpeg stderr for channel be93be21-2554-4924-b832-069dad46424e: [out#0/mpegts @ 0x5567f2ec6d00] Error closing file: Broken pipe
2025-09-22 00:59:46,925 INFO ts_proxy.stream_manager FFmpeg info for channel be93be21-2554-4924-b832-069dad46424e: [out#0/mpegts @ 0x5567f2ec6d00] video:45682KiB audio:1477KiB subtitle:0KiB other streams:0KiB global headers:0KiB muxing overhead: 3.845837%
2025-09-22 00:59:46,947 INFO ts_proxy.server Waiting for stream thread to terminate
2025-09-22 00:59:47,000 INFO ts_proxy.stream_manager Stream was stable for 75.0 seconds, resetting switch attempts counter for channel: be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,000 INFO ts_proxy.stream_manager Updated channel be93be21-2554-4924-b832-069dad46424e state to ERROR in Redis after stream failure
2025-09-22 00:59:47,000 INFO ts_proxy.stream_manager Stream manager stopped for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,001 INFO ts_proxy.server Released ownership of channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,001 INFO ts_proxy.server Set stopping signal for channel be93be21-2554-4924-b832-069dad46424e clients
2025-09-22 00:59:47,001 INFO ts_proxy.server Released ownership of channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,001 INFO ts_proxy.server Removed stream manager for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,001 INFO ts_proxy.stream_buffer Flushed final chunk of 232556 bytes to Redis
2025-09-22 00:59:47,001 INFO ts_proxy.server Removed stream buffer for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,001 INFO ts_proxy.server Removed client manager for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,005 INFO ts_proxy.server Cleaned up 135 Redis keys for channel be93be21-2554-4924-b832-069dad46424e
2025-09-22 00:59:47,720 INFO vod_proxy MultiWorkerVODConnectionManager initialized for worker 35b59e35bd09-134
2025-09-22 00:59:48,186 INFO ts_proxy.client_manager No clients for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec after 3 consecutive checks and not in shutdown delay, exiting heartbeat thread
2025-09-22 00:59:49,119 INFO ts_proxy.client_manager No clients for channel be93be21-2554-4924-b832-069dad46424e after 3 consecutive checks and not in shutdown delay, exiting heartbeat thread
2025-09-22 00:59:52,720 INFO vod_proxy MultiWorkerVODConnectionManager initialized for worker 35b59e35bd09-133
2025-09-22 01:00:12,722 INFO vod_proxy MultiWorkerVODConnectionManager initialized for worker 35b59e35bd09-132
2025-09-22 01:00:52,804 INFO vod_proxy MultiWorkerVODConnectionManager initialized for worker 35b59e35bd09-1351 INFO ts_proxy.stream_generator [client_1758502759123_7038] Channel 804a4e11-cbd4-4f33-958d-75bab8e670ec ready, starting normal streaming
2025-09-22 00:59:19,261 INFO ts_proxy.stream_generator [client_1758502759123_7038] Starting stream at index 0 (buffer at 0)
2025-09-22 00:59:21,085 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Input #0, mpegts, from 'provider_url/live/profile_account_2_user/profile_account_2_pass/534381.ts':
2025-09-22 00:59:21,105 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream #0:0[0x100]: Audio: aac (LC) ([15][0][0][0] / 0x000F), 44100 Hz, stereo, fltp, 96 kb/s, start 380.396122
2025-09-22 00:59:21,109 INFO ts_proxy Video stream info - Codec: h264, Resolution: 1280x720, Source FPS: 59.94, Pixel Format: yuv420p, Video Bitrate: None kb/s
2025-09-22 00:59:21,109 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream #0:1[0x101]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709, progressive), 1280x720 [SAR 1:1 DAR 16:9], 59.94 fps, 59.94 tbr, 90k tbn, start 380.404789
2025-09-22 00:59:21,109 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream mapping:
2025-09-22 00:59:21,109 INFO ts_proxy.stream_manager FFmpeg info for channel 804a4e11-cbd4-4f33-958d-75bab8e670ec: Stream #0:1 -> #0:0 (h264 (h264_qsv) -> h264 (h264_qsv))
2025-09-22 00:59:21,109 

With Jellyfin

  1. Create .strm files and add them to a jellyfin library. Example file content: http://dispatcharr:9191/movie/username/password/2441.mkv
  2. Open a movie in Jellyfin
  3. Go back home or close tab
  4. Dispatcharr shows the movie as an active connection with 100% watched.
  5. /player_api.php on the provider shows active_cons":"1"
  6. Restarting Jellyfin releases the connection
  7. /player_api.php on the provider shows active_cons":"0"
  8. Dispatcharr stills shows an active connection.
  9. Stream limit validation prevents new streams from opening. All active M3U profiles have reached maximum connection limits
  10. Restarting Dispatcharr removes it from active connections.

Dispatcharr Version

0.10.0

Docker Version

Docker version 27.0.3, build 7d4bcd8

What's in your Docker Compose file?

not using.

Client Information

Jellyfin Server 10.10.7
Web Version 10.10.7 (f4b8aa0ed4)

Browser:
Brave 1.82.170 (Official Build) (64-bit)
Chromium: 140.0.7339.186

Metadata

Metadata

Assignees

No one assigned

    Labels

    Area: BackendArea: VODVideo On DemandAvailable In DevThis is fixed/available in the dev branch and will be included in the next release.

    Type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions