22:50:03.968 [WrapperSimpleAppMain] INFO Main - Starting OpenDCT 0.5.3... 22:50:04.296 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:04.576 [WrapperSimpleAppMain] INFO Main - OpenDCT logging to the directory 'c:/ProgramData/OpenDCT/logs'. 22:50:04.639 [WrapperSimpleAppMain] INFO UpnpManager - Logging Cling UPnP to 'c:/ProgramData/OpenDCT/logs\opendct_cling.log'. 22:50:04.639 [WrapperSimpleAppMain] DEBUG UpnpManager - Logging level for Cling UPnP is set to 'severe'. 22:50:04.654 [FFmpegAsyncInit-20] INFO Main - FFmpeg loading... 22:50:05.247 [WrapperSimpleAppMain] INFO NetworkPowerEventManger - Network interfaces which are up and have an IP4 address are: name:eth3 (Realtek PCIe GBE Family Controller) 192.168.1.14 22:50:05.247 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.power.NetworkPowerEventManger' is now listening. 22:50:05.247 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.sagetv.SageTVManager' is now listening. 22:50:05.341 [SageTVDevicesLoaded-26] INFO SageTVDevicesLoaded - Waiting for all capture devices to become available... 22:50:05.403 [WrapperSimpleAppMain] INFO Main - Early port assignment is enabled. 22:50:05.419 [WrapperSimpleAppMain] DEBUG SageTVSocketServer - Setting listening flag... 22:50:05.419 [SageTVTuningMonitor-27] INFO SageTVTuningMonitor - Tuning monitor thread started. 22:50:05.419 [WrapperSimpleAppMain] INFO SageTVSocketServer - Opening ServerSocket on port 9000... 22:50:05.434 [SageTVSocketServer-28:9000] INFO SageTVSocketServer - Started listening on port 9000... 22:50:05.450 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:05.450 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:06.168 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:06.168 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:06.168 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:06.168 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Windows 7' is WINDOWS. 22:50:06.214 [WrapperSimpleAppMain] INFO UpnpManager - Starting UPnP services... 22:50:06.651 [WrapperSimpleAppMain] INFO DCTDefaultUpnpServiceConfiguration - Using the interface 'eth3' with IP address 192.168.1.14 for UPnP discovery. 22:50:06.932 [WrapperSimpleAppMain] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:06.979 [UPnPDiscovery-36] INFO UpnpManager - UPnP discovery thread has started. 22:50:07.384 [HDHomeRunDiscoverySend-37] INFO HDHomeRunDiscovery - HDHomeRun discovery sender thread started. 22:50:07.384 [HDHomeRunDiscoverySend-37] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... 22:50:07.400 [HDHomeRunDiscoveryReceive-37] INFO HDHomeRunDiscovery - HDHomeRun discovery receive thread for /192.168.1.255:65001 broadcast started. 22:50:07.416 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.tuning.discovery.DiscoveryManager' is now listening. 22:50:07.416 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:07.431 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:07.447 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:07.462 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunDiscovery - HDHomerun device returned an unknown tag with the length 34 22:50:07.462 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/sys/hwmodel' value: 'null' lockKey: '0' sendLength: 23 22:50:07.478 [HDHomeRunDiscoveryReceive-37] INFO HDHomeRunDiscoverer - Discovered a new HDHomeRun device 'HDHomeRun DRI Tuner 1319F1B6'. 22:50:07.603 [HDHomeRunDiscoverySend-37] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... 22:50:07.806 [HDHomeRunDiscoverySend-37] INFO HDHomeRunDiscovery - Broadcasting HDHomeRun discovery packet to /192.168.1.255:65001... 22:50:07.899 [HDHomeRunDiscoveryReceive-37] INFO DynamicConsumerImpl - Dynamic consumer default set to use opendct.consumer.FFmpegTransSageTVConsumerImpl 22:50:07.915 [HDHomeRunDiscoveryReceive-37] INFO DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.FFmpegTransSageTVConsumerImpl for [] 22:50:07.915 [HDHomeRunDiscoveryReceive-37] INFO DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.RawSageTVConsumerImpl for [] 22:50:07.915 [HDHomeRunDiscoveryReceive-37] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 22:50:07.930 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:07.962 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/sys/model' value: 'null' lockKey: '0' sendLength: 21 22:50:07.977 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/sys/version' value: 'null' lockKey: '0' sendLength: 23 22:50:08.008 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/sys/features' value: 'null' lockKey: '0' sendLength: 24 22:50:08.024 [HDHomeRunDiscoveryReceive-37] DEBUG HDHRNativeCaptureDevice - HDHomeRun details: HDHR3-CC, hdhomerun3_cablecard, 20160328atest2, HDHomeRunFeatures{channelmap=[us-cable, us-hrc, us-irc, kr-cable], modulation=[auto, auto6c, qam], autoModulation=[]} 22:50:08.024 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: 'help' value: 'null' lockKey: '0' sendLength: 15 22:50:08.040 [HDHomeRunDiscoveryReceive-37] DEBUG HDHRNativeCaptureDevice - HDHomeRun help: [Supported configuration options:, /card/status, /ir/target ://:, /lineup/scan, /oob/channel :, /oob/debug, /oob/status, /sys/copyright, /sys/debug, /sys/features, /sys/hwmodel, /sys/model, /sys/restart , /sys/version, /tuner/channel :, /tuner/channelmap , /tuner/debug, /tuner/filter "0x-0x [...]", /tuner/lockkey, /tuner/program , /tuner/streaminfo, /tuner/status, /tuner/target :, /tuner/vchannel , /tuner/vstatus] 22:50:08.040 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/card/status' value: 'null' lockKey: '0' sendLength: 23 22:50:08.055 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/card/status' value: 'null' lockKey: '0' sendLength: 23 22:50:08.086 [HDHomeRunDiscoveryReceive-37] DEBUG RTPCaptureDeviceServices - Initializing RTSP client... 22:50:08.086 [HDHomeRunDiscoveryReceive-37] DEBUG RTPCaptureDeviceServices - Getting a port for incoming RTP data... 22:50:08.086 [HDHomeRunDiscoveryReceive-37] INFO HDHRNativeCaptureDevice - Encoder Manufacturer: 'Silicondust', Number: 0, Remote IP: '/192.168.1.21', Local IP: '192.168.1.14', CableCARD: true, Lineup: 'dct_hdhomerun', Offline Scan Enabled: false, RTP Port: 8370 22:50:08.086 [HDHomeRunDiscoveryReceive-37] INFO SageTVManager - The capture device 'DCT-HDHRun 1319F1B6-0' is ready. 22:50:08.398 [HDHomeRunDiscoveryReceive-37] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 22:50:08.414 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:08.430 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/sys/version' value: 'null' lockKey: '0' sendLength: 23 22:50:08.445 [HDHomeRunDiscoveryReceive-37] DEBUG HDHRNativeCaptureDevice - HDHomeRun details: HDHR3-CC, hdhomerun3_cablecard, 20160328atest2, HDHomeRunFeatures{channelmap=[us-cable, us-hrc, us-irc, kr-cable], modulation=[auto, auto6c, qam], autoModulation=[]} 22:50:08.445 [HDHomeRunDiscoveryReceive-37] DEBUG HDHRNativeCaptureDevice - HDHomeRun help: [Supported configuration options:, /card/status, /ir/target ://:, /lineup/scan, /oob/channel :, /oob/debug, /oob/status, /sys/copyright, /sys/debug, /sys/features, /sys/hwmodel, /sys/model, /sys/restart , /sys/version, /tuner/channel :, /tuner/channelmap , /tuner/debug, /tuner/filter "0x-0x [...]", /tuner/lockkey, /tuner/program , /tuner/streaminfo, /tuner/status, /tuner/target :, /tuner/vchannel , /tuner/vstatus] 22:50:08.445 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/card/status' value: 'null' lockKey: '0' sendLength: 23 22:50:08.461 [HDHomeRunDiscoveryReceive-37] DEBUG RTPCaptureDeviceServices - Initializing RTSP client... 22:50:08.461 [HDHomeRunDiscoveryReceive-37] DEBUG RTPCaptureDeviceServices - Getting a port for incoming RTP data... 22:50:08.461 [HDHomeRunDiscoveryReceive-37] INFO HDHRNativeCaptureDevice - Encoder Manufacturer: 'Silicondust', Number: 1, Remote IP: '/192.168.1.21', Local IP: '192.168.1.14', CableCARD: true, Lineup: 'dct_hdhomerun', Offline Scan Enabled: false, RTP Port: 8484 22:50:08.461 [HDHomeRunDiscoveryReceive-37] INFO SageTVManager - The capture device 'DCT-HDHRun 1319F1B6-1' is ready. 22:50:08.773 [HDHomeRunDiscoveryReceive-37] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '' 22:50:08.804 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:08.820 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/sys/version' value: 'null' lockKey: '0' sendLength: 23 22:50:08.835 [HDHomeRunDiscoveryReceive-37] DEBUG HDHRNativeCaptureDevice - HDHomeRun details: HDHR3-CC, hdhomerun3_cablecard, 20160328atest2, HDHomeRunFeatures{channelmap=[us-cable, us-hrc, us-irc, kr-cable], modulation=[auto, auto6c, qam], autoModulation=[]} 22:50:08.835 [HDHomeRunDiscoveryReceive-37] DEBUG HDHRNativeCaptureDevice - HDHomeRun help: [Supported configuration options:, /card/status, /ir/target ://:, /lineup/scan, /oob/channel :, /oob/debug, /oob/status, /sys/copyright, /sys/debug, /sys/features, /sys/hwmodel, /sys/model, /sys/restart , /sys/version, /tuner/channel :, /tuner/channelmap , /tuner/debug, /tuner/filter "0x-0x [...]", /tuner/lockkey, /tuner/program , /tuner/streaminfo, /tuner/status, /tuner/target :, /tuner/vchannel , /tuner/vstatus] 22:50:08.835 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/card/status' value: 'null' lockKey: '0' sendLength: 23 22:50:08.851 [HDHomeRunDiscoveryReceive-37] DEBUG RTPCaptureDeviceServices - Initializing RTSP client... 22:50:08.851 [HDHomeRunDiscoveryReceive-37] DEBUG RTPCaptureDeviceServices - Getting a port for incoming RTP data... 22:50:08.851 [HDHomeRunDiscoveryReceive-37] INFO HDHRNativeCaptureDevice - Encoder Manufacturer: 'Silicondust', Number: 2, Remote IP: '/192.168.1.21', Local IP: '192.168.1.14', CableCARD: true, Lineup: 'dct_hdhomerun', Offline Scan Enabled: false, RTP Port: 8416 22:50:08.851 [HDHomeRunDiscoveryReceive-37] INFO SageTVManager - The capture device 'DCT-HDHRun 1319F1B6-2' is ready. 22:50:08.851 [HDHomeRunDiscoveryReceive-37] DEBUG SageTVDevicesLoaded - Interrupting the device detection timeout thread... 22:50:08.851 [SageTVDevicesLoaded-26] INFO SageTVDevicesLoaded - All required capture devices are now available. 22:50:08.866 [WrapperSimpleAppMain] DEBUG WindowsPowerMessagePump - 'class opendct.channel.ChannelManager' is now listening. 22:50:08.929 [WrapperSimpleAppMain] INFO Main - Running in daemon mode... 22:50:09.256 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:09.272 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:09.303 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:09.319 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunDiscovery - HDHomerun device returned an unknown tag with the length 34 22:50:09.319 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner0/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:09.350 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:09.366 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:50:09.381 [HDHomeRunDiscoveryReceive-37] DEBUG HDHomeRunDiscovery - HDHomerun device returned an unknown tag with the length 34 22:50:10.988 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:14.248 [WindowsPowerMessagePump] INFO WindowsPowerMessagePump - Message pump started. 22:50:14.997 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:19.006 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:23.016 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:27.025 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:31.034 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:31.861 [SageTVDiscovery-53] DEBUG SageTVDiscovery - Received discovery datagram from SageTV server '/192.168.1.14:49760' and validating... 22:50:31.861 [SageTVDiscovery-53] DEBUG SageTVDiscovery - Validated discovery datagram from SageTV server '/192.168.1.14:49760' and preparing response... 22:50:31.861 [SageTVDiscovery-53] INFO SageTVDiscovery - Sent discovery response datagram to SageTV server '/192.168.1.14:49760'. 22:50:34.918 [SageTVRequestHandler-76:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'PROPERTIES' 22:50:34.918 [SageTVRequestHandler-76:Unknown-9000] INFO SageTVRequestHandler - Sent PROPERTIES. 22:50:34.918 [SageTVRequestHandler-76:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'QUIT' 22:50:35.043 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:39.052 [UPnPDiscovery-36] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices... 22:50:39.052 [UPnPDiscovery-36] DEBUG UpnpManager - Stopping broadcast. 22:50:39.052 [UPnPDiscovery-36] DEBUG DiscoveryRegistryListener - Before shutdown, the registry has 1 device(s). 22:50:39.052 [UPnPDiscovery-36] DEBUG DiscoveryRegistryListener - Shutdown of UPnP registry complete. 22:50:40.659 [SageTVSocketServer-28:9000] WARN SageTVSocketServer - IPv6 connection detected. This is an untested configuration. 22:50:40.659 [SageTVRequestHandler-77:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION' 22:50:40.659 [SageTVRequestHandler-77:Unknown-9000] DEBUG SageTVRequestHandler - Replied: '3.0' 22:50:40.675 [SageTVRequestHandler-78:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION' 22:50:40.675 [SageTVRequestHandler-78:Unknown-9000] DEBUG SageTVRequestHandler - Replied: '3.0' 22:50:40.706 [SageTVRequestHandler-79:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION' 22:50:40.706 [SageTVRequestHandler-79:Unknown-9000] DEBUG SageTVRequestHandler - Replied: '3.0' 22:50:46.322 [FFmpegAsyncInit-20] INFO Main - FFmpeg loaded in 41668ms. 22:51:38.880 [ChannelManager-60] INFO ChannelManager - Updating the HDHomeRun channel lineup HDHomeRun DRI Tuner 1319F1B6 (dct_hdhomerun). 22:51:38.882 [ChannelManager-60] INFO HDHomeRunChannels - Connecting to Prime DCT using the URL 'http://192.168.1.21:80/lineup.xml' 22:51:38.916 [ChannelManager-60] INFO ChannelManager - The static channel lineup HDHomeRun DRI Tuner 1319F1B6 (dct_prime) will remain unchanged. 22:53:07.823 [SageTVRequestHandler-80:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'START DCT-HDHRun 1319F1B6-1 Digital TV Tuner|1325014069|808|2943841575610|I:\Recorded TV\BaseballTonight-20849678-0.ts|Great' 22:53:07.823 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Capture device is now locked. 22:53:07.823 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - Starting network encoder to file name 'I:\Recorded TV\BaseballTonight-20849678-0.ts'. 22:53:07.823 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Starting the encoding for the channel '808' from the device 'DCT-HDHRun 1319F1B6-1' to the file 'I:\Recorded TV\BaseballTonight-20849678-0.ts'... 22:53:07.824 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'force' lockKey: '-1' sendLength: 40 22:53:08.827 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:53:08.829 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:53:08.831 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: '1062731506' lockKey: '1062731506' sendLength: 45 22:53:08.834 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now locked. 22:53:08.834 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Producer was not running. 22:53:08.834 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Consumer is was not in progress. 22:53:08.836 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '808' 22:53:08.853 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/vchannel' value: '808' lockKey: '1062731506' sendLength: 39 22:53:08.863 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Configuring and starting the new RTP producer... 22:53:08.863 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Producer was not running. 22:53:08.865 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'rtp://192.168.1.14:8484' lockKey: '1062731506' sendLength: 57 22:53:08.865 [NIORTPProducerImpl-87:DCT-HDHRun 1319F1B6-1] INFO NIORTPProducerImpl - Producer thread is running. 22:53:08.867 [NIORTPProducerImpl-87:DCT-HDHRun 1319F1B6-1] DEBUG NIORTPProducerImpl - Thread priority is 9. 22:53:08.867 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/program' value: 'null' lockKey: '0' sendLength: 26 22:53:08.869 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Configuring and starting the new SageTV consumer... 22:53:08.880 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Consumer is was not in progress. 22:53:08.881 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 22:53:08.881 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 22:53:08.881 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 22:53:08.941 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegStreamDetection - Calling avformat_open_input 22:53:09.548 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - Before avformat_find_stream_info() pos=92120 bytes_read=92120 seek_count=0. probesize: 165628 analyzeduration: 2000000. 22:53:10.156 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1641052 22:53:10.156 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1641052, returnValue = 1666057 22:53:10.156 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1641052 22:53:10.156 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1641052, returnValue = 1667373 22:53:10.156 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1417373, readIndex = 1641052 22:53:10.156 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1417373, readIndex = 1417373, returnValue = 1417373 22:53:10.257 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1167373, readIndex = 1923992 22:53:10.257 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1167373, readIndex = 1167373, returnValue = 1167373 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1691661 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1691661, returnValue = 1927941 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 1691661 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 0, returnValue = 0 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0, returnValue = 1927941 22:53:10.258 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - After avformat_find_stream_info() pos=0 bytes_read=2671959 seek_count=3. probesize: 165628 analyzeduration: 2000000. 22:53:10.259 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - current container duration = 0.758155 seconds 22:53:10.276 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegStreamDetection - FFmpeg stream detection done in 1336ms, 22:53:10.276 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegTranscoder - Initializing FFmpeg transcoder stream output. 22:53:10.293 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegContext - Primary: Input #0, mpegts, from 'input-mpeg-ts.ts': Duration: 00:00:00.76, start: 35068.594689, bitrate: 20343 kb/s Program 1 Stream #0:0[0xfca], 9, 1/90000: Video: mpeg2video (Main) ([128][0][0][0] / 0x0080), yuv420p(tv), 1280x720 [SAR 1:1 DAR 16:9], Closed Captions, max. 25000 kb/s, 59.94 fps, 60 tbr, 90k tbn, 119.88 tbc Stream #0:1[0xfcb](eng), 5, 1/90000: Audio: ac3 (AC-3 / 0x332D4341), 48000 Hz, 5.1(side), fltp, 448 kb/s Stream #0:2[0xfcc], 0, 1/90000: Unknown: none ([134][0][0][0] / 0x0086) Stream #0:3[0xfcd], 0, 1/90000: Unknown: none (ETV1 / 0x31565445) Stream #0:4[0xfce], 0, 1/90000: Unknown: none (ETV1 / 0x31565445) 22:53:10.293 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - Calling avformat_alloc_output_context2 22:53:10.304 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegContext - Primary: Output #0, mpegts, to 'I:\Recorded TV\BaseballTonight-20849678-0.ts': Stream #0:0, 0, 1/0: Video: mpeg2video, yuv420p(tv), 1280x720 [SAR 1:1 DAR 16:9], q=2-31, max. 25000 kb/s, 119.88 tbc Stream #0:1(eng), 0, 1/0: Audio: ac3, 48000 Hz, 5.1(side), fltp, 448 kb/s 22:53:10.304 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegTranscoder - Writing header 22:53:10.305 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegTranscoder - Initialized FFmpeg transcoder stream output. 22:53:10.384 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 22:53:12.675 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - Replied: 'OK' 22:53:13.594 [SageTVTuningMonitor-27] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 22:53:17.663 [SageTVRequestHandler-80:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP DCT-HDHRun 1319F1B6-1 Digital TV Tuner' 22:53:17.663 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHRNativeCaptureDevice - Stopping encoding... 22:53:17.663 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Stopping producer thread... 22:53:17.663 [NIORTPProducerImpl-87:DCT-HDHRun 1319F1B6-1] DEBUG NIORTPProducerImpl - Producer was closed by an asynchronous close exception => null 22:53:17.663 [NIORTPProducerImpl-87:DCT-HDHRun 1319F1B6-1] INFO NIORTPProducerImpl - Producer thread has disconnected. 22:53:17.663 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Waiting for producer thread to stop... 22:53:17.663 [NIORTPProducerImpl-87:DCT-HDHRun 1319F1B6-1] INFO NIORTPProducerImpl - Producer thread has stopped. 22:53:17.663 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Stopping consumer thread... 22:53:17.663 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Waiting for consumer thread to stop... 22:53:17.664 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - FFmpeg consumer was interrupted while reading. 22:53:17.664 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegContext - Returning AVERROR_EOF in readCallback.call() 22:53:17.664 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] WARN mpegts - [03f89a60] PES packet size mismatch 22:53:17.668 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegTranscoder - FFmpeg transcoder ended with code 0 22:53:17.668 [AsyncWriter-90:BaseballTonight-20849678-0.ts] INFO FFmpegTransSageTVConsumerImpl - Closing the file 'I:\Recorded TV\BaseballTonight-20849678-0.ts' 22:53:17.669 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - avcodec_close 22:53:17.669 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - avio_closep 22:53:17.669 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - avformat_free_context 22:53:17.669 [FFmpegTransSageTVConsumerImpl-91:DCT-HDHRun 1319F1B6-1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread stopped. 22:53:17.669 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/channel' value: 'none' lockKey: '1062731506' sendLength: 39 22:53:17.672 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'none' lockKey: '1062731506' sendLength: 38 22:53:17.674 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'force' lockKey: '1062731506' sendLength: 40 22:53:17.675 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:53:17.677 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now unlocked. 22:53:17.678 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Capture device is now unlocked. 22:53:17.678 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - Replied: 'OK' 22:53:17.805 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - SageTV sent: 'START DCT-HDHRun 1319F1B6-1 Digital TV Tuner|471723263|781|2943841595578|I:\Recorded TV\MajorCrimes-S05E09-FamilyLaw-20850061-0.ts|Great' 22:53:17.805 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Capture device is now locked. 22:53:17.805 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - Starting network encoder to file name 'I:\Recorded TV\MajorCrimes-S05E09-FamilyLaw-20850061-0.ts'. 22:53:17.805 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Starting the encoding for the channel '781' from the device 'DCT-HDHRun 1319F1B6-1' to the file 'I:\Recorded TV\MajorCrimes-S05E09-FamilyLaw-20850061-0.ts'... 22:53:17.805 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'force' lockKey: '-1' sendLength: 40 22:53:17.808 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:53:17.810 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:53:17.811 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: '1062731506' lockKey: '1062731506' sendLength: 45 22:53:17.814 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now locked. 22:53:17.814 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Producer was not running. 22:53:17.814 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Consumer is was not in progress. 22:53:17.814 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '781' 22:53:17.814 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/vchannel' value: '781' lockKey: '1062731506' sendLength: 39 22:53:17.823 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Configuring and starting the new RTP producer... 22:53:17.823 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Producer was not running. 22:53:17.825 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'rtp://192.168.1.14:8484' lockKey: '1062731506' sendLength: 57 22:53:17.826 [NIORTPProducerImpl-106:DCT-HDHRun 1319F1B6-1] INFO NIORTPProducerImpl - Producer thread is running. 22:53:17.827 [NIORTPProducerImpl-106:DCT-HDHRun 1319F1B6-1] DEBUG NIORTPProducerImpl - Thread priority is 9. 22:53:17.827 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/program' value: 'null' lockKey: '0' sendLength: 26 22:53:17.829 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Configuring and starting the new SageTV consumer... 22:53:17.858 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Consumer is was not in progress. 22:53:17.860 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 22:53:17.860 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 22:53:17.860 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 22:53:17.862 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegStreamDetection - Calling avformat_open_input 22:53:18.363 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - Before avformat_find_stream_info() pos=31584 bytes_read=31584 seek_count=0. probesize: 165628 analyzeduration: 2000000. 22:53:19.362 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 22:53:19.929 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO mpegts - [03f88f40] decoding for stream 0 failed 22:53:19.933 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1609468 22:53:19.933 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1609468, returnValue = 1635789 22:53:19.934 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1609468 22:53:19.934 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1609468, returnValue = 1635789 22:53:19.934 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1385789, readIndex = 1609468 22:53:19.934 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1385789, readIndex = 1385789, returnValue = 1385789 22:53:19.984 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1720012 22:53:19.984 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1720012, returnValue = 1720013 22:53:19.984 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 1720012 22:53:19.984 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 0, returnValue = 0 22:53:19.984 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0 22:53:19.984 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0, returnValue = 1720013 22:53:19.985 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - After avformat_find_stream_info() pos=0 bytes_read=1943691 seek_count=2. probesize: 165628 analyzeduration: 2000000. 22:53:19.985 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - current container duration = 2.172922 seconds 22:53:19.985 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegUtil - Video stream 0 has no width or no height. 22:53:19.985 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - Desired program set. Stream details unavailable for one or more streams. Trying again with an extended probe. 22:53:19.985 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegStreamDetection - Calling avformat_open_input 22:53:19.986 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - Before avformat_find_stream_info() pos=262144 bytes_read=262144 seek_count=0. probesize: 1720200 analyzeduration: 3172922. 22:53:20.406 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 2214828 22:53:20.406 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 2214828, returnValue = 2217461 22:53:20.407 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 2214828 22:53:20.407 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 2214828, returnValue = 2218777 22:53:20.407 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1968777, readIndex = 2214828 22:53:20.407 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1968777, readIndex = 1968777, returnValue = 1968777 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 2309580 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 2309580, returnValue = 2309581 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 2309580 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 0, returnValue = 0 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0, returnValue = 2309581 22:53:20.482 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - After avformat_find_stream_info() pos=0 bytes_read=2555631 seek_count=2. probesize: 1720200 analyzeduration: 3172922. 22:53:20.483 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegStreamDetection - current container duration = 2.673422 seconds 22:53:20.483 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegStreamDetection - FFmpeg stream detection done in 2621ms, 22:53:20.483 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegTranscoder - Initializing FFmpeg transcoder stream output. 22:53:20.484 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegContext - Primary: Input #0, mpegts, from 'input-mpeg-ts.ts': Duration: 00:00:02.67, start: 35077.573656, bitrate: 6911 kb/s Program 3 Stream #0:0[0x1014], 49, 1/90000: Video: mpeg2video (Main) ([128][0][0][0] / 0x0080), yuv420p(tv), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, max. 25000 kb/s, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc Stream #0:1[0x1015](eng), 50, 1/90000: Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, 5.1(side), fltp, 448 kb/s Stream #0:2[0x1016](spa), 50, 1/90000: Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s (visual impaired) Stream #0:3[0x1017], 0, 1/90000: Unknown: none ([134][0][0][0] / 0x0086) Stream #0:4[0x1018], 0, 1/90000: Unknown: none (ETV1 / 0x31565445) Stream #0:5[0x1019], 0, 1/90000: Unknown: none (ETV1 / 0x31565445) 22:53:20.484 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - Calling avformat_alloc_output_context2 22:53:20.485 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegContext - Primary: Output #0, mpegts, to 'I:\Recorded TV\MajorCrimes-S05E09-FamilyLaw-20850061-0.ts': Stream #0:0, 0, 1/0: Video: mpeg2video, yuv420p(tv), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, max. 25000 kb/s, 59.94 tbc Stream #0:1(eng), 0, 1/0: Audio: ac3, 48000 Hz, 5.1(side), fltp, 448 kb/s Stream #0:2(spa), 0, 1/0: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s 22:53:20.485 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegTranscoder - Writing header 22:53:20.485 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegTranscoder - Initialized FFmpeg transcoder stream output. 22:53:24.367 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - Replied: 'OK' 22:53:24.596 [SageTVTuningMonitor-27] DEBUG HDHomeRunControl - key: '/tuner1/vstatus' value: 'null' lockKey: '0' sendLength: 26 22:53:34.337 [SageTVRequestHandler-80:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP DCT-HDHRun 1319F1B6-1 Digital TV Tuner' 22:53:34.337 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHRNativeCaptureDevice - Stopping encoding... 22:53:34.337 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Stopping producer thread... 22:53:34.338 [NIORTPProducerImpl-106:DCT-HDHRun 1319F1B6-1] DEBUG NIORTPProducerImpl - Producer was closed by an asynchronous close exception => null 22:53:34.338 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG RTPCaptureDeviceServices - Waiting for producer thread to stop... 22:53:34.338 [NIORTPProducerImpl-106:DCT-HDHRun 1319F1B6-1] INFO NIORTPProducerImpl - Producer thread has disconnected. 22:53:34.338 [NIORTPProducerImpl-106:DCT-HDHRun 1319F1B6-1] INFO NIORTPProducerImpl - Producer thread has stopped. 22:53:34.338 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Stopping consumer thread... 22:53:34.338 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG BasicCaptureDevice - Waiting for consumer thread to stop... 22:53:34.338 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - FFmpeg consumer was interrupted while reading. 22:53:34.338 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegContext - Returning AVERROR_EOF in readCallback.call() 22:53:34.339 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] WARN mpegts - [3dcf4a00] PES packet size mismatch 22:53:34.340 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegTranscoder - FFmpeg transcoder ended with code 0 22:53:34.340 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - avcodec_close 22:53:34.340 [AsyncWriter-109:MajorCrimes-S05E09-FamilyLaw-20850061-0.ts] INFO FFmpegTransSageTVConsumerImpl - Closing the file 'I:\Recorded TV\MajorCrimes-S05E09-FamilyLaw-20850061-0.ts' 22:53:34.340 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - avio_closep 22:53:34.340 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] DEBUG FFmpegContext - avformat_free_context 22:53:34.341 [FFmpegTransSageTVConsumerImpl-110:DCT-HDHRun 1319F1B6-1] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread stopped. 22:53:34.341 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/channel' value: 'none' lockKey: '1062731506' sendLength: 39 22:53:34.343 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/target' value: 'none' lockKey: '1062731506' sendLength: 38 22:53:34.345 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'force' lockKey: '1062731506' sendLength: 40 22:53:34.347 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG HDHomeRunControl - key: '/tuner1/lockkey' value: 'null' lockKey: '0' sendLength: 26 22:53:34.349 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - HDHomeRun is now unlocked. 22:53:34.349 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] INFO HDHRNativeCaptureDevice - Capture device is now unlocked. 22:53:34.349 [SageTVRequestHandler-80:DCT-HDHRun 1319F1B6-1] DEBUG SageTVRequestHandler - Replied: 'OK' 23:00:00.153 [SageTVRequestHandler-121:Unknown-9000] DEBUG SageTVRequestHandler - SageTV sent: 'START DCT-HDHRun 1319F1B6-2 Digital TV Tuner|575764927|781|2943842400254|I:\Recorded TV\RizzoliIsles-S07E11-Stiffed-20850059-0.ts|Great' 23:00:00.153 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] INFO HDHRNativeCaptureDevice - Capture device is now locked. 23:00:00.153 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG SageTVRequestHandler - Starting network encoder to file name 'I:\Recorded TV\RizzoliIsles-S07E11-Stiffed-20850059-0.ts'. 23:00:00.153 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] INFO HDHRNativeCaptureDevice - Starting the encoding for the channel '781' from the device 'DCT-HDHRun 1319F1B6-2' to the file 'I:\Recorded TV\RizzoliIsles-S07E11-Stiffed-20850059-0.ts'... 23:00:00.154 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'force' lockKey: '-1' sendLength: 40 23:00:01.157 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 23:00:01.159 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: 'null' lockKey: '0' sendLength: 26 23:00:01.162 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/lockkey' value: '1062731506' lockKey: '1062731506' sendLength: 45 23:00:01.164 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] INFO HDHRNativeCaptureDevice - HDHomeRun is now locked. 23:00:01.164 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG RTPCaptureDeviceServices - Producer was not running. 23:00:01.164 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG BasicCaptureDevice - Consumer is was not in progress. 23:00:01.164 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.FFmpegTransSageTVConsumerImpl' for channel '781' 23:00:01.164 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/vchannel' value: '781' lockKey: '1062731506' sendLength: 39 23:00:01.173 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] INFO HDHRNativeCaptureDevice - Configuring and starting the new RTP producer... 23:00:01.173 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG RTPCaptureDeviceServices - Producer was not running. 23:00:01.175 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/target' value: 'rtp://192.168.1.14:8416' lockKey: '1062731506' sendLength: 57 23:00:01.175 [NIORTPProducerImpl-130:DCT-HDHRun 1319F1B6-2] INFO NIORTPProducerImpl - Producer thread is running. 23:00:01.176 [NIORTPProducerImpl-130:DCT-HDHRun 1319F1B6-2] DEBUG NIORTPProducerImpl - Thread priority is 9. 23:00:01.177 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/program' value: 'null' lockKey: '0' sendLength: 26 23:00:01.178 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] INFO HDHRNativeCaptureDevice - Configuring and starting the new SageTV consumer... 23:00:01.181 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG BasicCaptureDevice - Consumer is was not in progress. 23:00:01.183 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/vstatus' value: 'null' lockKey: '0' sendLength: 26 23:00:01.183 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegTransSageTVConsumerImpl - FFmpeg Transcoder consumer thread is now running. 23:00:01.183 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegTransSageTVConsumerImpl - Thread priority is 8. 23:00:01.185 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegStreamDetection - Calling avformat_open_input 23:00:01.685 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegContext - Returning AVERROR_EOF in readCallback.call() 23:00:01.994 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - Before avformat_find_stream_info() pos=273728 bytes_read=273728 seek_count=0. probesize: 165628 analyzeduration: 2000000. 23:00:02.684 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG HDHomeRunControl - key: '/tuner2/vstatus' value: 'null' lockKey: '0' sendLength: 26 23:00:02.807 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO mpegts - Repeated 1 time: [3dcf4a00] PES packet size mismatch. 23:00:02.807 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO mpegts - [03f88f40] decoding for stream 0 failed 23:00:02.810 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1112020 23:00:02.810 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1112020, returnValue = 1155449 23:00:02.811 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1112020 23:00:02.811 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1112020, returnValue = 1156765 23:00:02.811 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 906765, readIndex = 1112020 23:00:02.811 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 906765, readIndex = 906765, returnValue = 906765 23:00:02.886 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 656765, readIndex = 1239672 23:00:02.886 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 656765, readIndex = 656765, returnValue = 656765 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1181053 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1181053, returnValue = 1239673 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 1181053 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 0, returnValue = 0 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0, returnValue = 1239673 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - After avformat_find_stream_info() pos=0 bytes_read=1969215 seek_count=3. probesize: 165628 analyzeduration: 2000000. 23:00:02.887 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - current container duration = 1.682055 seconds 23:00:02.888 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegUtil - Video stream 0 has no width or no height. 23:00:02.888 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - Desired program set. Stream details unavailable for one or more streams. Trying again with an extended probe. 23:00:02.888 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegStreamDetection - Calling avformat_open_input 23:00:02.889 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - Before avformat_find_stream_info() pos=262144 bytes_read=262144 seek_count=0. probesize: 1241176 analyzeduration: 2682055. 23:00:03.323 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1758176 23:00:03.323 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1758176, returnValue = 1760809 23:00:03.323 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1758176 23:00:03.323 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1758176, returnValue = 1762125 23:00:03.324 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1440362, readIndex = 1758176 23:00:03.324 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1440362, readIndex = 1440362, returnValue = 1440362 23:00:03.399 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1262125, readIndex = 1872668 23:00:03.399 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 1262125, readIndex = 1262125, returnValue = 1262125 23:00:03.399 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1786413 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 1786413, returnValue = 1872669 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 1786413 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 0, offset = 0, readIndex = 0, returnValue = 0 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegCircularBufferNIO - Seek: wence = 65536, offset = 0, readIndex = 0, returnValue = 1872669 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - After avformat_find_stream_info() pos=0 bytes_read=2714770 seek_count=2. probesize: 1241176 analyzeduration: 2682055. 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegStreamDetection - current container duration = 2.182555 seconds 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegStreamDetection - FFmpeg stream detection done in 2216ms, 23:00:03.400 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegTranscoder - Initializing FFmpeg transcoder stream output. 23:00:03.402 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegContext - Primary: Input #0, mpegts, from 'input-mpeg-ts.ts': Duration: 00:00:02.18, start: 35480.933656, bitrate: 6864 kb/s Program 3 Stream #0:0[0x1014], 34, 1/90000: Video: mpeg2video (Main) ([128][0][0][0] / 0x0080), yuv420p(tv), 1920x1080 [SAR 1:1 DAR 16:9], Closed Captions, max. 25000 kb/s, 29.97 fps, 29.97 tbr, 90k tbn, 59.94 tbc Stream #0:1[0x1015](eng), 35, 1/90000: Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, 5.1(side), fltp, 448 kb/s Stream #0:2[0x1016](spa), 35, 1/90000: Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s (visual impaired) Stream #0:3[0x1017], 0, 1/90000: Unknown: none ([134][0][0][0] / 0x0086) Stream #0:4[0x1018], 0, 1/90000: Unknown: none (ETV1 / 0x31565445) Stream #0:5[0x1019], 0, 1/90000: Unknown: none (ETV1 / 0x31565445) 23:00:03.402 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegContext - Calling avformat_alloc_output_context2 23:00:03.402 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegContext - Primary: Output #0, mpegts, to 'I:\Recorded TV\RizzoliIsles-S07E11-Stiffed-20850059-0.ts': Stream #0:0, 0, 1/0: Video: mpeg2video, yuv420p(tv), 1920x1080 [SAR 1:1 DAR 16:9], q=2-31, max. 25000 kb/s, 59.94 tbc Stream #0:1(eng), 0, 1/0: Audio: ac3, 48000 Hz, 5.1(side), fltp, 448 kb/s Stream #0:2(spa), 0, 1/0: Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s 23:00:03.403 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] DEBUG FFmpegTranscoder - Writing header 23:00:03.403 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] INFO FFmpegTranscoder - Initialized FFmpeg transcoder stream output. 23:00:07.585 [SageTVRequestHandler-121:DCT-HDHRun 1319F1B6-2] DEBUG SageTVRequestHandler - Replied: 'OK' 23:00:08.241 [SageTVTuningMonitor-27] DEBUG HDHomeRunControl - key: '/tuner2/vstatus' value: 'null' lockKey: '0' sendLength: 26 23:00:28.244 [SageTVTuningMonitor-27] INFO SageTVTuningMonitor - 'DCT-HDHRun 1319F1B6-2' produced first 21412 packets. 23:00:28.244 [SageTVTuningMonitor-27] INFO SageTVTuningMonitor - 'DCT-HDHRun 1319F1B6-2' recorded first 25696404 bytes. 23:11:23.105 [FFmpegTransSageTVConsumerImpl-134:DCT-HDHRun 1319F1B6-2] WARN mpegts - [3e072960] PES packet size mismatch