search

tellytv / telly

An IPTV proxy
MIT License
760 stars 104 forks source link

Channel can not be tuned (translated) - Windows/Trelly/FritzBox #113

Closed Man-in-Black closed 5 years ago

Man-in-Black commented 6 years ago

Hello all together,

first of all, I was trying to bring my broadcasted signals over a Fritzbox 6490 or a FritzRepeater DVB-C over tvheadend onto my plex installation with no luck.

After many researches I found trelly and was happy, that from this moment on I was able to get all Channels with EPG data. Example: image

But if I want to stream a channel, I get to following error: 2018-06-18 12_06_16-plex In english this should be "Channel can not be tuned". The debug log from trelly shows the following:

[telly] [debug] Parsing URI /stream/cnRzcDovLzE5Mi4xNjXXXXXXXXXXXXXDY=?transcode=internet480 to cnRzcDovLXXXXXXY=
[telly] [debug] Redirecting to: rtsp://192.168.178.1:554/?freq=442&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=0,16,17,18,20,101,2201,2202,2203,2204,2206

My plexmediaserver runs onto Windows 10, also my trelly installation runs under Windows 10. The signals are coming from a FritzBox 6490 with DVB-C activated.

I hope that someone can help me.

Thank you Sascha

P.S.: I didn't find the same problem in the existing issues, so I created a new one.

tombowditch commented 6 years ago

Similar to #108

Can you wget one of the URLs in your .m3u file, after a few seconds hit Ctrl+C and then run mediainfo on the file it downloaded?

Man-in-Black commented 6 years ago

Thank you for the reference. I opened this issue because I do not have a .ts ending in my stream files.

Can you give me hint how download a rtsp-stream with wget? My m3u-File looks like this:

#EXTM3U
#EXTINF:0,ProSieben
#EXTVLCOPT:network-caching=1000
rtsp://192.168.178.1:554/?freq=442&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=0,16,17,18,20,101,2201,2202,2203,2204,2206
#EXTINF:0,kabel eins
#EXTVLCOPT:network-caching=1000
rtsp://192.168.178.1:554/?freq=442&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=0,16,17,18,20,109,2301,2302,2304,2306
#EXTINF:0,WELT
#EXTVLCOPT:network-caching=1000
rtsp://192.168.178.1:554/?freq=442&bw=8&msys=dvbc&mtype=256qam&sr=6900&specinv=1&pids=0,16,17,18,20,114,2801,2802,2804

I hope this helps.

Update: I was able to download the stream with VLC, but it was stored as mpeg. The following information is from mediainfo: image

another little update: I tried it with the parameter "-direct", but the error will be the same.

Man-in-Black commented 6 years ago

I got some new information, which I found in the Plex Media Server.log File:

Jun 20, 2018 06:54:20.235 [8340] DEBUG - Auth: authenticated user 1 as {USERNAME}
Jun 20, 2018 06:54:20.237 [14032] DEBUG - Request: [::ffff:x.x.x.x:48161 (WAN)] POST /livetv/dvrs/5/channels/105/tune (7 live) TLS GZIP Signed-in Token (Man-in-Black)
Jun 20, 2018 06:54:20.238 [14032] DEBUG - DVR:Subscription: Starting a new rolling subscription for session z2vpo281jxaifamjm55ls7w7 channel 105.
Jun 20, 2018 06:54:20.583 [14032] DEBUG - Activity: registered new activity 21bbdcc9-3052-4f6d-8d14-412ac7e9650f - Processing subscriptions
Jun 20, 2018 06:54:20.583 [14032] DEBUG - DVR:Subscription: Scheduling subscriptions.
Jun 20, 2018 06:54:20.587 [14032] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Jun 20, 2018 06:54:20.587 [14032] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Jun 20, 2018 06:54:20.587 [14032] DEBUG - DVR:Schedule: Attempting to schedule the item 'Live TV - Session'.
Jun 20, 2018 06:54:20.588 [14032] DEBUG - DVR:Schedule: Trying airing at 2018-06-20 06:54:20 on channel 105.
Jun 20, 2018 06:54:20.588 [14032] DEBUG - DVR:Schedule: Device 0 tuner 0 scheduled recording between 2018-06-20 06:54:20 and 2018-06-20 06:59:20 on channel 105.
Jun 20, 2018 06:54:20.588 [14032] DEBUG - DVR:Grabber: Starting grab immediately for 'Live TV - Session' and we're 0 seconds in already.
Jun 20, 2018 06:54:20.588 [14032] DEBUG - DVR:Subscription: Scheduled all subscriptions in 4 ms.
Jun 20, 2018 06:54:20.589 [14032] DEBUG - Activity: Ended activity 21bbdcc9-3052-4f6d-8d14-412ac7e9650f.
Jun 20, 2018 06:54:20.589 [14032] DEBUG - DVR:Subscription: There are 1 grabs at the end.
Jun 20, 2018 06:54:20.590 [14032] DEBUG - DVR:Subscription: Waiting for media grab to start.
Jun 20, 2018 06:54:20.591 [14104] DEBUG - DVR:Grabber: Starting operation for 'Live TV - Session'.
Jun 20, 2018 06:54:20.592 [14104] DEBUG - Activity: registered new activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - Recording
Jun 20, 2018 06:54:20.593 [14104] DEBUG - DVR:Grabber: HDHomerun starting a media grab on device device://tv.plex.grabbers.hdhomerun/12345678 (Silicondust HDHomerun (telly), supported) tuner 0.
Jun 20, 2018 06:54:20.594 [14104] DEBUG - DVR:Grabber: Creating custom segmented recorder for grab for channel 105
Jun 20, 2018 06:54:20.594 [14104] DEBUG - HTTP requesting GET http://localhost:6077/lineup.json
Jun 20, 2018 06:54:20.599 [14104] DEBUG - HTTP 200 response from GET http://localhost:6077/lineup.json
Jun 20, 2018 06:54:20.601 [14104] DEBUG - DVR:Segmenter: Creating a new recorder for http://localhost:6077/stream/cnRzcDovLzE5Mi4xNjguMTc4LjE6NTU0Lz9mcmVxPTQ0MiZidz04Jm1zeXM9ZHZiYyZtdHlwZT0yNTZxYW0mc3I9NjkwMCZzcGVjaW52PTEmcGlkcz0wLDE2LDE3LDE4LDIwLDEwMSwyMjAxLDIyMDIsMjIwMywyMjA0LDIyMDY=?transcode=internet480.
Jun 20, 2018 06:54:20.606 [14104] DEBUG - DVR:Grabber: Setting timer to stop grab of 'Live TV - Session' in 300 seconds.
Jun 20, 2018 06:54:20.606 [14104] DEBUG - DVR:Grabber: Waiting for a tuner (4 available).
Jun 20, 2018 06:54:20.606 [14104] DEBUG - DVR:Grabber: Allocated a tuner (3 left)
Jun 20, 2018 06:54:20.608 [14104] DEBUG - We're going to try to auto-select an audio stream for account 1.
Jun 20, 2018 06:54:20.608 [14104] DEBUG - Selecting best audio stream for part ID -1 (autoselect: 1 language: de)
Jun 20, 2018 06:54:20.609 [14104] DEBUG - We're going to try to auto-select a subtitle.
Jun 20, 2018 06:54:20.609 [14104] DEBUG - Audio Stream: -1, Subtitle Stream: -1
Jun 20, 2018 06:54:20.610 [14104] DEBUG - MDE: Selected protocol hls; container: mpegts
Jun 20, 2018 06:54:20.610 [14104] ERROR - Unable to find title for item of type 5
Jun 20, 2018 06:54:20.611 [14104] DEBUG - MDE: analyzing media item -1
Jun 20, 2018 06:54:20.611 [14104] DEBUG - MDE: : no direct play video profile exists for http/mpegts/
Jun 20, 2018 06:54:20.611 [14104] DEBUG - MDE: : no direct play video profile exists for http/mpegts//
Jun 20, 2018 06:54:20.611 [14104] DEBUG - MDE: : codec is unavailable for analysis
Jun 20, 2018 06:54:20.612 [14104] DEBUG - MDE: : codec is unavailable for analysis
Jun 20, 2018 06:54:20.612 [14104] ERROR - Unable to find title for item of type 5
Jun 20, 2018 06:54:20.613 [14104] DEBUG - MDE: : selected media 0 / -1
Jun 20, 2018 06:54:20.613 [14104] DEBUG - Cleaning directory for session 2725193b-7b5e-478d-a4c2-686ab13b936f ()
Jun 20, 2018 06:54:20.614 [14104] DEBUG - Starting a transcode session 2725193b-7b5e-478d-a4c2-686ab13b936f at offset -1.0 (state=3)
Jun 20, 2018 06:54:20.615 [14104] DEBUG - Streaming Resource: Added session 0x78d6310:2725193b-7b5e-478d-a4c2-686ab13b936f
Jun 20, 2018 06:54:20.617 [14104] DEBUG - TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Jun 20, 2018 06:54:20.618 [14104] DEBUG - TPU: hardware transcoding: final decoder: , final encoder: 
Jun 20, 2018 06:54:20.623 [14104] DEBUG - Job running: EAE_ROOT='\\?\D:\Medialibrary\Plex\Plex Media Server\Cache\Transcode\Sessions\EasyAudioEncoder' FFMPEG_EXTERNAL_LIBS='D\:\\Medialibrary\\Plex\\Plex\ Media\ Server\\Codecs\\e2b7800-1302-windows-i386\\' XDG_CACHE_HOME='D:\Medialibrary\Plex\Plex Media Server\Cache\' XDG_DATA_HOME='C:\Program Files (x86)\Plex\Plex Media Server\Resources\' X_PLEX_TOKEN='xxxxxxxxxxxxxxxxxxxx' C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe -noaccurate_seek -ignore_unknown -scan_all_pmts -1 -rw_timeout 30000000 -fflags +discardcorruptts+fillwallclockdts -i http://localhost:6077/stream/cnRzcDovLzE5Mi4xNjguMTc4LjE6NTU0Lz9mcmVxPTQ0MiZidz04Jm1zeXM9ZHZiYyZtdHlwZT0yNTZxYW0mc3I9NjkwMCZzcGVjaW52PTEmcGlkcz0wLDE2LDE3LDE4LDIwLDEwMSwyMjAxLDIyMDIsMjIwMywyMjA0LDIyMDY=?transcode=internet480 -map 0:V? -codec:V copy -map 0:a? -codec:a copy -copypriorss:a 0 -map 0:s? -codec:s copy -segment_format mpegts -f ssegment -individual_header_trailer 0 -segment_time 1 -segment_start_number 0 -segment_time_delta 0.0625 -segment_list http://127.0.0.1:32400/video/:/transcode/session/2725193b-7b5e-478d-a4c2-686ab13b936f/58bd7f1c-278b-432b-98cc-385b8d53d80e/seglist -segment_list_type csv -segment_list_size 2147483647 -segment_list_separate_stream_times 1 -max_delay 5000000 -map_metadata -1 -map_chapters -1 media-%05d.ts -y -nostats -loglevel quiet -loglevel_plex error -progressurl http://127.0.0.1:32400/video/:/transcode/session/2725193b-7b5e-478d-a4c2-686ab13b936f/58bd7f1c-278b-432b-98cc-385b8d53d80e/progress 
Jun 20, 2018 06:54:20.631 [14104] DEBUG - Jobs: Starting child process with pid 3648
Jun 20, 2018 06:54:23.594 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 1% - Recording
Jun 20, 2018 06:54:26.595 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 2% - Recording
Jun 20, 2018 06:54:29.597 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 3% - Recording
Jun 20, 2018 06:54:32.598 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 4% - Recording
Jun 20, 2018 06:54:35.600 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 5% - Recording
Jun 20, 2018 06:54:38.601 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 6% - Recording
Jun 20, 2018 06:54:41.603 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 7% - Recording
Jun 20, 2018 06:54:44.605 [6052] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 8% - Recording
Jun 20, 2018 06:54:47.606 [6052] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 9% - Recording
Jun 20, 2018 06:54:50.607 [9796] DEBUG - Activity: updated activity d4aed058-ea8b-4652-a0cd-81362f0d5529 - completed 10% - Recording
Jun 20, 2018 06:54:51.477 [14104] DEBUG - Using default transcode duration of 120 minutes
Jun 20, 2018 06:54:51.477 [14104] DEBUG - Started session successfully: 2725193b-7b5e-478d-a4c2-686ab13b936f
Jun 20, 2018 06:54:51.478 [14104] DEBUG - DVR:Recorder: It took 30.9 sec to start the session for http://localhost:6077/stream/cnRzcDovLzE5Mi4xNjguMTc4LjE6NTU0Lz9mcmVxPTQ0MiZidz04Jm1zeXM9ZHZiYyZtdHlwZT0yNTZxYW0mc3I9NjkwMCZzcGVjaW52PTEmcGlkcz0wLDE2LDE3LDE4LDIwLDEwMSwyMjAxLDIyMDIsMjIwMywyMjA0LDIyMDY=?transcode=internet480
Jun 20, 2018 06:54:51.479 [14104] DEBUG - DVR:Grabber: Merging EPG item with live item.
Jun 20, 2018 06:54:51.479 [14104] ERROR - DVR:Recorder: No part because session isn't reporting one.
Jun 20, 2018 06:54:51.480 [14104] DEBUG - DVR:Grabber: Operation for Live TV - Session completed with status error (Could not start recording)
Jun 20, 2018 06:54:51.480 [14104] DEBUG - Activity: Ended activity d4aed058-ea8b-4652-a0cd-81362f0d5529.
Jun 20, 2018 06:54:51.481 [14104] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 105.
Jun 20, 2018 06:54:51.482 [14104] DEBUG - DVR:Recorder: Asked to stop recording 'Live TV - Session'.
Jun 20, 2018 06:54:51.482 [14104] DEBUG - DVR:Recorder: Stopping transcode session.
Jun 20, 2018 06:54:51.482 [14104] DEBUG - Killing job.
Jun 20, 2018 06:54:51.494 [14104] DEBUG - Job was already killed, not killing again.
Jun 20, 2018 06:54:51.495 [14104] DEBUG - Stopping transcode session 2725193b-7b5e-478d-a4c2-686ab13b936f
Jun 20, 2018 06:54:51.495 [9796] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\PlexTranscoder.exe' exit code for process 3648 is -1059143458 (intentional termination)
Jun 20, 2018 06:54:51.496 [9228] DEBUG - Cleaning directory for session 2725193b-7b5e-478d-a4c2-686ab13b936f (D:\Medialibrary\Plex\Plex Media Server\Cache\Transcode\Sessions\plex-transcode-2725193b-7b5e-478d-a4c2-686ab13b936f)
Jun 20, 2018 06:54:51.523 [14104] DEBUG - DVR:Grabber: Freed a tuner (now 4 available)
Jun 20, 2018 06:54:51.524 [9796] INFO - Notification: Recording Live TV - Session - 
Jun 20, 2018 06:54:51.524 [14032] ERROR - DVR:Subscription: Rolling media grab failed to start.
Jun 20, 2018 06:54:51.524 [14032] DEBUG - DVR:Grabber: Cancelling operation for 'Live TV - Session' with status cancelled.
Jun 20, 2018 06:54:51.524 [14032] DEBUG - DVR:Grabber: Operation was active, stopping recorder.
Jun 20, 2018 06:54:51.525 [14032] DEBUG - DVR:Grabber: We've cancelled the grab with 269 seconds remaining.
Jun 20, 2018 06:54:51.525 [14032] DEBUG - DVR:Grabber: Cleaning up rolling grab for channel 105.
Jun 20, 2018 06:54:51.525 [14032] DEBUG - DVR:Recorder: Asked to stop recording 'Live TV - Session'.
Jun 20, 2018 06:54:51.526 [7592] DEBUG - DVR:Grabber: Recording for 'Live TV - Session' stopped with status complete.
Jun 20, 2018 06:54:51.526 [7592] DEBUG - DVR:Grabber: This was a rolling sub, not assimilating.
Jun 20, 2018 06:54:51.529 [14032] DEBUG - Activity: registered new activity 2ab0134d-f912-4d01-ac73-fb602d888dfe - Processing subscriptions
Jun 20, 2018 06:54:51.530 [14032] DEBUG - DVR:Subscription: Scheduling subscriptions.
Jun 20, 2018 06:54:51.533 [14032] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Jun 20, 2018 06:54:51.533 [14032] DEBUG - DVR:Subscription: Starting with 1 grabs, scheduled 0 active ones preemptively.
Jun 20, 2018 06:54:51.534 [14032] DEBUG - DVR:Subscription: Scheduled all subscriptions in 3 ms.
Jun 20, 2018 06:54:51.534 [14032] DEBUG - Activity: Ended activity 2ab0134d-f912-4d01-ac73-fb602d888dfe.
Jun 20, 2018 06:54:51.535 [14032] DEBUG - DVR:Subscription: There are 0 grabs at the end.
Jun 20, 2018 06:54:51.538 [7592] DEBUG - Activity: registered new activity d2b0dfa9-4835-43de-8cbc-fff5197c8ae8 - Processing subscriptions
Jun 20, 2018 06:54:51.539 [7592] DEBUG - DVR:Subscription: Scheduling subscriptions.
Jun 20, 2018 06:54:51.540 [8340] DEBUG - Completed: [::ffff:x.x.x.x:48161] 200 POST /livetv/dvrs/5/channels/105/tune (7 live) TLS GZIP 31303ms 522 bytes (pipelined: 1)
Jun 20, 2018 06:54:51.543 [7592] DEBUG - DVR:Subscription: Initialized 1 schedulers.
Jun 20, 2018 06:54:51.543 [7592] DEBUG - DVR:Subscription: Starting with 0 grabs, scheduled 0 active ones preemptively.
Jun 20, 2018 06:54:51.543 [7592] DEBUG - DVR:Subscription: Scheduled all subscriptions in 3 ms.
Jun 20, 2018 06:54:51.543 [7592] DEBUG - Activity: Ended activity d2b0dfa9-4835-43de-8cbc-fff5197c8ae8.
Jun 20, 2018 06:54:51.544 [7592] DEBUG - DVR:Subscription: There are 0 grabs at the end.
Jun 20, 2018 06:54:51.595 [9136] DEBUG - Auth: authenticated user 1 as {USERNAME}
Jun 20, 2018 06:54:51.596 [7496] DEBUG - Request: [::ffff:x.x.x.x:48261 (WAN)] GET /status/sessions/background (7 live) TLS GZIP Signed-in Token (Man-in-Black)
Jun 20, 2018 06:54:51.601 [8340] DEBUG - Completed: [::ffff:x.x.x.x:48261] 200 GET /status/sessions/background (7 live) TLS GZIP 5ms 397 bytes (pipelined: 1)
Jun 20, 2018 06:54:51.608 [2916] DEBUG - DVR:Recorder: Asked to stop recording 'Live TV - Session'.

I hope it is not to much, but this is the part when I try to stream a tv show over telly.

julien-blanchon commented 6 years ago

Same issues

stale[bot] commented 6 years ago

This issue has been automatically marked as stale because it has not had recent activity. It will be closed if no further activity occurs. Thank you for your contributions.