Server Version#: 1.19.5.3112
I have created a dockerized setup of TellyTV and Plex according to documentation (I also tried non-dockerized setups and setups using xteve rather than tellytv. No matter what I do, I keep getting the playback error at the end when I try to play a stream):
TellyTV:
$ cat telly.config.toml
[Discovery] # most likely you won't need to change anything here
Device-Auth = "telly123" # These settings are all related to how telly identifies
Device-ID = 12345678 # itself to Plex.
Device-UUID = ""
Device-Firmware-Name = "hdhomeruntc_atsc"
Device-Firmware-Version = "20150826"
Device-Friendly-Name = "telly"
Device-Manufacturer = "Silicondust"
Device-Model-Number = "HDTC-2US"
SSDP = true
[IPTV]
Streams = 1 # number of simultaneous streams that telly virtual tuner will provide
Starting-Channel = 10000 # When telly assigns channel numbers it will start here
XMLTV-Channels = true # if true, any channel numbers specified in your M3U file will be used.
[Log]
Level = "info" # Only log messages at or above the given level. [debug, info, warn, error, fatal]
Requests = true # Log HTTP requests made to telly
[Web]
Base-Address = "192.168.188.20:6077" # Set this to the IP address of the machine telly runs on AS SEEN BY PLEX
Listen-Address = "0.0.0.0:6077" # this can stay as-is
[[Source]]
Name = "Magenta TV" # Name is optional and is used mostly for logging purposes
Provider = "Custom" # DO NOT CHANGE THIS
M3U = "http://download.avm.de/tv/1/m3u/das_erste.m3u" # This can be either URL or fully-qualified path.
$ docker run -d \
--name='telly-test' \
-e TZ="Europe/Berlin" \
-p '6077:6077/tcp' \
-v ${MY_DIR}/telly.config.toml:/etc/telly/telly.config.toml \
--restart unless-stopped \
tellytv/telly:dev-ffmpeg
time="2020-08-07T14:39:34Z" level=info msg="telly is preparing to go live (version=1.1.0.6, branch=dev, revision=741108cd2100cf9f4d3a13f771d1ebe5132baae6)"
time="2020-08-07T14:39:34Z" level=info msg="Loading M3U from http://download.avm.de/tv/1/m3u/das_erste.m3u"
time="2020-08-07T14:39:34Z" level=info msg="Loaded 52 channels into the lineup from Magenta TV"
time="2020-08-07T14:39:34Z" level=info msg="telly is live and on the air!"
time="2020-08-07T14:39:34Z" level=info msg="Broadcasting from http://0.0.0.0:6077/"
time="2020-08-07T14:39:34Z" level=info msg="EPG URL: http://0.0.0.0:6077/epg.xml"
Plex:
$ docker run \
--name=plex \
--net=host \
-e PUID=999 \
-e PGID=999 \
-e VERSION=docker \
-e UMASK_SET=022 `#optional` \
-e PLEX_CLAIM= `#optional` \
-v /opt/plex:/config \
-v /Videos/series:/tv \
-v /Videos/movies:/movies \
--restart unless-stopped \
linuxserver/plex
Works as a charm. I can now navigate to Settings-> Live TV & DVR and add TellyTV:
Seems to work. But when I now try to play a show on one of the enabled channels (e.g. using Chrome browser as Plex client) I get a Playback Error:
every time. Why is that?
The TellyTV log just says
time="2020-08-07T14:20:23Z" level=info msg="Serving channel number 10001"
Please below an excerpt of the Plex Media Server.log:
Aug 07, 2020 14:33:57.165 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.165 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58434 (Loopback)] POST /livetv/dvrs/2/channels/001/tune (4 live) GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.166 [0x7f58237fe700] DEBUG - Subscription: Starting a new rolling subscription for session hxbuuxqllm9h12qlwxbkwc6j channel 001.
Aug 07, 2020 14:33:57.173 [0x7f58237fe700] DEBUG - Activity: registered new activity e56cbe99-8fd5-413f-a35d-adbe5789644a - Refreshing Sub
Aug 07, 2020 14:33:57.173 [0x7f58237fe700] DEBUG - Activity: updated activity e56cbe99-8fd5-413f-a35d-adbe5789644a - completed 0.0% - Refreshing Sub
Aug 07, 2020 14:33:57.173 [0x7f58237fe700] DEBUG - Activity: updated activity e56cbe99-8fd5-413f-a35d-adbe5789644a - completed 0.0% - Refreshing Sub
Aug 07, 2020 14:33:57.174 [0x7f58237fe700] DEBUG - Activity: updated activity e56cbe99-8fd5-413f-a35d-adbe5789644a - completed 0.0% - Refreshing Sub
Aug 07, 2020 14:33:57.174 [0x7f58237fe700] DEBUG - Activity: registered new activity a5b378f6-a301-490d-9643-9e04c7ba5971 - Processing subscriptions
Aug 07, 2020 14:33:57.174 [0x7f58237fe700] DEBUG - Subscription: Scheduling subscriptions.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - Subscription: Initialized 1 schedulers.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Between 2020-08-07 14:33:57 and 2020-08-07 14:38:57 on channel 001: 'Live TV - Session'
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Initial partitioning completed with 1 partitions.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Partitioning completed with 1 partitions.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: PARTITION of size 1.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Between 2020-08-07 14:33:57 and 2020-08-07 14:38:57 on channel 001: 'Live TV - Session'
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: After processing the partition, we have 1 alternate realities.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: The winning schedule had score: 10 and 0 conflicts.
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - DVR:NewSchedule: device 0 tuner 0 airing 0 between 2020-08-07 14:33:57 and 2020-08-07 14:38:57 on channel 001: 'Live TV - Session'
Aug 07, 2020 14:33:57.175 [0x7f58237fe700] DEBUG - Grabber: Starting grab immediately for 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001 and we're 0 seconds in already.
Aug 07, 2020 14:33:57.176 [0x7f58237fe700] DEBUG - Subscription: Scheduled all subscriptions in 1 ms.
Aug 07, 2020 14:33:57.176 [0x7f58237fe700] DEBUG - Activity: Ended activity a5b378f6-a301-490d-9643-9e04c7ba5971.
Aug 07, 2020 14:33:57.176 [0x7f58017fa700] DEBUG - Grabber: Starting operation for 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001.
Aug 07, 2020 14:33:57.176 [0x7f58017fa700] DEBUG - Activity: registered new activity 7f9d0c47-4bde-495b-85f5-6bd79a49a794 - Recording
Aug 07, 2020 14:33:57.176 [0x7f58237fe700] DEBUG - Subscription: There are 1 active grabs at the end.
Aug 07, 2020 14:33:57.176 [0x7f58017fa700] DEBUG - Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/12345678 (Silicondust telly, supported) tuner 0.
Aug 07, 2020 14:33:57.176 [0x7f58017fa700] DEBUG - Grabber: Creating custom segmented recorder for grab for channel 001
Aug 07, 2020 14:33:57.176 [0x7f58237fe700] DEBUG - Subscription: Waiting for media grab to start.
Aug 07, 2020 14:33:57.176 [0x7f58017fa700] DEBUG - HTTP requesting GET http://192.168.188.20:6077/lineup.json
Aug 07, 2020 14:33:57.176 [0x7f58037fe700] DEBUG - HTTP requesting GET http://192.168.188.20:6077/discover.json
Aug 07, 2020 14:33:57.177 [0x7f58037fe700] DEBUG - HTTP 200 response from GET http://192.168.188.20:6077/discover.json
Aug 07, 2020 14:33:57.177 [0x7f58017fa700] DEBUG - HTTP 200 response from GET http://192.168.188.20:6077/lineup.json
Aug 07, 2020 14:33:57.177 [0x7f58037fe700] DEBUG - HTTP requesting GET http://192.168.188.20:6077/lineup_status.json
Aug 07, 2020 14:33:57.177 [0x7f58017fa700] DEBUG - DVR:Segmenter: Creating a new recorder for http://192.168.188.20:6077/auto/v10000?transcode=heavy.
Aug 07, 2020 14:33:57.177 [0x7f58017fa700] DEBUG - Grabber: Setting timer to stop grab of 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001 in 300 seconds.
Aug 07, 2020 14:33:57.177 [0x7f58017fa700] DEBUG - Grabber: Waiting for a tuner on device://tv.plex.grabbers.hdhomerun/12345678 (1 available) for at most 300 seconds.
Aug 07, 2020 14:33:57.177 [0x7f58017fa700] DEBUG - Grabber: Allocated a tuner on device://tv.plex.grabbers.hdhomerun/12345678 (0 left)
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - We're going to try to auto-select an audio stream for account 1.
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 0 language: en)
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Aug 07, 2020 14:33:57.178 [0x7f58037fe700] DEBUG - HTTP 200 response from GET http://192.168.188.20:6077/lineup_status.json
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: Selected protocol hls; container: mpegts
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] ERROR - Unable to find title for item of type 5
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: analyzing media item -1
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: : codec is unavailable for analysis
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: : codec is unavailable for analysis
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] ERROR - Unable to find title for item of type 5
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - MDE: : selected media 0 / -1
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - Cleaning directory for session 249709c3-5926-4531-87fd-baeaf62e9222 ()
Aug 07, 2020 14:33:57.178 [0x7f58017fa700] DEBUG - Starting a transcode session 249709c3-5926-4531-87fd-baeaf62e9222 at offset -1.0 (state=3)
Aug 07, 2020 14:33:57.179 [0x7f58017fa700] DEBUG - Streaming Resource: Added session 0x7f57ec012820:249709c3-5926-4531-87fd-baeaf62e9222
Aug 07, 2020 14:33:57.179 [0x7f58017fa700] DEBUG - Job running: EAE_ROOT='/tmp/pms-2a67dd2b-826e-4b40-a09d-f363909e211c/EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='/config/Library/Application\ Support/Plex\ Media\ Server/Codecs/5f603a2-3204-linux-x86_64/' XDG_CACHE_HOME='/config/Library/Application Support/Plex Media Server/Cache' XDG_DATA_HOME='/usr/lib/plexmediaserver/Resources' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' '/usr/lib/plexmediaserver/Plex Transcoder' '-noaccurate_seek' '-ignore_unknown' '-scan_all_pmts' '-1' '-rw_timeout' '30000000' '-reconnect' '1' '-reconnect_streamed' '1' '-reconnect_delay_max' '30' '-fflags' '+discardcorruptts+fillwallclockdts' '-probesize' '20000000' '-i' 'http://192.168.188.20:6077/auto/v10000?transcode=heavy' '-map' '0:V?' '-codec:V' 'copy' '-map' '0:a?' '-codec:a' 'copy' '-map' '0:s?' '-codec:s' 'copy' '-break_non_keyframes' '1' '-segment_format' 'mpegts' '-f' 'ssegment' '-individual_header_trailer' '0' '-segment_time' '1' '-segment_start_number' '0' '-segment_time_delta' '0.25' '-segment_list' 'http://127.0.0.1:32400/video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/seglist?X-Plex-Http-Pipeline=infinite' '-segment_list_type' 'csv' '-segment_list_size' '5' '-segment_list_separate_stream_times' '1' '-segment_list_unfinished' '1' '-max_delay' '5000000' '-map_metadata' '-1' '-map_chapters' '-1' 'media-%05d.ts' '-y' '-nostats' '-loglevel' 'quiet' '-loglevel_plex' 'error' '-xioerror' '-progressurl' 'http://127.0.0.1:32400/video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress'
Aug 07, 2020 14:33:57.179 [0x7f5831162700] DEBUG - Whacked session 6d852fa3-e316-485d-87d5-fe09f75271da, 1 remaining.
Aug 07, 2020 14:33:57.179 [0x7f5831162700] DEBUG - Streaming Resource: Removing session 0x7f58180476b0:6d852fa3-e316-485d-87d5-fe09f75271da
Aug 07, 2020 14:33:57.179 [0x7f5831162700] DEBUG - Subscription: Destroying rolling subscription session hxbuuxqllm9h12qlwxbkwc6j for channel 001.
Aug 07, 2020 14:33:57.179 [0x7f58017fa700] DEBUG - Jobs: Starting child process with pid 478
Aug 07, 2020 14:33:57.183 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58448 (Loopback)] PUT /video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress?status=startup (5 live) Signed-in Token (mystef) (range: bytes=0-)
Aug 07, 2020 14:33:57.183 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58448] 204 PUT /video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Aug 07, 2020 14:33:57.187 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58448 (Loopback)] PUT /video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress?status=startup (5 live) Signed-in Token (mystef) (range: bytes=0-)
Aug 07, 2020 14:33:57.187 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58448] 204 PUT /video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress?status=startup (5 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Aug 07, 2020 14:33:57.188 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58448 (Loopback)] PUT /video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress?status=opening (5 live) Signed-in Token (mystef) (range: bytes=0-)
Aug 07, 2020 14:33:57.189 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58448] 204 PUT /video/:/transcode/session/249709c3-5926-4531-87fd-baeaf62e9222/50ca2a34-b517-4d85-a57c-e967898738e2/progress?status=opening (5 live) 1ms 203 bytes (pipelined: 3) (range: bytes=0-)
Aug 07, 2020 14:33:57.189 [0x7f5829ffb700] ERROR - [Transcoder] http://192.168.188.20:6077/auto/v10000?transcode=heavy: Server returned 404 Not Found
Aug 07, 2020 14:33:57.190 [0x7f582bfff700] DEBUG - Jobs: '/usr/lib/plexmediaserver/Plex Transcoder' exit code for process 478 is 1 (failure)
Aug 07, 2020 14:33:57.190 [0x7f5828ff9700] DEBUG - Streaming Resource: Terminated session 0x7f57ec012820:249709c3-5926-4531-87fd-baeaf62e9222 with reason Recording failed. Please check your tuner or antenna.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - TranscodeSession: session failed while waiting for duration
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] ERROR - Failed to start session.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] ERROR - Recorder: Unable to create transcode session or session failed to start.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Killing job.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Signalling job ID 478 with 9
Aug 07, 2020 14:33:57.190 [0x7f5828ff9700] DEBUG - Job was already killed, not killing again.
Aug 07, 2020 14:33:57.190 [0x7f5828ff9700] DEBUG - Job was already killed, not killing again.
Aug 07, 2020 14:33:57.190 [0x7f5828ff9700] DEBUG - Stopping transcode session 249709c3-5926-4531-87fd-baeaf62e9222
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] ERROR - Recorder: Error 16 (Recording failed. Please check your tuner or antenna.) starting the record, shutting things down.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Grabber: Cleaning up rolling grab for channel 001.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Recorder: No more consumers, stopping.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Recorder: Asked to stop recording 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Grabber: Freed a tuner on device://tv.plex.grabbers.hdhomerun/12345678 (now 1 available)
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Grabber: Operation for 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001 completed with status error (Recording failed. Please check your tuner or antenna.)
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Activity: Ended activity 7f9d0c47-4bde-495b-85f5-6bd79a49a794.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Activity: updated activity e56cbe99-8fd5-413f-a35d-adbe5789644a - completed 100.0% - Refreshing Sub
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Activity: Ended activity e56cbe99-8fd5-413f-a35d-adbe5789644a.
Aug 07, 2020 14:33:57.190 [0x7f5823fff700] DEBUG - Cleaning directory for session 249709c3-5926-4531-87fd-baeaf62e9222 (/config/Library/Application Support/Plex Media Server/Cache/Transcode/Sessions/plex-transcode-249709c3-5926-4531-87fd-baeaf62e9222)
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Grabber: Cleaning up rolling grab for channel 001.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Recorder: No more consumers, stopping.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] ERROR - Grabber: Starting media grab failed.
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] ERROR - Unknown metadata type:
Aug 07, 2020 14:33:57.190 [0x7f58017fa700] DEBUG - Asked for unknown type: -1
Aug 07, 2020 14:33:57.190 [0x7f5828ff9700] DEBUG - Transcoder: Cleaning old transcode directories.
Aug 07, 2020 14:33:57.190 [0x7f5828ff9700] DEBUG - Whacked session 249709c3-5926-4531-87fd-baeaf62e9222, 0 remaining.
Aug 07, 2020 14:33:57.190 [0x7f58237fe700] ERROR - Subscription: Rolling media grab failed to start.
Aug 07, 2020 14:33:57.190 [0x7f58237fe700] DEBUG - Subscription: Cancelling grab for subscription 8: 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001
Aug 07, 2020 14:33:57.190 [0x7f58237fe700] DEBUG - Grabber: Cancelling operation for 'Live TV - Session hxbuuxqllm9h12qlwxbkwc6j' on channel 001 with status cancelled.
Aug 07, 2020 14:33:57.190 [0x7f58237fe700] DEBUG - Grabber: Operation was active, stopping recorder.
Aug 07, 2020 14:33:57.190 [0x7f58237fe700] DEBUG - Grabber: We've cancelled the grab with 300 seconds remaining.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Activity: registered new activity 45781842-607b-4542-be29-2a0d98bdf37d - Processing subscriptions
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Subscription: Scheduling subscriptions.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Subscription: Initialized 1 schedulers.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Partitioning, step one, let's compute an absolute ordering.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Initial partitioning completed with 0 partitions.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - DVR:NewSchedule: Partitioning completed with 0 partitions.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - DVR:NewSchedule: We computed 1 alternative realities in 0 ms.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - DVR:NewSchedule: The winning schedule had score: 0 and 0 conflicts.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Subscription: Scheduled all subscriptions in 0 ms.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Activity: Ended activity 45781842-607b-4542-be29-2a0d98bdf37d.
Aug 07, 2020 14:33:57.191 [0x7f58237fe700] DEBUG - Subscription: There are 0 active grabs at the end.
Aug 07, 2020 14:33:57.191 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58434] 200 POST /livetv/dvrs/2/channels/001/tune (4 live) GZIP 25ms 541 bytes (pipelined: 1)
Aug 07, 2020 14:33:57.350 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.350 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58434 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onnow/all (4 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.351 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.355 [0x7f582affd700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.355 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onnow/shows (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.355 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.361 [0x7f582a7fc700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onnow/shows (5 live) GZIP Page 0-15 6ms 3782 bytes (pipelined: 1)
Aug 07, 2020 14:33:57.363 [0x7f582a7fc700] DEBUG - Completed: [127.0.0.1:58434] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onnow/all (5 live) GZIP Page 0-15 12ms 4250 bytes (pipelined: 2)
Aug 07, 2020 14:33:57.373 [0x7f582affd700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.373 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onnow/sports (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.373 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.376 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onnow/sports (5 live) GZIP Page 0-15 3ms 544 bytes (pipelined: 2)
Aug 07, 2020 14:33:57.378 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.378 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onnow/movies (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.378 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.378 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.379 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58434 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onnow/kids (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.379 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.379 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onnow/movies (5 live) GZIP Page 0-15 1ms 544 bytes (pipelined: 3)
Aug 07, 2020 14:33:57.382 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58434] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onnow/kids (5 live) GZIP Page 0-15 3ms 1372 bytes (pipelined: 3)
Aug 07, 2020 14:33:57.389 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.389 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onnow/news (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.389 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.393 [0x7f582a7fc700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onnow/news (5 live) GZIP Page 0-15 3ms 544 bytes (pipelined: 4)
Aug 07, 2020 14:33:57.396 [0x7f582affd700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.396 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/sections/2/all?type=4&beginsAt%3E=1596826800&beginsAt%3C=1596841200&mediaAnalysisVersion=1&sort=beginsAt (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.397 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.398 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=0)
Aug 07, 2020 14:33:57.398 [0x7f582a7fc700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/sections/2/all?type=4&beginsAt%3E=1596826800&beginsAt%3C=1596841200&mediaAnalysisVersion=1&sort=beginsAt (5 live) GZIP Page 0-15 2ms 695 bytes (pipelined: 5)
Aug 07, 2020 14:33:57.402 [0x7f582affd700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.403 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/playlists/fav (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.403 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.403 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.403 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58434 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/playlists/rec (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.403 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.404 [0x7f582a7fc700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/playlists/fav (5 live) GZIP Page 0-15 1ms 544 bytes (pipelined: 6)
Aug 07, 2020 14:33:57.404 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58434] 200 GET /tv.plex.providers.epg.cloud:2/hubs/playlists/rec (5 live) GZIP Page 0-15 1ms 544 bytes (pipelined: 4)
Aug 07, 2020 14:33:57.407 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.407 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onlater/all (5 live) Page 0-15 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.407 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 15] (total=-1)
Aug 07, 2020 14:33:57.412 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onlater/all (5 live) GZIP Page 0-15 5ms 3751 bytes (pipelined: 7)
Aug 07, 2020 14:33:57.413 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.413 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58434 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onlater/movies (5 live) Page 0-16 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.413 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 16] (total=-1)
Aug 07, 2020 14:33:57.414 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.414 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/onlater/kids (5 live) Page 0-50 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.414 [0x7f58237fe700] DEBUG - Setting container serialization range to [0, 50] (total=-1)
Aug 07, 2020 14:33:57.417 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58434] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onlater/movies (5 live) GZIP Page 0-16 3ms 5126 bytes (pipelined: 5)
Aug 07, 2020 14:33:57.427 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.427 [0x7f5829ffb700] DEBUG - Request: [127.0.0.1:58462 (Loopback)] GET /tv.plex.providers.epg.cloud:2/sections/4/all?type=4&beginsAt%3E=now&sort=beginsAt,mediaHeight:desc,mediaAnalysisVersion:desc (7 live) Page 0-50 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.427 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 50] (total=-1)
Aug 07, 2020 14:33:57.427 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.427 [0x7f5803fff700] DEBUG - Request: [127.0.0.1:58460 (Loopback)] GET /tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fepisode%2F5f2425bce96ad600427bb483?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (7 live) GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.428 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/hubs/onlater/kids (7 live) GZIP Page 0-50 14ms 13349 bytes (pipelined: 8)
Aug 07, 2020 14:33:57.429 [0x7f5803fff700] DEBUG - Activity: registered new activity 02f898b4-690b-4585-bd8e-43d6ee31f01e - Refreshing
Aug 07, 2020 14:33:57.429 [0x7f5828ff9700] DEBUG - Activity: updated activity 02f898b4-690b-4585-bd8e-43d6ee31f01e - completed 0.0% - Refreshing
Aug 07, 2020 14:33:57.429 [0x7f5828ff9700] DEBUG - Activity: updated activity 02f898b4-690b-4585-bd8e-43d6ee31f01e - completed 33.0% - Refreshing
Aug 07, 2020 14:33:57.429 [0x7f5828ff9700] DEBUG - Activity: updated activity 02f898b4-690b-4585-bd8e-43d6ee31f01e - completed 66.0% - Refreshing
Aug 07, 2020 14:33:57.429 [0x7f5828ff9700] DEBUG - Activity: Ended activity 02f898b4-690b-4585-bd8e-43d6ee31f01e.
Aug 07, 2020 14:33:57.429 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58460] 200 GET /tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fepisode%2F5f2425bce96ad600427bb483?includeConcerts=1&includeExtras=1&includeOnDeck=1&includePopularLeaves=1&includePreferences=1&includeChapters=1&includeStations=1&includeExternalMedia=1&asyncAugmentMetadata=1&asyncCheckFiles=1&asyncRefreshAnalysis=1&asyncRefreshLocalMediaAgent=1&skipRefresh=1 (7 live) GZIP 2ms 1726 bytes (pipelined: 1)
Aug 07, 2020 14:33:57.432 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.432 [0x7f582a7fc700] DEBUG - Auth: authenticated user 1 as mystef
Aug 07, 2020 14:33:57.432 [0x7f5803fff700] DEBUG - Request: [127.0.0.1:58458 (Loopback)] GET /tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fshow%2F5da900d562aeac001f688dca/grandchildren?excludeAllLeaves=1 (7 live) Page 0-0 GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.432 [0x7f58237fe700] DEBUG - Request: [127.0.0.1:58460 (Loopback)] GET /tv.plex.providers.epg.cloud:2/hubs/metadata/plex%3A%2F%2Fepisode%2F5f2425bce96ad600427bb483/related?excludeFields=summary&includeExternalMetadata=1&asyncAugmentMetadata=1 (7 live) GZIP Signed-in Token (mystef)
Aug 07, 2020 14:33:57.433 [0x7f5803fff700] DEBUG - Setting container serialization range to [0, 0] (total=-1)
Aug 07, 2020 14:33:57.434 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58460] 200 GET /tv.plex.providers.epg.cloud:2/hubs/metadata/plex%3A%2F%2Fepisode%2F5f2425bce96ad600427bb483/related?excludeFields=summary&includeExternalMetadata=1&asyncAugmentMetadata=1 (7 live) GZIP 2ms 484 bytes (pipelined: 2)
Aug 07, 2020 14:33:57.435 [0x7f5803fff700] DEBUG - Setting container serialization range to [0, 0] (total=1)
Aug 07, 2020 14:33:57.435 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58458] 200 GET /tv.plex.providers.epg.cloud:2/metadata/plex%3A%2F%2Fshow%2F5da900d562aeac001f688dca/grandchildren?excludeAllLeaves=1 (7 live) GZIP Page 0-0 3ms 1614 bytes (pipelined: 9)
Aug 07, 2020 14:33:57.437 [0x7f5829ffb700] DEBUG - Setting container serialization range to [0, 50] (total=55)
Aug 07, 2020 14:33:57.442 [0x7f582affd700] DEBUG - Completed: [127.0.0.1:58462] 200 GET /tv.plex.providers.epg.cloud:2/sections/4/all?type=4&beginsAt%3E=now&sort=beginsAt,mediaHeight:desc,mediaAnalysisVersion:desc (7 live) GZIP Page 0-50 15ms 7143 bytes (pipelined: 1)
Aug 07, 2020 14:33:58.178 [0x7f5800ff9700] DEBUG - DVR:Segmenter: Stopping idle session: http://192.168.188.20:6077/auto/v10000?transcode=heavy.



