18:53:33.011 [WrapperSimpleAppMain] INFO Main - Starting OpenDCT 0.5.32... 18:53:33.020 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 10' is WINDOWS. 18:53:33.020 [WrapperSimpleAppMain] INFO Config - Current directory is 'C:\Program Files (x86)\OpenDCT Authors\OpenDCT' 18:53:33.038 [WrapperSimpleAppMain] INFO Main - OpenDCT logging to the directory 'c:/ProgramData/OpenDCT/logs'. 18:53:33.069 [WrapperSimpleAppMain] INFO UpnpManager - Logging Cling UPnP to 'c:/ProgramData/OpenDCT/logs\opendct_cling.log'. 18:53:33.069 [WrapperSimpleAppMain] DEBUG UpnpManager - Logging level for Cling UPnP is set to 'severe'. 18:53:33.070 [FFmpegAsyncInit-23] INFO Main - FFmpeg loading... 18:53:33.079 [FFmpegAsyncInit-23] DEBUG FFmpegUtil - Initializing FFmpegLogger... 18:53:33.134 [WindowsPowerMessagePump] INFO WindowsPowerMessagePump - Message pump started. 18:53:38.109 [WrapperSimpleAppMain] INFO NetworkPowerEventManger - Network interfaces which are up and have an IP4 address are: name:eth1 (Intel(R) Ethernet Connection (14) I219-V) 192.168.1.143 18:53:38.109 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.power.NetworkPowerEventManger' is now listening. 18:53:38.111 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.sagetv.SageTVManager' is now listening. 18:53:38.114 [DynamicAsyncInit-23] INFO DynamicConsumerImpl - Dynamic consumer default set to use opendct.consumer.FFmpegTransSageTVConsumerImpl 18:53:38.114 [DynamicAsyncInit-23] INFO DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.FFmpegTransSageTVConsumerImpl for [] 18:53:38.114 [DynamicAsyncInit-23] INFO DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.MediaServerConsumerImpl for [] 18:53:38.114 [DynamicAsyncInit-23] INFO DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.RawSageTVConsumerImpl for [] 18:53:38.162 [WrapperSimpleAppMain] INFO UpnpManager - Starting UPnP services... 18:53:38.185 [WrapperSimpleAppMain] INFO DCTDefaultUpnpServiceConfiguration - Using the interface 'eth1' with IP address 192.168.1.143 for UPnP discovery. 18:53:38.199 [WrapperSimpleAppMain] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:53:38.203 [UPnPDiscovery-38] INFO UpnpManager - UPnP discovery thread has started. 18:53:38.216 [HDHomeRunDiscoverySend-39] INFO HDHomeRunDiscovery - HDHomeRun discovery sender thread started. 18:53:38.216 [HDHomeRunDiscoveryReceive-39] INFO HDHomeRunDiscovery - HDHomeRun discovery receive thread for /192.168.1.255:65001 broadcast started. 18:53:38.217 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.tuning.discovery.DiscoveryManager' is now listening. 18:53:38.217 [WrapperSimpleAppMain] INFO NanoHTTPDManager - Starting webserver on port 9091... 18:53:38.217 [HDHomeRunDiscoverySend-39] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... (startup) 18:53:38.218 [GenericPipeLoader-43] DEBUG DeviceLoaderImpl - Advertising new capture device 'Chan1'. 18:53:38.220 [GenericPipeLoader-43] DEBUG SageTVManager - The requested capture device 'Chan1' did not exist. Triggering re-discovery. 18:53:38.220 [GenericPipeLoader-43] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 18:53:38.224 [GenericPipeLoader-43] DEBUG SageTVManager - Port 9000 is available. 18:53:38.226 [GenericPipeLoader-43] INFO SageTVManager - The capture device 'Chan1' is ready. 18:53:38.227 [GenericPipeLoader-43] DEBUG SageTVSocketServer - Setting listening flag... 18:53:38.227 [GenericPipeLoader-43] INFO SageTVSocketServer - Opening ServerSocket on port 9000... 18:53:38.227 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - Tuning monitor thread started. 18:53:38.227 [GenericPipeLoader-43] DEBUG DeviceLoaderImpl - Advertising new capture device 'Chan2'. 18:53:38.227 [SageTVSocketServer-48:9000] INFO SageTVSocketServer - Started listening on port 9000... 18:53:38.227 [GenericPipeLoader-43] DEBUG SageTVManager - The requested capture device 'Chan2' did not exist. Triggering re-discovery. 18:53:38.227 [GenericPipeLoader-43] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 18:53:38.228 [GenericPipeLoader-43] INFO SageTVManager - The capture device 'Chan2' is ready. 18:53:38.229 [GenericPipeLoader-43] DEBUG DeviceLoaderImpl - Advertising new capture device 'Chan3'. 18:53:38.229 [GenericPipeLoader-43] DEBUG SageTVManager - The requested capture device 'Chan3' did not exist. Triggering re-discovery. 18:53:38.229 [GenericPipeLoader-43] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 18:53:38.230 [GenericPipeLoader-43] INFO SageTVManager - The capture device 'Chan3' is ready. 18:53:38.230 [GenericPipeLoader-43] DEBUG DeviceLoaderImpl - Advertising new capture device 'Chan4'. 18:53:38.230 [GenericPipeLoader-43] DEBUG SageTVManager - The requested capture device 'Chan4' did not exist. Triggering re-discovery. 18:53:38.230 [GenericPipeLoader-43] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 18:53:38.231 [GenericPipeLoader-43] INFO SageTVManager - The capture device 'Chan4' is ready. 18:53:38.245 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.nanohttpd.NanoHTTPDManager' is now listening. 18:53:38.245 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.channel.ChannelManager' is now listening. 18:53:38.247 [WrapperSimpleAppMain] INFO Main - Running in daemon mode... 18:53:38.432 [HDHomeRunDiscoverySend-39] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... (requested) 18:53:38.634 [HDHomeRunDiscoverySend-39] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... (startup) 18:53:38.836 [HDHomeRunDiscoverySend-39] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... (startup) 18:53:42.217 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:53:46.224 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:53:49.075 [SageTVSocketServer-48:9000] WARN SageTVSocketServer - IPv6 connection detected. This is an untested configuration. 18:53:49.076 [SageTVRequestHandler-51:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION' 18:53:49.076 [SageTVRequestHandler-51:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0' 18:53:49.077 [SageTVRequestHandler-51:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP Chan1 HDMI' 18:53:49.077 [SageTVRequestHandler-51:Chan1] DEBUG GenericPipeCaptureDevice - Stopping encoding... 18:53:49.078 [SageTVRequestHandler-51:Chan1] DEBUG BasicCaptureDevice - Consumer is not in progress. 18:53:49.078 [SageTVRequestHandler-51:Chan1] INFO GenericPipeCaptureDevice - Capture device was already unlocked. 18:53:49.078 [SageTVRequestHandler-51:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:53:49.080 [SageTVDiscovery-46] DEBUG SageTVDiscovery - Received discovery datagram from SageTV server '/192.168.1.143:8270' and validating... 18:53:49.080 [SageTVDiscovery-46] DEBUG SageTVDiscovery - Validated discovery datagram from SageTV server '/192.168.1.143:8270' and preparing response... 18:53:49.081 [SageTVDiscovery-46] INFO SageTVDiscovery - Sent discovery response datagram to SageTV server '/192.168.1.143:8270'. 18:53:50.229 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:53:52.085 [SageTVRequestHandler-52:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'PROPERTIES' 18:53:52.088 [SageTVRequestHandler-52:Unknown] INFO SageTVRequestHandler - Sent PROPERTIES. 18:53:52.089 [SageTVRequestHandler-52:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'QUIT' 18:53:54.233 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:53:58.238 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:02.239 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:02.535 [SageTVRequestHandler-51:Chan1] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|38643292|6050|3282184085070|G:\Sage Recordings 64 G\PaperGlue-43162-0.ts|Great-H.264' 18:54:02.535 [SageTVRequestHandler-51:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-locked. 18:54:02.535 [SageTVRequestHandler-51:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '38643292' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-0.ts'. 18:54:02.535 [SageTVRequestHandler-51:Chan1] DEBUG BasicCaptureDevice - Consumer is not in progress. 18:54:02.536 [SageTVRequestHandler-51:Chan1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '6050' 18:54:02.537 [SageTVRequestHandler-51:Chan1] INFO GenericPipeCaptureDevice - Starting the encoding for the channel '6050' from the device 'Chan1' to the file 'G:\Sage Recordings 64 G\PaperGlue-43162-0.ts' via the upload id '38643292'... 18:54:02.537 [SageTVRequestHandler-51:Chan1] DEBUG GenericPipeCaptureDevice - Executing: 'c:\FFMPEG\ffmpeg.exe -i http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8? -f mpegts -codec:v copy -codec:a copy - ' 18:54:02.541 [SageTVRequestHandler-51:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV producer... 18:54:02.541 [SageTVRequestHandler-51:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV consumer... 18:54:02.542 [SageTVRequestHandler-51:Chan1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /127.0.0.1:7818... 18:54:02.542 [SageTVRequestHandler-51:Chan1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN G:\Sage Recordings 64 G\PaperGlue-43162-0.ts 38643292' to SageTV server... 18:54:02.546 [SageTVRequestHandler-51:Chan1] DEBUG BasicCaptureDevice - Consumer is not in progress. 18:54:02.547 [FFmpegTransSageTVConsumerImpl-55:Chan1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 18:54:02.547 [FFmpegTransSageTVConsumerImpl-55:Chan1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 18:54:02.550 [FFmpegTransSageTVConsumerImpl-55:Chan1] ERROR ThreadPoolExecutor - Thread threw unhandled exception => java.lang.NullPointerException at opendct.consumer.FFmpegTransSageTVConsumerImpl.run(FFmpegTransSageTVConsumerImpl.java:176) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 18:54:02.717 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: ffmpeg version n4.4.1-2-gcc33e73618-20211203 Copyright (c) 2000-2021 the FFmpeg developers 18:54:02.717 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: built with gcc 10-win32 (GCC) 20210610 18:54:02.717 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: configuration: --prefix=/ffbuild/prefix --pkg-config-flags=--static --pkg-config=pkg-config --cross-prefix=x86_64-w64-mingw32- --arch=x86_64 --target-os=mingw32 --enable-gpl --enable-version3 --disable-debug --disable-w32threads --enable-pthreads --enable-iconv --enable-libxml2 --enable-zlib --enable-libfreetype --enable-libfribidi --enable-gmp --enable-lzma --enable-fontconfig --enable-libvorbis --enable-opencl --enable-libvmaf --disable-libxcb --disable-xlib --enable-amf --enable-libaom --enable-avisynth --enable-libdav1d --enable-libdavs2 --disable-libfdk-aac --enable-ffnvcodec --enable-cuda-llvm --disable-frei0r --enable-libgme --enable-libass --enable-libbluray --enable-libmp3lame --enable-libopus --enable-librist --enable-libtheora --enable-libvpx --enable-libwebp --enable-lv2 --enable-libmfx --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenh264 --enable-libopenjpeg --enable-librav1e --enable-librubberband --enable-schannel --enable-sdl2 --enable-libsoxr --enable-libsrt --enable-libsvtav1 --enable-libtwolame --enable-libuavs3d --disable-libdrm --disable-vaapi --enable-libvidstab --disable-vulkan --disable-libglslang --enable-libx264 --enable-libx265 --enable-libxavs2 --enable-libxvid --enable-libzimg --enable-libzvbi --extra-cflags=-DLIBTWOLAME_STATIC --extra-cxxflags= --extra-ldflags=-pthread --extra-ldexeflags= --extra-libs=-lgomp --extra-version=20211203 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libavutil 56. 70.100 / 56. 70.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libavcodec 58.134.100 / 58.134.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libavformat 58. 76.100 / 58. 76.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libavdevice 58. 13.100 / 58. 13.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libavfilter 7.110.100 / 7.110.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libswscale 5. 9.100 / 5. 9.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libswresample 3. 9.100 / 3. 9.100 18:54:02.718 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: libpostproc 55. 9.100 / 55. 9.100 18:54:06.250 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:08.253 [WrapperSimpleAppMain] INFO Main - Saving config... 18:54:09.048 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Skip ('#EXT-X-VERSION:3') 18:54:09.048 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:09.059 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Skip ('#EXT-X-VERSION:3') 18:54:09.059 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream1.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:09.076 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream2.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:09.103 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Input #0, hls, from 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8?': 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Duration: N/A, start: 0.066733, bitrate: N/A 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Program 0 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Output #0, mpegts, to 'pipe:': 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: encoder : Lavf58.76.100 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 29.97 tbr, 90k tbn, 90k tbc 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:54:09.104 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:54:09.105 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:54:09.105 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream mapping: 18:54:09.105 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream #0:0 -> #0:0 (copy) 18:54:09.105 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Stream #0:1 -> #0:1 (copy) 18:54:09.105 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: Press [q] to stop, [?] for help 18:54:09.105 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: frame= 1 fps=0.0 q=-1.0 size= 0kB time=00:00:00.00 bitrate=N/A speed=N/A 18:54:10.253 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:10.253 [UPnPDiscovery-38] DEBUG UpnpManager - Stopping broadcast. 18:54:10.253 [UPnPDiscovery-38] DEBUG DiscoveryRegistryListener - Before shutdown, the registry has 1 device(s). 18:54:10.254 [UPnPDiscovery-38] DEBUG DiscoveryRegistryListener - Shutdown of UPnP registry complete. 18:54:11.166 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600b4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:11.166 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Skip ('#EXT-X-VERSION:3') 18:54:12.680 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600b4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:12.704 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Skip ('#EXT-X-VERSION:3') 18:54:12.704 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600c1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream3.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:12.704 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600be900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream4.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:12.705 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: frame= 120 fps= 33 q=-1.0 size= 3973kB time=00:00:03.93 bitrate=8279.5kbits/s speed=1.09x 18:54:12.710 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600c1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream5.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:12.715 [InputStreamProducerImpl-52:Chan1] WARN SeekableCircularBufferNIO - The buffer has 7848436 bytes left to be read, has only 15883 bytes left for writing and 51700 bytes cannot be added. Deferring bytes to queue buffer. 18:54:12.716 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600be900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream6.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:12.720 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600c1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream7.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:12.724 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600be900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream8.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:14.781 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600b4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:14.781 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Skip ('#EXT-X-VERSION:3') 18:54:16.275 [UPnPDiscovery-38] DEBUG UpnpManager - Broadcast requested. 18:54:16.285 [UPnPDiscovery-38] INFO DCTDefaultUpnpServiceConfiguration - Using the interface 'eth1' with IP address 192.168.1.143 for UPnP discovery. 18:54:16.290 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600b4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:16.299 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000015f600b2cc0] Skip ('#EXT-X-VERSION:3') 18:54:16.299 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600be900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream9.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:16.299 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600c1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream10.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:16.300 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: frame= 479 fps= 67 q=-1.0 size= 15820kB time=00:00:15.96 bitrate=8118.2kbits/s speed=2.22x 18:54:16.304 [StreamLogger-54] DEBUG GenericPipeCaptureDevice - err: [http @ 0000015f600be900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream11.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:54:17.542 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|38643292|6050|3282184085070|G:\Sage Recordings 64 G\PaperGlue-43162-0.ts|Great-H.264' 18:54:17.542 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Capture device was already locked. 18:54:17.542 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '38643292' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-0.ts'. 18:54:17.555 [SageTVRequestHandler-51:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:54:17.555 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Re-tune came back too fast. Skipping. 18:54:17.556 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:54:17.557 [SageTVRequestHandler-51:Chan1] DEBUG SageTVRequestHandler - An unhandled exception was created => java.net.SocketException: Software caused connection abort: recv failed at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at sun.nio.cs.StreamDecoder.readBytes(Unknown Source) at sun.nio.cs.StreamDecoder.implRead(Unknown Source) at sun.nio.cs.StreamDecoder.read(Unknown Source) at java.io.InputStreamReader.read(Unknown Source) at java.io.BufferedReader.fill(Unknown Source) at java.io.BufferedReader.readLine(Unknown Source) at java.io.BufferedReader.readLine(Unknown Source) at opendct.sagetv.SageTVRequestHandler.run(SageTVRequestHandler.java:127) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 18:54:17.558 [InputStreamProducerImpl-52:Chan1] DEBUG InputStreamProducerImpl - Stream ended. 18:54:20.292 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:24.299 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:28.299 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:32.305 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:36.307 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:37.706 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - 'Chan1' produced first 22769808 packets. 18:54:40.318 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:44.322 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:48.327 [UPnPDiscovery-38] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 18:54:48.327 [UPnPDiscovery-38] DEBUG UpnpManager - Stopping broadcast. 18:54:48.327 [UPnPDiscovery-38] WARN DiscoveryRegistryListener - Before UPnP shutdown, the registry did not contain any devices. 18:54:48.327 [UPnPDiscovery-38] DEBUG DiscoveryRegistryListener - Shutdown of UPnP registry complete. 18:54:53.864 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-0.ts', copy protection NONE. 18:54:53.864 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 22769808. 18:55:08.255 [ChannelManager-50] INFO ChannelManager - The static channel lineup Chan1 (generic_pipe) will remain unchanged. 18:55:10.049 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-0.ts', copy protection NONE. 18:55:10.049 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 22769808. 18:55:22.222 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP Chan1 HDMI' 18:55:22.222 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Stopping encoding... 18:55:22.222 [SageTVRequestHandler-55:Chan1] DEBUG InputStreamCaptureDeviceServices - Stopping producer thread... 18:55:22.222 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:55:22.223 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-unlocked. 18:55:22.223 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:55:22.224 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|1789149166|6050|3282184244446|G:\Sage Recordings 64 G\PaperGlue-43162-1.ts|Great-H.264' 18:55:22.224 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-locked. 18:55:22.224 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1789149166' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-1.ts'. 18:55:22.224 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:55:22.224 [SageTVRequestHandler-55:Chan1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '6050' 18:55:22.225 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Starting the encoding for the channel '6050' from the device 'Chan1' to the file 'G:\Sage Recordings 64 G\PaperGlue-43162-1.ts' via the upload id '1789149166'... 18:55:22.226 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Executing: 'c:\FFMPEG\ffmpeg.exe -i http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8? -f mpegts -codec:v copy -codec:a copy - ' 18:55:22.228 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV producer... 18:55:22.228 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV consumer... 18:55:22.228 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /127.0.0.1:7818... 18:55:22.228 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN G:\Sage Recordings 64 G\PaperGlue-43162-1.ts 1789149166' to SageTV server... 18:55:22.229 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:55:22.229 [FFmpegTransSageTVConsumerImpl-58:Chan1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 18:55:22.229 [FFmpegTransSageTVConsumerImpl-58:Chan1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 18:55:22.229 [FFmpegTransSageTVConsumerImpl-58:Chan1] ERROR ThreadPoolExecutor - Thread threw unhandled exception => java.lang.NullPointerException at opendct.consumer.FFmpegTransSageTVConsumerImpl.run(FFmpegTransSageTVConsumerImpl.java:176) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: ffmpeg version n4.4.1-2-gcc33e73618-20211203 Copyright (c) 2000-2021 the FFmpeg developers 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: built with gcc 10-win32 (GCC) 20210610 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: configuration: --prefix=/ffbuild/prefix --pkg-config-flags=--static --pkg-config=pkg-config --cross-prefix=x86_64-w64-mingw32- --arch=x86_64 --target-os=mingw32 --enable-gpl --enable-version3 --disable-debug --disable-w32threads --enable-pthreads --enable-iconv --enable-libxml2 --enable-zlib --enable-libfreetype --enable-libfribidi --enable-gmp --enable-lzma --enable-fontconfig --enable-libvorbis --enable-opencl --enable-libvmaf --disable-libxcb --disable-xlib --enable-amf --enable-libaom --enable-avisynth --enable-libdav1d --enable-libdavs2 --disable-libfdk-aac --enable-ffnvcodec --enable-cuda-llvm --disable-frei0r --enable-libgme --enable-libass --enable-libbluray --enable-libmp3lame --enable-libopus --enable-librist --enable-libtheora --enable-libvpx --enable-libwebp --enable-lv2 --enable-libmfx --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenh264 --enable-libopenjpeg --enable-librav1e --enable-librubberband --enable-schannel --enable-sdl2 --enable-libsoxr --enable-libsrt --enable-libsvtav1 --enable-libtwolame --enable-libuavs3d --disable-libdrm --disable-vaapi --enable-libvidstab --disable-vulkan --disable-libglslang --enable-libx264 --enable-libx265 --enable-libxavs2 --enable-libxvid --enable-libzimg --enable-libzvbi --extra-cflags=-DLIBTWOLAME_STATIC --extra-cxxflags= --extra-ldflags=-pthread --extra-ldexeflags= --extra-libs=-lgomp --extra-version=20211203 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libavutil 56. 70.100 / 56. 70.100 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libavcodec 58.134.100 / 58.134.100 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libavformat 58. 76.100 / 58. 76.100 18:55:22.386 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libavdevice 58. 13.100 / 58. 13.100 18:55:22.387 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libavfilter 7.110.100 / 7.110.100 18:55:22.387 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libswscale 5. 9.100 / 5. 9.100 18:55:22.387 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libswresample 3. 9.100 / 3. 9.100 18:55:22.387 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: libpostproc 55. 9.100 / 55. 9.100 18:55:26.117 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:26.117 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:26.133 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:26.133 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream1.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:26.148 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream2.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Input #0, hls, from 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8?': 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Duration: N/A, start: 0.066733, bitrate: N/A 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Program 0 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Output #0, mpegts, to 'pipe:': 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: encoder : Lavf58.76.100 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 29.97 tbr, 90k tbn, 90k tbc 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream mapping: 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream #0:0 -> #0:0 (copy) 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Stream #0:1 -> #0:1 (copy) 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: Press [q] to stop, [?] for help 18:55:26.178 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: frame= 1 fps=0.0 q=-1.0 size= 0kB time=00:00:00.00 bitrate=N/A speed=N/A 18:55:28.236 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028977794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:28.237 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:29.757 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028977794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:29.775 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:29.775 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 00000289777a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream3.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:29.776 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 000002897779e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream4.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:29.776 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: frame= 119 fps= 33 q=-1.0 size= 4267kB time=00:00:03.95 bitrate=8838.0kbits/s speed= 1.1x 18:55:29.780 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 00000289777a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream5.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:29.782 [InputStreamProducerImpl-52:Chan1] WARN SeekableCircularBufferNIO - The buffer has 7834768 bytes left to be read, has only 29551 bytes left for writing and 32092 bytes cannot be added. Deferring bytes to queue buffer. 18:55:29.783 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 000002897779e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream6.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:29.786 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 00000289777a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream7.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:29.789 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 000002897779e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream8.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:31.864 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028977794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:31.866 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:33.378 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028977794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:33.378 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:34.901 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028977794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:34.918 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:34.918 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 000002897779e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream9.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:34.918 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 00000289777a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream10.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:34.919 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: frame= 479 fps= 55 q=-1.0 size= 16025kB time=00:00:15.96 bitrate=8222.4kbits/s speed=1.83x 18:55:34.923 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 000002897779e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream11.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:36.983 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028977794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:55:36.983 [StreamLogger-57] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028977792cc0] Skip ('#EXT-X-VERSION:3') 18:55:37.231 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:55:37.233 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|1789149166|6050|3282184244446|G:\Sage Recordings 64 G\PaperGlue-43162-1.ts|Great-H.264' 18:55:37.233 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Capture device was already locked. 18:55:37.234 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1789149166' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-1.ts'. 18:55:37.234 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Re-tune came back too fast. Skipping. 18:55:37.234 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:55:37.237 [InputStreamProducerImpl-52:Chan1] DEBUG InputStreamProducerImpl - Stream ended. 18:55:57.479 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - 'Chan1' produced first 22761912 packets. 18:56:13.606 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-1.ts', copy protection NONE. 18:56:13.606 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 22761912. 18:56:29.710 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-1.ts', copy protection NONE. 18:56:29.710 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 22761912. 18:56:37.273 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP Chan1 HDMI' 18:56:37.273 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Stopping encoding... 18:56:37.273 [SageTVRequestHandler-55:Chan1] DEBUG InputStreamCaptureDeviceServices - Stopping producer thread... 18:56:37.273 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:56:37.273 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-unlocked. 18:56:37.273 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:56:37.274 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|383566744|6050|3282184394548|G:\Sage Recordings 64 G\PaperGlue-43162-2.ts|Great-H.264' 18:56:37.274 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-locked. 18:56:37.274 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '383566744' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-2.ts'. 18:56:37.274 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:56:37.274 [SageTVRequestHandler-55:Chan1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '6050' 18:56:37.276 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Starting the encoding for the channel '6050' from the device 'Chan1' to the file 'G:\Sage Recordings 64 G\PaperGlue-43162-2.ts' via the upload id '383566744'... 18:56:37.276 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Executing: 'c:\FFMPEG\ffmpeg.exe -i http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8? -f mpegts -codec:v copy -codec:a copy - ' 18:56:37.278 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV producer... 18:56:37.278 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV consumer... 18:56:37.279 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /127.0.0.1:7818... 18:56:37.279 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN G:\Sage Recordings 64 G\PaperGlue-43162-2.ts 383566744' to SageTV server... 18:56:37.279 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:56:37.280 [FFmpegTransSageTVConsumerImpl-60:Chan1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 18:56:37.280 [FFmpegTransSageTVConsumerImpl-60:Chan1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 18:56:37.280 [FFmpegTransSageTVConsumerImpl-60:Chan1] ERROR ThreadPoolExecutor - Thread threw unhandled exception => java.lang.NullPointerException at opendct.consumer.FFmpegTransSageTVConsumerImpl.run(FFmpegTransSageTVConsumerImpl.java:176) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 18:56:37.432 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: ffmpeg version n4.4.1-2-gcc33e73618-20211203 Copyright (c) 2000-2021 the FFmpeg developers 18:56:37.432 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: built with gcc 10-win32 (GCC) 20210610 18:56:37.432 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: configuration: --prefix=/ffbuild/prefix --pkg-config-flags=--static --pkg-config=pkg-config --cross-prefix=x86_64-w64-mingw32- --arch=x86_64 --target-os=mingw32 --enable-gpl --enable-version3 --disable-debug --disable-w32threads --enable-pthreads --enable-iconv --enable-libxml2 --enable-zlib --enable-libfreetype --enable-libfribidi --enable-gmp --enable-lzma --enable-fontconfig --enable-libvorbis --enable-opencl --enable-libvmaf --disable-libxcb --disable-xlib --enable-amf --enable-libaom --enable-avisynth --enable-libdav1d --enable-libdavs2 --disable-libfdk-aac --enable-ffnvcodec --enable-cuda-llvm --disable-frei0r --enable-libgme --enable-libass --enable-libbluray --enable-libmp3lame --enable-libopus --enable-librist --enable-libtheora --enable-libvpx --enable-libwebp --enable-lv2 --enable-libmfx --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenh264 --enable-libopenjpeg --enable-librav1e --enable-librubberband --enable-schannel --enable-sdl2 --enable-libsoxr --enable-libsrt --enable-libsvtav1 --enable-libtwolame --enable-libuavs3d --disable-libdrm --disable-vaapi --enable-libvidstab --disable-vulkan --disable-libglslang --enable-libx264 --enable-libx265 --enable-libxavs2 --enable-libxvid --enable-libzimg --enable-libzvbi --extra-cflags=-DLIBTWOLAME_STATIC --extra-cxxflags= --extra-ldflags=-pthread --extra-ldexeflags= --extra-libs=-lgomp --extra-version=20211203 18:56:37.432 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libavutil 56. 70.100 / 56. 70.100 18:56:37.432 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libavcodec 58.134.100 / 58.134.100 18:56:37.432 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libavformat 58. 76.100 / 58. 76.100 18:56:37.433 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libavdevice 58. 13.100 / 58. 13.100 18:56:37.433 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libavfilter 7.110.100 / 7.110.100 18:56:37.433 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libswscale 5. 9.100 / 5. 9.100 18:56:37.433 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libswresample 3. 9.100 / 3. 9.100 18:56:37.433 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: libpostproc 55. 9.100 / 55. 9.100 18:56:44.629 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Skip ('#EXT-X-VERSION:3') 18:56:44.629 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:44.644 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Skip ('#EXT-X-VERSION:3') 18:56:44.644 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream3.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:44.660 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream4.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Input #0, hls, from 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8?': 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Duration: N/A, start: 4.023400, bitrate: N/A 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Program 0 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Output #0, mpegts, to 'pipe:': 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: encoder : Lavf58.76.100 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 29.97 tbr, 90k tbn, 90k tbc 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:56:44.702 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream mapping: 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream #0:0 -> #0:0 (copy) 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Stream #0:1 -> #0:1 (copy) 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: Press [q] to stop, [?] for help 18:56:44.703 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: frame= 0 fps=0.0 q=-1.0 size= 0kB time=00:00:00.00 bitrate=N/A speed= 0x 18:56:44.706 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de11e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream5.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:46.750 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de114000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:46.751 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Skip ('#EXT-X-VERSION:3') 18:56:48.260 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de114000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:48.266 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Skip ('#EXT-X-VERSION:3') 18:56:48.266 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de11e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream6.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:48.266 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de121200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream7.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:48.267 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: frame= 180 fps= 50 q=-1.0 size= 6216kB time=00:00:05.97 bitrate=8525.2kbits/s speed=1.68x 18:56:48.270 [InputStreamProducerImpl-52:Chan1] WARN SeekableCircularBufferNIO - The buffer has 7834336 bytes left to be read, has only 29983 bytes left for writing and 60536 bytes cannot be added. Deferring bytes to queue buffer. 18:56:48.271 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de11e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream8.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:50.318 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de114000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:50.325 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [hls @ 00000178de112cc0] Skip ('#EXT-X-VERSION:3') 18:56:50.325 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de11e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream9.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:50.325 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de121200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream10.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:50.327 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: frame= 359 fps= 64 q=-1.0 size= 11639kB time=00:00:12.01 bitrate=7938.6kbits/s speed=2.14x 18:56:50.330 [StreamLogger-59] DEBUG GenericPipeCaptureDevice - err: [http @ 00000178de11e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream11.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:56:52.282 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:56:52.283 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|383566744|6050|3282184394548|G:\Sage Recordings 64 G\PaperGlue-43162-2.ts|Great-H.264' 18:56:52.285 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Capture device was already locked. 18:56:52.285 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '383566744' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-2.ts'. 18:56:52.285 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Re-tune came back too fast. Skipping. 18:56:52.285 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:56:52.287 [InputStreamProducerImpl-52:Chan1] DEBUG InputStreamProducerImpl - Stream ended. 18:57:13.080 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - 'Chan1' produced first 18825192 packets. 18:57:29.214 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-2.ts', copy protection NONE. 18:57:29.214 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 18825192. 18:57:45.395 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-2.ts', copy protection NONE. 18:57:45.395 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 18825192. 18:57:52.338 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP Chan1 HDMI' 18:57:52.338 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Stopping encoding... 18:57:52.338 [SageTVRequestHandler-55:Chan1] DEBUG InputStreamCaptureDeviceServices - Stopping producer thread... 18:57:52.339 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:57:52.339 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-unlocked. 18:57:52.339 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:57:52.340 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|1921539304|6050|3282184544678|G:\Sage Recordings 64 G\PaperGlue-43162-3.ts|Great-H.264' 18:57:52.340 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-locked. 18:57:52.340 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1921539304' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-3.ts'. 18:57:52.340 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:57:52.340 [SageTVRequestHandler-55:Chan1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '6050' 18:57:52.341 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Starting the encoding for the channel '6050' from the device 'Chan1' to the file 'G:\Sage Recordings 64 G\PaperGlue-43162-3.ts' via the upload id '1921539304'... 18:57:52.341 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Executing: 'c:\FFMPEG\ffmpeg.exe -i http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8? -f mpegts -codec:v copy -codec:a copy - ' 18:57:52.344 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV producer... 18:57:52.344 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV consumer... 18:57:52.344 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /127.0.0.1:7818... 18:57:52.344 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN G:\Sage Recordings 64 G\PaperGlue-43162-3.ts 1921539304' to SageTV server... 18:57:52.345 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:57:52.345 [FFmpegTransSageTVConsumerImpl-62:Chan1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 18:57:52.345 [FFmpegTransSageTVConsumerImpl-62:Chan1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 18:57:52.345 [FFmpegTransSageTVConsumerImpl-62:Chan1] ERROR ThreadPoolExecutor - Thread threw unhandled exception => java.lang.NullPointerException at opendct.consumer.FFmpegTransSageTVConsumerImpl.run(FFmpegTransSageTVConsumerImpl.java:176) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: ffmpeg version n4.4.1-2-gcc33e73618-20211203 Copyright (c) 2000-2021 the FFmpeg developers 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: built with gcc 10-win32 (GCC) 20210610 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: configuration: --prefix=/ffbuild/prefix --pkg-config-flags=--static --pkg-config=pkg-config --cross-prefix=x86_64-w64-mingw32- --arch=x86_64 --target-os=mingw32 --enable-gpl --enable-version3 --disable-debug --disable-w32threads --enable-pthreads --enable-iconv --enable-libxml2 --enable-zlib --enable-libfreetype --enable-libfribidi --enable-gmp --enable-lzma --enable-fontconfig --enable-libvorbis --enable-opencl --enable-libvmaf --disable-libxcb --disable-xlib --enable-amf --enable-libaom --enable-avisynth --enable-libdav1d --enable-libdavs2 --disable-libfdk-aac --enable-ffnvcodec --enable-cuda-llvm --disable-frei0r --enable-libgme --enable-libass --enable-libbluray --enable-libmp3lame --enable-libopus --enable-librist --enable-libtheora --enable-libvpx --enable-libwebp --enable-lv2 --enable-libmfx --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenh264 --enable-libopenjpeg --enable-librav1e --enable-librubberband --enable-schannel --enable-sdl2 --enable-libsoxr --enable-libsrt --enable-libsvtav1 --enable-libtwolame --enable-libuavs3d --disable-libdrm --disable-vaapi --enable-libvidstab --disable-vulkan --disable-libglslang --enable-libx264 --enable-libx265 --enable-libxavs2 --enable-libxvid --enable-libzimg --enable-libzvbi --extra-cflags=-DLIBTWOLAME_STATIC --extra-cxxflags= --extra-ldflags=-pthread --extra-ldexeflags= --extra-libs=-lgomp --extra-version=20211203 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libavutil 56. 70.100 / 56. 70.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libavcodec 58.134.100 / 58.134.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libavformat 58. 76.100 / 58. 76.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libavdevice 58. 13.100 / 58. 13.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libavfilter 7.110.100 / 7.110.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libswscale 5. 9.100 / 5. 9.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libswresample 3. 9.100 / 3. 9.100 18:57:52.497 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: libpostproc 55. 9.100 / 55. 9.100 18:57:56.096 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:57:56.096 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:57:56.118 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:57:56.118 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream1.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:57:56.133 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream2.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Input #0, hls, from 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8?': 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Duration: N/A, start: 0.066733, bitrate: N/A 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Program 0 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Output #0, mpegts, to 'pipe:': 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: encoder : Lavf58.76.100 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 29.97 tbr, 90k tbn, 90k tbc 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream mapping: 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream #0:0 -> #0:0 (copy) 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Stream #0:1 -> #0:1 (copy) 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: Press [q] to stop, [?] for help 18:57:56.163 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: frame= 1 fps=0.0 q=-1.0 size= 0kB time=00:00:00.00 bitrate=N/A speed=N/A 18:57:58.218 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 0000023221794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:57:58.227 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:57:58.227 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 00000232217a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream3.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:57:58.228 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 000002322179e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream4.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:57:58.228 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: frame= 119 fps= 58 q=-1.0 size= 3354kB time=00:00:03.94 bitrate=6958.1kbits/s speed=1.91x 18:58:00.293 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 0000023221794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:00.306 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:58:00.306 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 000002322179e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream5.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:00.307 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 00000232217a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream6.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:00.307 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: frame= 239 fps= 58 q=-1.0 size= 7341kB time=00:00:07.95 bitrate=7556.0kbits/s speed=1.92x 18:58:00.308 [InputStreamProducerImpl-52:Chan1] WARN SeekableCircularBufferNIO - The buffer has 7859528 bytes left to be read, has only 4791 bytes left for writing and 66608 bytes cannot be added. Deferring bytes to queue buffer. 18:58:00.311 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 000002322179e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream7.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:02.358 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 0000023221794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:02.358 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:58:03.871 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 0000023221794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:03.871 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:58:05.375 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 0000023221794000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:05.382 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000023221792cc0] Skip ('#EXT-X-VERSION:3') 18:58:05.382 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 000002322179e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream8.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:05.383 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 00000232217a1200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream9.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:05.383 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: frame= 419 fps= 45 q=-1.0 size= 13324kB time=00:00:13.95 bitrate=7821.9kbits/s speed=1.51x 18:58:05.386 [StreamLogger-61] DEBUG GenericPipeCaptureDevice - err: [http @ 000002322179e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream10.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:58:07.353 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:58:07.354 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|1921539304|6050|3282184544678|G:\Sage Recordings 64 G\PaperGlue-43162-3.ts|Great-H.264' 18:58:07.355 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Capture device was already locked. 18:58:07.355 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1921539304' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-3.ts'. 18:58:07.355 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Re-tune came back too fast. Skipping. 18:58:07.355 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:58:07.358 [InputStreamProducerImpl-52:Chan1] DEBUG InputStreamProducerImpl - Stream ended. 18:58:27.737 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - 'Chan1' produced first 19732668 packets. 18:58:43.927 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-3.ts', copy protection NONE. 18:58:43.927 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 19732668. 18:59:00.106 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-3.ts', copy protection NONE. 18:59:00.106 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 19732668. 18:59:07.393 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP Chan1 HDMI' 18:59:07.393 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Stopping encoding... 18:59:07.393 [SageTVRequestHandler-55:Chan1] DEBUG InputStreamCaptureDeviceServices - Stopping producer thread... 18:59:07.393 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:59:07.393 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-unlocked. 18:59:07.393 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:59:07.395 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|1575679360|6050|3282184694788|G:\Sage Recordings 64 G\PaperGlue-43162-4.ts|Great-H.264' 18:59:07.395 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-locked. 18:59:07.395 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1575679360' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-4.ts'. 18:59:07.395 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:59:07.395 [SageTVRequestHandler-55:Chan1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '6050' 18:59:07.396 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Starting the encoding for the channel '6050' from the device 'Chan1' to the file 'G:\Sage Recordings 64 G\PaperGlue-43162-4.ts' via the upload id '1575679360'... 18:59:07.396 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Executing: 'c:\FFMPEG\ffmpeg.exe -i http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8? -f mpegts -codec:v copy -codec:a copy - ' 18:59:07.399 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV producer... 18:59:07.399 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV consumer... 18:59:07.399 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /127.0.0.1:7818... 18:59:07.399 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN G:\Sage Recordings 64 G\PaperGlue-43162-4.ts 1575679360' to SageTV server... 18:59:07.400 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 18:59:07.400 [FFmpegTransSageTVConsumerImpl-64:Chan1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 18:59:07.400 [FFmpegTransSageTVConsumerImpl-64:Chan1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 18:59:07.400 [FFmpegTransSageTVConsumerImpl-64:Chan1] ERROR ThreadPoolExecutor - Thread threw unhandled exception => java.lang.NullPointerException at opendct.consumer.FFmpegTransSageTVConsumerImpl.run(FFmpegTransSageTVConsumerImpl.java:176) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: ffmpeg version n4.4.1-2-gcc33e73618-20211203 Copyright (c) 2000-2021 the FFmpeg developers 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: built with gcc 10-win32 (GCC) 20210610 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: configuration: --prefix=/ffbuild/prefix --pkg-config-flags=--static --pkg-config=pkg-config --cross-prefix=x86_64-w64-mingw32- --arch=x86_64 --target-os=mingw32 --enable-gpl --enable-version3 --disable-debug --disable-w32threads --enable-pthreads --enable-iconv --enable-libxml2 --enable-zlib --enable-libfreetype --enable-libfribidi --enable-gmp --enable-lzma --enable-fontconfig --enable-libvorbis --enable-opencl --enable-libvmaf --disable-libxcb --disable-xlib --enable-amf --enable-libaom --enable-avisynth --enable-libdav1d --enable-libdavs2 --disable-libfdk-aac --enable-ffnvcodec --enable-cuda-llvm --disable-frei0r --enable-libgme --enable-libass --enable-libbluray --enable-libmp3lame --enable-libopus --enable-librist --enable-libtheora --enable-libvpx --enable-libwebp --enable-lv2 --enable-libmfx --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenh264 --enable-libopenjpeg --enable-librav1e --enable-librubberband --enable-schannel --enable-sdl2 --enable-libsoxr --enable-libsrt --enable-libsvtav1 --enable-libtwolame --enable-libuavs3d --disable-libdrm --disable-vaapi --enable-libvidstab --disable-vulkan --disable-libglslang --enable-libx264 --enable-libx265 --enable-libxavs2 --enable-libxvid --enable-libzimg --enable-libzvbi --extra-cflags=-DLIBTWOLAME_STATIC --extra-cxxflags= --extra-ldflags=-pthread --extra-ldexeflags= --extra-libs=-lgomp --extra-version=20211203 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libavutil 56. 70.100 / 56. 70.100 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libavcodec 58.134.100 / 58.134.100 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libavformat 58. 76.100 / 58. 76.100 18:59:07.553 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libavdevice 58. 13.100 / 58. 13.100 18:59:07.554 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libavfilter 7.110.100 / 7.110.100 18:59:07.554 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libswscale 5. 9.100 / 5. 9.100 18:59:07.554 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libswresample 3. 9.100 / 3. 9.100 18:59:07.554 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: libpostproc 55. 9.100 / 55. 9.100 18:59:12.167 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:12.167 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:12.190 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:12.190 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream1.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:12.191 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream2.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Input #0, hls, from 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8?': 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Duration: N/A, start: 0.066733, bitrate: N/A 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Program 0 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Output #0, mpegts, to 'pipe:': 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: encoder : Lavf58.76.100 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 29.97 tbr, 90k tbn, 90k tbc 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 18:59:12.199 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Metadata: 18:59:12.200 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 18:59:12.200 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream mapping: 18:59:12.200 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream #0:0 -> #0:0 (copy) 18:59:12.200 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Stream #0:1 -> #0:1 (copy) 18:59:12.200 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: Press [q] to stop, [?] for help 18:59:12.200 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: frame= 1 fps=0.0 q=-1.0 size= 0kB time=00:00:00.00 bitrate=N/A speed=N/A 18:59:14.248 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096334000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:14.250 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:15.758 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096334000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:15.766 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:15.766 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096341200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream3.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:15.766 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 000001409633e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream4.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:15.767 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: frame= 119 fps= 33 q=-1.0 size= 3952kB time=00:00:03.94 bitrate=8201.0kbits/s speed=1.11x 18:59:15.770 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096341200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream5.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:15.772 [InputStreamProducerImpl-52:Chan1] WARN SeekableCircularBufferNIO - The buffer has 7858024 bytes left to be read, has only 6295 bytes left for writing and 33840 bytes cannot be added. Deferring bytes to queue buffer. 18:59:17.836 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096334000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:17.846 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:17.846 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096341200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream6.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:17.847 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 000001409633e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream7.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:17.847 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: frame= 299 fps= 53 q=-1.0 size= 10282kB time=00:00:09.96 bitrate=8453.8kbits/s speed=1.76x 18:59:17.851 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096341200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream8.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:19.902 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096334000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:19.902 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:21.422 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096334000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:21.430 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000014096332cc0] Skip ('#EXT-X-VERSION:3') 18:59:21.430 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096341200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream9.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:21.431 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 000001409633e900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream10.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:21.431 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: frame= 479 fps= 52 q=-1.0 size= 15948kB time=00:00:15.95 bitrate=8186.8kbits/s speed=1.73x 18:59:21.436 [StreamLogger-63] DEBUG GenericPipeCaptureDevice - err: [http @ 0000014096341200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream11.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 18:59:22.404 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:59:22.405 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|1575679360|6050|3282184694788|G:\Sage Recordings 64 G\PaperGlue-43162-4.ts|Great-H.264' 18:59:22.405 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Capture device was already locked. 18:59:22.405 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1575679360' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-4.ts'. 18:59:22.405 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Re-tune came back too fast. Skipping. 18:59:22.405 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 18:59:22.406 [InputStreamProducerImpl-52:Chan1] DEBUG InputStreamProducerImpl - Stream ended. 18:59:42.496 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - 'Chan1' produced first 22396628 packets. 18:59:58.657 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-4.ts', copy protection NONE. 18:59:58.657 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 22396628. 19:00:14.827 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-4.ts', copy protection NONE. 19:00:14.827 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 22396628. 19:00:22.439 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP Chan1 HDMI' 19:00:22.439 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Stopping encoding... 19:00:22.439 [SageTVRequestHandler-55:Chan1] DEBUG InputStreamCaptureDeviceServices - Stopping producer thread... 19:00:22.439 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 19:00:22.439 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-unlocked. 19:00:22.439 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 19:00:22.440 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|2087972558|6050|3282184844880|G:\Sage Recordings 64 G\PaperGlue-43162-5.ts|Great-H.264' 19:00:22.440 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Capture device is now re-locked. 19:00:22.440 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '2087972558' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-5.ts'. 19:00:22.440 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 19:00:22.441 [SageTVRequestHandler-55:Chan1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '6050' 19:00:22.442 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Starting the encoding for the channel '6050' from the device 'Chan1' to the file 'G:\Sage Recordings 64 G\PaperGlue-43162-5.ts' via the upload id '2087972558'... 19:00:22.442 [SageTVRequestHandler-55:Chan1] DEBUG GenericPipeCaptureDevice - Executing: 'c:\FFMPEG\ffmpeg.exe -i http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8? -f mpegts -codec:v copy -codec:a copy - ' 19:00:22.445 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV producer... 19:00:22.445 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Configuring and starting the new SageTV consumer... 19:00:22.445 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Connecting to SageTV server on socket /127.0.0.1:7818... 19:00:22.445 [SageTVRequestHandler-55:Chan1] INFO NIOSageTVMediaServer - Sending 'WRITEOPEN G:\Sage Recordings 64 G\PaperGlue-43162-5.ts 2087972558' to SageTV server... 19:00:22.446 [SageTVRequestHandler-55:Chan1] DEBUG BasicCaptureDevice - Stopping consumer thread... 19:00:22.446 [FFmpegTransSageTVConsumerImpl-66:Chan1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 19:00:22.446 [FFmpegTransSageTVConsumerImpl-66:Chan1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 19:00:22.446 [FFmpegTransSageTVConsumerImpl-66:Chan1] ERROR ThreadPoolExecutor - Thread threw unhandled exception => java.lang.NullPointerException at opendct.consumer.FFmpegTransSageTVConsumerImpl.run(FFmpegTransSageTVConsumerImpl.java:176) at opendct.util.ThreadPool$1.run(ThreadPool.java:39) at java.util.concurrent.Executors$RunnableAdapter.call(Unknown Source) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: ffmpeg version n4.4.1-2-gcc33e73618-20211203 Copyright (c) 2000-2021 the FFmpeg developers 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: built with gcc 10-win32 (GCC) 20210610 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: configuration: --prefix=/ffbuild/prefix --pkg-config-flags=--static --pkg-config=pkg-config --cross-prefix=x86_64-w64-mingw32- --arch=x86_64 --target-os=mingw32 --enable-gpl --enable-version3 --disable-debug --disable-w32threads --enable-pthreads --enable-iconv --enable-libxml2 --enable-zlib --enable-libfreetype --enable-libfribidi --enable-gmp --enable-lzma --enable-fontconfig --enable-libvorbis --enable-opencl --enable-libvmaf --disable-libxcb --disable-xlib --enable-amf --enable-libaom --enable-avisynth --enable-libdav1d --enable-libdavs2 --disable-libfdk-aac --enable-ffnvcodec --enable-cuda-llvm --disable-frei0r --enable-libgme --enable-libass --enable-libbluray --enable-libmp3lame --enable-libopus --enable-librist --enable-libtheora --enable-libvpx --enable-libwebp --enable-lv2 --enable-libmfx --enable-libopencore-amrnb --enable-libopencore-amrwb --enable-libopenh264 --enable-libopenjpeg --enable-librav1e --enable-librubberband --enable-schannel --enable-sdl2 --enable-libsoxr --enable-libsrt --enable-libsvtav1 --enable-libtwolame --enable-libuavs3d --disable-libdrm --disable-vaapi --enable-libvidstab --disable-vulkan --disable-libglslang --enable-libx264 --enable-libx265 --enable-libxavs2 --enable-libxvid --enable-libzimg --enable-libzvbi --extra-cflags=-DLIBTWOLAME_STATIC --extra-cxxflags= --extra-ldflags=-pthread --extra-ldexeflags= --extra-libs=-lgomp --extra-version=20211203 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libavutil 56. 70.100 / 56. 70.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libavcodec 58.134.100 / 58.134.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libavformat 58. 76.100 / 58. 76.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libavdevice 58. 13.100 / 58. 13.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libavfilter 7.110.100 / 7.110.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libswscale 5. 9.100 / 5. 9.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libswresample 3. 9.100 / 3. 9.100 19:00:22.599 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: libpostproc 55. 9.100 / 55. 9.100 19:00:25.810 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:25.810 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:25.829 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:25.829 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream1.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:25.830 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream2.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Input #0, hls, from 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/master.m3u8?': 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Duration: N/A, start: 0.066733, bitrate: N/A 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Program 0 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Metadata: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Metadata: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Metadata: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Output #0, mpegts, to 'pipe:': 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Metadata: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: encoder : Lavf58.76.100 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream #0:0: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, 29.97 fps, 29.97 tbr, 90k tbn, 90k tbc 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Metadata: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream #0:1: Audio: aac (LC) ([15][0][0][0] / 0x000F), 48000 Hz, stereo, fltp 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Metadata: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: variant_bitrate : 9006000 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream mapping: 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream #0:0 -> #0:0 (copy) 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Stream #0:1 -> #0:1 (copy) 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: Press [q] to stop, [?] for help 19:00:25.859 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: frame= 1 fps=0.0 q=-1.0 size= 0kB time=00:00:00.00 bitrate=N/A speed= 0x 19:00:27.924 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37af4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:27.925 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:29.443 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37af4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:29.444 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:30.964 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37af4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:30.987 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:30.987 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37b01200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream3.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:30.987 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37afe900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream4.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:30.988 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: frame= 119 fps= 23 q=-1.0 size= 3928kB time=00:00:03.93 bitrate=8167.6kbits/s speed=0.768x 19:00:30.993 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37b01200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream5.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:30.996 [InputStreamProducerImpl-52:Chan1] WARN SeekableCircularBufferNIO - The buffer has 7833396 bytes left to be read, has only 30923 bytes left for writing and 33840 bytes cannot be added. Deferring bytes to queue buffer. 19:00:30.997 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37afe900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream6.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:31.002 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37b01200] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream7.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:31.005 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37afe900] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8/stream8.ts?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:33.063 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37af4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:33.064 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:34.574 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37af4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:34.575 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:36.090 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [http @ 0000028a37af4000] Opening 'http://192.168.1.143:8089/devices/ANY/channels/6050/hls/stream.m3u8?acodec=copy&bitrate=9006&indexed=true&resolution=1080&ssize=1&vcodec=copy' for reading 19:00:36.090 [StreamLogger-65] DEBUG GenericPipeCaptureDevice - err: [hls @ 0000028a37af2cc0] Skip ('#EXT-X-VERSION:3') 19:00:37.451 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 19:00:37.452 [SageTVRequestHandler-55:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START Chan1 HDMI|2087972558|6050|3282184844880|G:\Sage Recordings 64 G\PaperGlue-43162-5.ts|Great-H.264' 19:00:37.452 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Capture device was already locked. 19:00:37.453 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '2087972558' to file name 'G:\Sage Recordings 64 G\PaperGlue-43162-5.ts'. 19:00:37.453 [SageTVRequestHandler-55:Chan1] INFO GenericPipeCaptureDevice - Re-tune came back too fast. Skipping. 19:00:37.453 [SageTVRequestHandler-55:Chan1] DEBUG SageTVRequestHandler - Replied: 'OK' 19:00:37.456 [InputStreamProducerImpl-52:Chan1] DEBUG InputStreamProducerImpl - Stream ended. 19:00:58.142 [SageTVTuningMonitor-47] INFO SageTVTuningMonitor - 'Chan1' produced first 16239816 packets. 19:01:14.307 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-5.ts', copy protection NONE. 19:01:14.307 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 16239816. 19:01:30.474 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name 'G:\Sage Recordings 64 G\PaperGlue-43162-5.ts', copy protection NONE. 19:01:30.474 [SageTVTuningMonitor-47] DEBUG SageTVTuningMonitor - The producer appears to be stuck at 16239816.