and again…
Apr 19, 2022 20:47:01.007 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Subscription: No existing library item show 'guid: tv.plex.xmltv://show/The%20Thing%20About%20Pam ratingKey: tv%2Eplex%2Exmltv%3A%2F%2Fshow%2FThe%2520Thing%2520About%2520Pam thumb: http://images.icetv.com.au/placeholder/56300_poster.png title: The Thing About Pam type: 2'
Apr 19, 2022 20:47:01.007 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Starting operation for 'The Thing About Pam - E1 - She's a Good Friend' on channel 2642.
Apr 19, 2022 20:47:01.007 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Activity: registered new activity 95e9a9f6-cfde-4307-b948-cf5657171925 - "Recording"
Apr 19, 2022 20:47:01.007 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Grabber: Remote starting a media grab on device device://tv.plex.grabbers.tunerservice/dvb%23bda%23pci%23ven_1131%26dev_7164%26subsys_f1200070%26rev_81%234%2629ca82d5%260%2600e0%23 (Hauppauge WinTV-HVR-22XX, supported) tuner 0.
Apr 19, 2022 20:47:01.007 [9720] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] HTTP requesting GET http://127.0.0.1:32600/devices/compound_device071dvb%23bda%23pci%23ven_1131%26dev_7164%26subsys_f1200070%26rev_81%234%2629ca82d5%260%2600e0%23
Apr 19, 2022 20:47:01.008 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Subscription: No existing library item show 'guid: tv.plex.xmltv://show/The%20Thing%20About%20Pam ratingKey: tv%2Eplex%2Exmltv%3A%2F%2Fshow%2FThe%2520Thing%2520About%2520Pam thumb: http://images.icetv.com.au/placeholder/56300_poster.png title: The Thing About Pam type: 2'
Apr 19, 2022 20:47:01.009 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Grabber: checking for 14467 MB of available disk space in grab path and 200 MB in the transcode cache path
Apr 19, 2022 20:47:01.011 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Grabber: Saving recording to "D:\plexTV\.grab\c0ddccd38fb738e1008c5141aa072e82ea30d886-8607b98028f25c801680b454af44e013a28cf86e"
Apr 19, 2022 20:47:01.013 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Grabber: Going to record for 4979 seconds, final URL is http://127.0.0.1:32600/devices/compound_device071dvb%23bda%23pci%23ven_1131%26dev_7164%26subsys_f1200070%26rev_81%234%2629ca82d5%260%2600e0%23/media/triplet%3A%2F%2F4121%3A1792%3A111?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E4%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E585375%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E0%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E7%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E1792%3C%2Ft%3E%3Cn%3E4121%3C%2Fn%3E%3Cs%3E111%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A
Apr 19, 2022 20:47:01.013 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MediaRecorderVirtual: setting stop time to 2022-04-19 22:10:00
Apr 19, 2022 20:47:01.013 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] DVR:Segmenter: Consumer for 'http://127.0.0.1:32600/devices/compound_device071dvb%23bda%23pci%23ven_1131%26dev_7164%26subsys_f1200070%26rev_81%234%2629ca82d5%260%2600e0%23/media/triplet%3A%2F%2F4121%3A1792%3A111?param=%3C%3Fxml%20version%3D%221%2E0%22%20encoding%3D%22UTF-8%22%3F%3E%0A%3Ctunedata%3E%3Ctt%3E4%3C%2Ftt%3E%3Cdq%3E0%3C%2Fdq%3E%3Cdr%2F%3E%3Cfr%3E585375%3C%2Ffr%3E%3Cmd%3E0%3C%2Fmd%3E%3Csr%3E0%3C%2Fsr%3E%3Cpl%3E0%3C%2Fpl%3E%3Clf%3E7%3C%2Flf%3E%3Cl1%3E0%3C%2Fl1%3E%3Cl2%3E0%3C%2Fl2%3E%3Cls%3E0%3C%2Fls%3E%3Cpp%3E0%3C%2Fpp%3E%3Chz%3E0%3C%2Fhz%3E%3Cin%3E0%3C%2Fin%3E%3Cfc%3E0%3C%2Ffc%3E%3Ct%3E1792%3C%2Ft%3E%3Cn%3E4121%3C%2Fn%3E%3Cs%3E111%3C%2Fs%3E%3Cen%3E0%3C%2Fen%3E%3C%2Ftunedata%3E%0A' successfully added to existing recorder 'ea5b4943-8f66-42d7-b172-c40c0e358382'.
Apr 19, 2022 20:47:01.013 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] We're going to try to auto-select an audio stream for account 1.
Apr 19, 2022 20:47:01.013 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Selecting best audio stream for part ID -1 (autoselect: 0 language: en)
Apr 19, 2022 20:47:01.013 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Audio Stream: -1, Subtitle Stream: -1
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MDE: Selected protocol http; container: mpegts
Apr 19, 2022 20:47:01.014 [2072] ERROR - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Unable to find title for item of type 5
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MDE: analyzing media item -1
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MDE: : no direct play video profile exists for hls/mpegts/h264
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MDE: : no direct play video profile exists for hls/mpegts/h264/ac3
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MDE: : non-selected embedded subtitle stream cannot be direct-played
Apr 19, 2022 20:47:01.014 [2072] ERROR - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Unable to find title for item of type 5
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MDE: : selected media 0 / -1
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Cleaning directory for session 0da24166-3051-4bd3-af4b-c5376141dc03 ()
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Starting a transcode session 0da24166-3051-4bd3-af4b-c5376141dc03 at offset -1.0 (state=3)
Apr 19, 2022 20:47:01.014 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Streaming Resource: Added session 0C0E84A8:0da24166-3051-4bd3-af4b-c5376141dc03
Apr 19, 2022 20:47:01.015 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] TPU: hardware transcoding: enabled, but no hardware decode accelerator found
Apr 19, 2022 20:47:01.015 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] TPU: hardware transcoding: final decoder: , final encoder:
Apr 19, 2022 20:47:01.016 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886/JobRunner] Job running: set "FFMPEG_EXTERNAL_LIBS=\\\\?\\C\:\\Users\\user1\\AppData\\Local\\Plex\ Media\ Server\\Codecs\\62cc2bc-4278-windows-x86\\" & set "X_PLEX_TOKEN=xxxxxxxxxxxxxxxxxxxx" & "C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe" -codec:0 h264 -noaccurate_seek -live_start_index 0 -probesize 20000000 -i http://127.0.0.1:32400/livetv/sessions/ea5b4943-8f66-42d7-b172-c40c0e358382/0da24166-3051-4bd3-af4b-c5376141dc03/index.m3u8?offset=4796.649308&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx -map 0:0 -codec:0 copy -map 0:1 -metadata:s:1 language=eng -codec:1 copy -map 0:2 -metadata:s:2 language=eng -codec:2 copy -f mpegts -map_metadata -1 -map_chapters -1 "D:\plexTV\.grab\c0ddccd38fb738e1008c5141aa072e82ea30d886-8607b98028f25c801680b454af44e013a28cf86e\The Thing About Pam - S01E01 - She's a Good Friend.ts" -y -nostats -loglevel quiet -loglevel_plex error -xioerror -progressurl http://127.0.0.1:32400/video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress
Apr 19, 2022 20:47:01.017 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886/JobRunner] Jobs: Starting child process with pid 5544
Apr 19, 2022 20:47:01.044 [8016] DEBUG - Request: [127.0.0.1:55578 (Loopback)] PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=startup (16 live) Signed-in Token (benrric) (HTPC) (range: bytes=0-)
Apr 19, 2022 20:47:01.044 [11492] DEBUG - Completed: [127.0.0.1:55578] 204 PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=startup (16 live) 0ms 203 bytes (pipelined: 1) (range: bytes=0-)
Apr 19, 2022 20:47:01.046 [8016] DEBUG - Request: [127.0.0.1:55578 (Loopback)] PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=startup (16 live) Signed-in Token (benrric) (HTPC) (range: bytes=0-)
Apr 19, 2022 20:47:01.046 [11492] DEBUG - Completed: [127.0.0.1:55578] 204 PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=startup (16 live) 0ms 203 bytes (pipelined: 2) (range: bytes=0-)
Apr 19, 2022 20:47:01.046 [8016] DEBUG - Request: [127.0.0.1:55578 (Loopback)] PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=opening (16 live) Signed-in Token (benrric) (HTPC) (range: bytes=0-)
Apr 19, 2022 20:47:01.046 [11492] DEBUG - Completed: [127.0.0.1:55578] 204 PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=opening (16 live) 0ms 203 bytes (pipelined: 3) (range: bytes=0-)
Apr 19, 2022 20:47:01.047 [8016] DEBUG - [Live/ea5b4943-8f66-42d7-b172-c40c0e358382/0da24166-3051-4bd3-af4b-c5376141dc03] buildLiveM3U8: min 0 max 4796 ended 0
Apr 19, 2022 20:47:01.048 [8016] DEBUG - [Live/ea5b4943-8f66-42d7-b172-c40c0e358382/0da24166-3051-4bd3-af4b-c5376141dc03] buildLiveM3U8: min 0 max 4796 ended 0
Apr 19, 2022 20:47:01.048 [8016] DEBUG - Request: [127.0.0.1:55578 (Loopback)] PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=opened (17 live) Signed-in Token (benrric) (HTPC) (range: bytes=0-)
Apr 19, 2022 20:47:01.048 [11492] DEBUG - Completed: [127.0.0.1:55578] 204 PUT /video/:/transcode/session/0da24166-3051-4bd3-af4b-c5376141dc03/8588b6b9-d33e-469d-b409-3ffe84da4571/progress?status=opened (17 live) 0ms 203 bytes (pipelined: 4) (range: bytes=0-)
Apr 19, 2022 20:47:01.048 [8016] ERROR - [Transcoder] Stream map '0:0' matches no streams.
To ignore this, add a trailing '?' to the map.
Apr 19, 2022 20:47:01.050 [4808] DEBUG - Jobs: 'C:\Program Files (x86)\Plex\Plex Media Server\Plex Transcoder.exe' exit code for process 5544 is 1 (failure)
Apr 19, 2022 20:47:01.050 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] TranscodeSession: session failed while waiting for duration
Apr 19, 2022 20:47:01.050 [2072] ERROR - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Failed to start session.
Apr 19, 2022 20:47:01.050 [0316] DEBUG - Streaming Resource: Terminated session 0C0E84A8:0da24166-3051-4bd3-af4b-c5376141dc03 with reason Recording failed. Please check your tuner or antenna.
Apr 19, 2022 20:47:01.050 [0316] DEBUG - Killing job.
Apr 19, 2022 20:47:01.050 [2072] ERROR - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Recorder: Unable to create transcode session or session failed to start.
Apr 19, 2022 20:47:01.050 [0316] DEBUG - Job was already killed, not killing again.
Apr 19, 2022 20:47:01.050 [0316] DEBUG - Stopping transcode session 0da24166-3051-4bd3-af4b-c5376141dc03
Apr 19, 2022 20:47:01.050 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Job was already killed, not killing again.
Apr 19, 2022 20:47:01.050 [2072] ERROR - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Recorder: Error 12 (There was a transcoder error) starting the record, shutting things down.
Apr 19, 2022 20:47:01.050 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] MediaRecorderVirtual: setting stop time to 2022-04-19 20:47:01
Apr 19, 2022 20:47:01.050 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Grabber: Operation for 'The Thing About Pam - E1 - She's a Good Friend' on channel 2642 completed with status error (There was a transcoder error)
Apr 19, 2022 20:47:01.050 [2072] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Activity: Ended activity 95e9a9f6-cfde-4307-b948-cf5657171925.
Apr 19, 2022 20:47:01.051 [2072] ERROR - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Starting media grab failed.
Apr 19, 2022 20:47:01.051 [7320] DEBUG - [Grabber/c0ddccd38fb738e1008c5141aa072e82ea30d886] Activity: updated activity 54a93434-e1dd-48c3-b6a6-59bdcaf2fe1e - completed 50.0% - Refreshing Sub
Apr 19, 2022 20:47:01.054 [0316] DEBUG - Streaming Resource: Removing session 0C0E84A8:0da24166-3051-4bd3-af4b-c5376141dc03
Apr 19, 2022 20:47:01.054 [0316] DEBUG - Whacked session 0da24166-3051-4bd3-af4b-c5376141dc03, 2 remaining.
