22:04:56.380 [WrapperSimpleAppMain] INFO  Main - Starting OpenDCT 0.5.28...
22:04:56.397 [WrapperSimpleAppMain] DEBUG Config - OSVersion determined that 'Linux' is LINUX.
22:04:56.397 [WrapperSimpleAppMain] INFO  Config - Current directory is '/opt/opendct'
22:04:56.417 [WrapperSimpleAppMain] INFO  Main - OpenDCT logging to the directory '/var/log/opendct'.
22:04:56.425 [WrapperSimpleAppMain] INFO  UpnpManager - Logging Cling UPnP to '/var/log/opendct/opendct_cling.log'.
22:04:56.425 [WrapperSimpleAppMain] DEBUG UpnpManager - Logging level for Cling UPnP is set to 'severe'.
22:04:56.426 [FFmpegAsyncInit-21] INFO  Main - FFmpeg loading...
22:04:56.426 [WrapperSimpleAppMain] WARN  LinuxPowerMessagePump - System power state messages are currently not implemented for Linux.
22:04:56.428 [FFmpegAsyncInit-21] DEBUG FFmpegUtil - Initializing FFmpegLogger...
22:04:56.452 [FFmpegAsyncInit-21] DEBUG FFmpegUtil - Calling av_log_set_callback...
22:04:56.452 [FFmpegAsyncInit-21] DEBUG FFmpegUtil - Calling avcodec_register_all...
22:04:56.598 [FFmpegAsyncInit-21] DEBUG FFmpegUtil - Calling avfilter_register_all...
22:04:56.682 [FFmpegAsyncInit-21] DEBUG FFmpegUtil - Calling av_register_all...
22:04:56.682 [FFmpegAsyncInit-21] INFO  Main - FFmpeg loaded in 257ms.
22:05:01.431 [WrapperSimpleAppMain] INFO  NetworkPowerEventManger - Network interfaces which are up and have an IP4 address are: 
name:docker0 (docker0) 172.17.0.1
name:br0 (br0) 192.168.1.5
22:05:01.445 [DynamicAsyncInit-21] INFO  DynamicConsumerImpl - Dynamic consumer default set to use opendct.consumer.MediaServerConsumerImpl
22:05:01.445 [DynamicAsyncInit-21] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.FFmpegTransSageTVConsumerImpl for []
22:05:01.445 [DynamicAsyncInit-21] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.MediaServerConsumerImpl for []
22:05:01.445 [DynamicAsyncInit-21] INFO  DynamicConsumerImpl - Dynamic consumer set to use opendct.consumer.RawSageTVConsumerImpl for []
22:05:01.507 [WrapperSimpleAppMain] WARN  HDHomeRunDiscovery - Broadcast IP address was any local address. Using 255.255.255.255 instead.
22:05:01.521 [WrapperSimpleAppMain] INFO  UpnpManager - Starting UPnP services...
22:05:01.535 [WrapperSimpleAppMain] INFO  DCTDefaultUpnpServiceConfiguration - Using the interface 'docker0' with IP address 172.17.0.1 for UPnP discovery.
22:05:01.536 [WrapperSimpleAppMain] INFO  DCTDefaultUpnpServiceConfiguration - Using the interface 'br0' with IP address 192.168.1.5 for UPnP discovery.
22:05:01.572 [WrapperSimpleAppMain] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:01.580 [UPnPDiscovery-39] INFO  UpnpManager - UPnP discovery thread has started.
22:05:01.582 [WrapperSimpleAppMain] INFO  NanoHTTPDManager - Starting webserver on port 9091...
22:05:01.595 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'virgin1'.
22:05:01.599 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'virgin1' did not exist. Triggering re-discovery.
22:05:01.600 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.628 [WrapperSimpleAppMain] INFO  Main - Running in daemon mode...
22:05:01.683 [GenericHttpLoader-40] DEBUG SageTVManager - Port 9000 is available.
22:05:01.685 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'virgin1' is ready.
22:05:01.686 [GenericHttpLoader-40] DEBUG SageTVSocketServer - Setting listening flag...
22:05:01.686 [SageTVTuningMonitor-48] INFO  SageTVTuningMonitor - Tuning monitor thread started.
22:05:01.686 [GenericHttpLoader-40] INFO  SageTVSocketServer - Opening ServerSocket on port 9000...
22:05:01.687 [SageTVSocketServer-49:9000] INFO  SageTVSocketServer - Started listening on port 9000...
22:05:01.687 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'virgin2'.
22:05:01.687 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'virgin2' did not exist. Triggering re-discovery.
22:05:01.687 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.689 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'virgin2' is ready.
22:05:01.689 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'virgin3'.
22:05:01.689 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'virgin3' did not exist. Triggering re-discovery.
22:05:01.689 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.690 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'virgin3' is ready.
22:05:01.691 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'virgin4'.
22:05:01.691 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'virgin4' did not exist. Triggering re-discovery.
22:05:01.691 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.692 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'virgin4' is ready.
22:05:01.692 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview1'.
22:05:01.693 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview1' did not exist. Triggering re-discovery.
22:05:01.693 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.694 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview1' is ready.
22:05:01.694 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview2'.
22:05:01.694 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview2' did not exist. Triggering re-discovery.
22:05:01.695 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.696 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview2' is ready.
22:05:01.696 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview3'.
22:05:01.696 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview3' did not exist. Triggering re-discovery.
22:05:01.697 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.698 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview3' is ready.
22:05:01.698 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview4'.
22:05:01.698 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview4' did not exist. Triggering re-discovery.
22:05:01.699 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.700 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview4' is ready.
22:05:01.700 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'vmstb'.
22:05:01.700 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'vmstb' did not exist. Triggering re-discovery.
22:05:01.700 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.701 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'vmstb' is ready.
22:05:01.702 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview5'.
22:05:01.702 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview5' did not exist. Triggering re-discovery.
22:05:01.702 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.703 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview5' is ready.
22:05:01.703 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview6'.
22:05:01.703 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview6' did not exist. Triggering re-discovery.
22:05:01.704 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.705 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview6' is ready.
22:05:01.705 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview7'.
22:05:01.705 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview7' did not exist. Triggering re-discovery.
22:05:01.705 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.706 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview7' is ready.
22:05:01.707 [GenericHttpLoader-40] DEBUG DeviceLoaderImpl - Advertising new capture device 'freeview8'.
22:05:01.707 [GenericHttpLoader-40] DEBUG SageTVManager - The requested capture device 'freeview8' did not exist. Triggering re-discovery.
22:05:01.707 [GenericHttpLoader-40] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel ''
22:05:01.708 [GenericHttpLoader-40] INFO  SageTVManager - The capture device 'freeview8' is ready.
22:05:05.581 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:09.582 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:13.583 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:17.584 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:20.507 [SageTVRequestHandler-50:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.508 [SageTVRequestHandler-50:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.508 [SageTVRequestHandler-50:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP virgin1 HDMI'
22:05:20.509 [SageTVRequestHandler-50:virgin1] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.509 [SageTVRequestHandler-50:virgin1] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.510 [SageTVRequestHandler-50:virgin1] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.510 [StoppingThread-51] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.511 [SageTVRequestHandler-50:virgin1] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.512 [SageTVRequestHandler-52:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.513 [SageTVRequestHandler-52:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.513 [SageTVRequestHandler-52:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP vmstb HDMI'
22:05:20.513 [SageTVRequestHandler-52:vmstb] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.513 [SageTVRequestHandler-52:vmstb] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.516 [SageTVRequestHandler-52:vmstb] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.516 [SageTVRequestHandler-52:vmstb] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.518 [StoppingThread-53] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.519 [SageTVRequestHandler-54:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.524 [SageTVRequestHandler-54:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.530 [SageTVRequestHandler-54:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview7 HDMI'
22:05:20.533 [SageTVRequestHandler-54:freeview7] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.538 [SageTVRequestHandler-54:freeview7] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.546 [SageTVRequestHandler-54:freeview7] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.548 [StoppingThread-55] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.551 [SageTVRequestHandler-54:freeview7] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.556 [SageTVRequestHandler-56:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.557 [SageTVRequestHandler-56:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.563 [SageTVRequestHandler-56:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview3 HDMI'
22:05:20.569 [SageTVRequestHandler-56:freeview3] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.575 [SageTVRequestHandler-56:freeview3] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.584 [SageTVRequestHandler-56:freeview3] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.590 [SageTVRequestHandler-56:freeview3] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.585 [StoppingThread-57] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.592 [SageTVRequestHandler-58:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.597 [SageTVRequestHandler-58:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.598 [SageTVRequestHandler-58:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview8 HDMI'
22:05:20.600 [SageTVRequestHandler-58:freeview8] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.603 [SageTVRequestHandler-58:freeview8] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.606 [SageTVRequestHandler-58:freeview8] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.608 [SageTVRequestHandler-58:freeview8] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.607 [StoppingThread-59] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.610 [SageTVRequestHandler-60:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.619 [SageTVRequestHandler-60:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.622 [SageTVRequestHandler-60:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview1 HDMI'
22:05:20.624 [SageTVRequestHandler-60:freeview1] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.625 [SageTVRequestHandler-60:freeview1] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.626 [SageTVRequestHandler-60:freeview1] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.627 [SageTVRequestHandler-60:freeview1] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.628 [StoppingThread-61] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.635 [SageTVRequestHandler-62:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.637 [SageTVRequestHandler-62:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.640 [SageTVRequestHandler-62:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview4 HDMI'
22:05:20.645 [SageTVRequestHandler-62:freeview4] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.645 [SageTVRequestHandler-62:freeview4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.645 [SageTVRequestHandler-62:freeview4] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.646 [SageTVRequestHandler-62:freeview4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.646 [StoppingThread-63] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.651 [SageTVRequestHandler-64:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.654 [SageTVRequestHandler-64:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.657 [SageTVRequestHandler-64:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview6 HDMI'
22:05:20.658 [SageTVRequestHandler-64:freeview6] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.658 [SageTVRequestHandler-64:freeview6] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.658 [SageTVRequestHandler-64:freeview6] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.658 [StoppingThread-65] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.658 [SageTVRequestHandler-64:freeview6] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.674 [SageTVRequestHandler-66:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.674 [SageTVRequestHandler-66:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.677 [SageTVRequestHandler-66:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP virgin2 HDMI'
22:05:20.677 [SageTVRequestHandler-66:virgin2] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.679 [SageTVRequestHandler-66:virgin2] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.682 [SageTVRequestHandler-66:virgin2] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.683 [StoppingThread-67] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.683 [SageTVRequestHandler-66:virgin2] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.687 [SageTVRequestHandler-68:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.693 [SageTVRequestHandler-68:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.698 [SageTVRequestHandler-68:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview2 HDMI'
22:05:20.704 [SageTVRequestHandler-68:freeview2] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.710 [SageTVRequestHandler-68:freeview2] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.714 [SageTVRequestHandler-68:freeview2] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.715 [SageTVRequestHandler-68:freeview2] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.715 [StoppingThread-69] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.725 [SageTVRequestHandler-70:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.725 [SageTVRequestHandler-70:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.725 [SageTVRequestHandler-70:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP vmstb2 HDMI'
22:05:20.726 [SageTVRequestHandler-70:Unknown] DEBUG SageTVManager - The capture device 'vmstb2' is not yet available.
22:05:20.732 [SageTVRequestHandler-70:Unknown] DEBUG SageTVManager - The requested capture device 'vmstb2' did not exist. Triggering re-discovery.
22:05:20.741 [SageTVRequestHandler-70:Unknown] ERROR SageTVRequestHandler - SageTV requested the tuner 'vmstb2' and it does not exist at this time.
22:05:20.742 [SageTVRequestHandler-70:vmstb2] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.748 [SageTVRequestHandler-71:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.753 [SageTVRequestHandler-71:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.759 [SageTVRequestHandler-71:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP virgin4 HDMI'
22:05:20.760 [SageTVRequestHandler-71:virgin4] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.760 [SageTVRequestHandler-71:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.762 [SageTVRequestHandler-71:virgin4] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.768 [SageTVRequestHandler-71:virgin4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.763 [StoppingThread-72] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.772 [SageTVRequestHandler-73:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.780 [SageTVRequestHandler-73:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.785 [SageTVRequestHandler-73:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP virgin3 HDMI'
22:05:20.788 [SageTVRequestHandler-73:virgin3] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.794 [SageTVRequestHandler-73:virgin3] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.804 [SageTVRequestHandler-73:virgin3] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.806 [SageTVRequestHandler-73:virgin3] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.808 [StoppingThread-74] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.810 [SageTVRequestHandler-75:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'VERSION'
22:05:20.820 [SageTVRequestHandler-75:Unknown] DEBUG SageTVRequestHandler - Replied: '3.0'
22:05:20.823 [SageTVRequestHandler-75:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP freeview5 HDMI'
22:05:20.826 [SageTVRequestHandler-75:freeview5] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:05:20.832 [SageTVRequestHandler-75:freeview5] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:05:20.838 [SageTVRequestHandler-75:freeview5] INFO  GenericHttpCaptureDevice - Capture device was already unlocked.
22:05:20.843 [SageTVRequestHandler-75:freeview5] DEBUG SageTVRequestHandler - Replied: 'OK'
22:05:20.839 [StoppingThread-76] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:05:20.845 [SageTVDiscovery-47] DEBUG SageTVDiscovery - Received discovery datagram from SageTV server '/192.168.1.5:8270' and validating...
22:05:20.849 [SageTVDiscovery-47] DEBUG SageTVDiscovery - Validated discovery datagram from SageTV server '/192.168.1.5:8270' and preparing response...
22:05:20.849 [SageTVDiscovery-47] INFO  SageTVDiscovery - Sent discovery response datagram to SageTV server '/192.168.1.5:8270'.
22:05:21.584 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:23.854 [SageTVRequestHandler-77:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'PROPERTIES'
22:05:23.859 [SageTVRequestHandler-77:Unknown] INFO  SageTVRequestHandler - Sent PROPERTIES.
22:05:23.864 [SageTVRequestHandler-77:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'QUIT'
22:05:25.585 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:29.585 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:31.628 [WrapperSimpleAppMain] INFO  Main - Saving config...
22:05:33.586 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:33.586 [UPnPDiscovery-39] DEBUG UpnpManager - Stopping broadcast.
22:05:33.586 [UPnPDiscovery-39] WARN  DiscoveryRegistryListener - Before UPnP shutdown, the registry did not contain any devices.
22:05:33.587 [UPnPDiscovery-39] DEBUG DiscoveryRegistryListener - Shutdown of UPnP registry complete.
22:05:40.589 [UPnPDiscovery-39] DEBUG UpnpManager - Broadcast requested.
22:05:40.591 [UPnPDiscovery-39] INFO  DCTDefaultUpnpServiceConfiguration - Using the interface 'docker0' with IP address 172.17.0.1 for UPnP discovery.
22:05:40.592 [UPnPDiscovery-39] INFO  DCTDefaultUpnpServiceConfiguration - Using the interface 'br0' with IP address 192.168.1.5 for UPnP discovery.
22:05:44.595 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:48.596 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:52.596 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:05:56.597 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:06:00.598 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:06:04.599 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:06:08.600 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:06:12.601 [UPnPDiscovery-39] DEBUG UpnpManager - Sending a SecureContainer search message for 'schemas-cetoncorp-com' devices...
22:06:12.601 [UPnPDiscovery-39] DEBUG UpnpManager - Stopping broadcast.
22:06:12.602 [UPnPDiscovery-39] WARN  DiscoveryRegistryListener - Before UPnP shutdown, the registry did not contain any devices.
22:06:12.602 [UPnPDiscovery-39] DEBUG DiscoveryRegistryListener - Shutdown of UPnP registry complete.
22:06:31.627 [ChannelManager-46] INFO  ChannelManager - The static channel lineup virgin1 (generic_http) will remain unchanged.
22:10:14.192 [SageTVRequestHandler-82:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START virgin4 HDMI|1252769756|502|3046443628380|/var/media/Premier League Football - 23092156-0.ts|Great-H.264'
22:10:14.192 [SageTVRequestHandler-82:virgin4] DEBUG GenericHttpCaptureDevice - Capture device is now re-locked.
22:10:14.192 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1252769756' to file name '/var/media/Premier League Football - 23092156-0.ts'.
22:10:14.192 [SageTVRequestHandler-82:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:10:14.193 [SageTVRequestHandler-82:virgin4] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel '502'
22:10:14.194 [SageTVRequestHandler-82:virgin4] INFO  GenericHttpCaptureDevice - Starting the encoding for the channel '502' from the device 'virgin4' to the file '/var/media/Premier League Football - 23092156-0.ts' via the upload id '1252769756'...
22:10:14.194 [SageTVRequestHandler-82:virgin4] INFO  GenericHttpCaptureDevice - Configuring and starting the new SageTV producer...
22:10:14.195 [SageTVRequestHandler-82:virgin4] INFO  NIOHTTPProducerImpl - Connecting to source using the URL 'http://192.168.1.5:9981/stream/channelnumber/502'
22:10:14.363 [SageTVRequestHandler-82:virgin4] DEBUG NIOHttpDownloader - HTTP response: 'HTTP/1.1 200 OK', 'Server: HTS/tvheadend', 'Cache-Control: no-cache', 'Connection: Close', 'Content-Type: video/mp2t', 
22:10:14.363 [SageTVRequestHandler-82:virgin4] INFO  GenericHttpCaptureDevice - Configuring and starting the new SageTV consumer...
22:10:14.363 [NIOHTTPProducerImpl-83:virgin4] INFO  NIOHTTPProducerImpl - Producer thread is running.
22:10:14.363 [SageTVRequestHandler-82:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:10:14.363 [NIOHTTPProducerImpl-83:virgin4] DEBUG NIOHTTPProducerImpl - Thread priority is 9.
22:10:14.364 [MediaServerConsumerImpl-84:virgin4] INFO  MediaServerConsumerImpl - MediaServer thread started.
22:10:14.364 [MediaServerConsumerImpl-84:virgin4] DEBUG MediaServerConsumerImpl - Thread priority is 8.
22:10:14.364 [MediaServerConsumerImpl-84:virgin4] INFO  MediaServerConsumerImpl - Opening file via MediaServer...
22:10:14.364 [MediaServerConsumerImpl-84:virgin4] INFO  NIOSageTVMediaServer - Connecting to SageTV server on socket /192.168.1.5:7818...
22:10:14.364 [MediaServerConsumerImpl-84:virgin4] INFO  NIOSageTVMediaServer - Sending 'WRITEOPEN /var/media/Premier League Football - 23092156-0.ts 1252769756' to SageTV server...
22:10:14.365 [MediaServerConsumerImpl-84:virgin4] INFO  MediaServerConsumerImpl - Setting up remuxing on MediaServer...
22:10:14.365 [MediaServerConsumerImpl-84:virgin4] INFO  NIOSageTVMediaServer - Sending 'REMUX_SETUP AUTO TS TRUE' to SageTV server...
22:10:14.369 [MediaServerConsumerImpl-84:virgin4] INFO  MediaServerConsumerImpl - Media Server consumer is now streaming...
22:10:15.404 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:10:23.597 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - SageTV sent: 'STOP virgin4 HDMI'
22:10:23.598 [SageTVRequestHandler-82:virgin4] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:10:23.598 [SageTVRequestHandler-82:virgin4] DEBUG HTTPCaptureDeviceServices - Stopping producer thread...
22:10:23.598 [SageTVRequestHandler-82:virgin4] DEBUG HTTPCaptureDeviceServices - Waiting for producer thread to stop...
22:10:23.599 [NIOHTTPProducerImpl-83:virgin4] DEBUG NIOHTTPProducerImpl - The socket has been closed.
22:10:23.599 [NIOHTTPProducerImpl-83:virgin4] INFO  NIOHTTPProducerImpl - Producer thread has stopped.
22:10:23.599 [SageTVRequestHandler-82:virgin4] DEBUG BasicCaptureDevice - Stopping consumer thread...
22:10:23.616 [SageTVRequestHandler-82:virgin4] INFO  NIOSageTVMediaServer - Sending 'CLOSE' to SageTV server...
22:10:23.617 [SageTVRequestHandler-82:virgin4] INFO  NIOSageTVMediaServer - Sending 'QUIT' to SageTV server...
22:10:23.618 [MediaServerConsumerImpl-84:virgin4] DEBUG MediaServerConsumerImpl - MediaServer consumer was interrupted.
22:10:23.618 [SageTVRequestHandler-82:virgin4] DEBUG BasicCaptureDevice - Waiting for consumer thread to stop...
22:10:23.618 [MediaServerConsumerImpl-84:virgin4] INFO  MediaServerConsumerImpl - MediaServer thread stopped.
22:10:23.620 [SageTVRequestHandler-82:virgin4] DEBUG GenericHttpCaptureDevice - Capture device is now re-unlocked.
22:10:23.620 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:10:23.620 [StoppingThread-99] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:10:26.139 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - SageTV sent: 'START virgin4 HDMI|1227918054|501|3046443652276|/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts|Great-H.264'
22:10:26.139 [SageTVRequestHandler-82:virgin4] DEBUG GenericHttpCaptureDevice - Capture device is now re-locked.
22:10:26.140 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1227918054' to file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts'.
22:10:26.140 [SageTVRequestHandler-82:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:10:26.140 [StoppingThread-99] DEBUG GenericHttpCaptureDevice - Stopping executable was cancelled.
22:10:26.140 [SageTVRequestHandler-82:virgin4] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel '501'
22:10:26.145 [SageTVRequestHandler-82:virgin4] INFO  GenericHttpCaptureDevice - Starting the encoding for the channel '501' from the device 'virgin4' to the file '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts' via the upload id '1227918054'...
22:10:26.145 [SageTVRequestHandler-82:virgin4] INFO  GenericHttpCaptureDevice - Configuring and starting the new SageTV producer...
22:10:26.146 [SageTVRequestHandler-82:virgin4] INFO  NIOHTTPProducerImpl - Connecting to source using the URL 'http://192.168.1.5:9981/stream/channelnumber/501'
22:10:26.481 [SageTVRequestHandler-82:virgin4] DEBUG NIOHttpDownloader - HTTP response: 'HTTP/1.1 200 OK', 'Server: HTS/tvheadend', 'Cache-Control: no-cache', 'Connection: Close', 'Content-Type: video/mp2t', 
22:10:26.481 [SageTVRequestHandler-82:virgin4] INFO  GenericHttpCaptureDevice - Configuring and starting the new SageTV consumer...
22:10:26.481 [SageTVRequestHandler-82:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:10:26.481 [NIOHTTPProducerImpl-100:virgin4] INFO  NIOHTTPProducerImpl - Producer thread is running.
22:10:26.481 [NIOHTTPProducerImpl-100:virgin4] DEBUG NIOHTTPProducerImpl - Thread priority is 9.
22:10:26.481 [MediaServerConsumerImpl-101:virgin4] INFO  MediaServerConsumerImpl - MediaServer thread started.
22:10:26.482 [MediaServerConsumerImpl-101:virgin4] DEBUG MediaServerConsumerImpl - Thread priority is 8.
22:10:26.482 [MediaServerConsumerImpl-101:virgin4] INFO  MediaServerConsumerImpl - Opening file via MediaServer...
22:10:26.482 [MediaServerConsumerImpl-101:virgin4] INFO  NIOSageTVMediaServer - Connecting to SageTV server on socket /192.168.1.5:7818...
22:10:26.482 [MediaServerConsumerImpl-101:virgin4] INFO  NIOSageTVMediaServer - Sending 'WRITEOPEN /var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts 1227918054' to SageTV server...
22:10:26.482 [MediaServerConsumerImpl-101:virgin4] INFO  MediaServerConsumerImpl - Setting up remuxing on MediaServer...
22:10:26.482 [MediaServerConsumerImpl-101:virgin4] INFO  NIOSageTVMediaServer - Sending 'REMUX_SETUP AUTO TS TRUE' to SageTV server...
22:10:26.483 [MediaServerConsumerImpl-101:virgin4] INFO  MediaServerConsumerImpl - Media Server consumer is now streaming...
22:10:27.579 [SageTVRequestHandler-82:virgin4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:10:47.734 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:10:47.735 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:10:47.736 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:10:47.736 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:10:47.736 [SageTVTuningMonitor-48] INFO  SageTVTuningMonitor - 'virgin4' produced first 14092104 packets.
22:11:03.739 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:11:03.740 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:11:03.740 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:11:03.741 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:11:03.742 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:11:19.745 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:11:19.745 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:11:19.746 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:11:19.746 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:11:19.747 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:11:35.749 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:11:35.750 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:11:35.750 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:11:35.751 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:11:35.751 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:11:51.754 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:11:51.755 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:11:51.756 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:11:51.756 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:11:51.757 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:12:07.759 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:12:07.760 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:12:07.760 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:12:07.760 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:12:07.761 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:12:23.764 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:12:23.764 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:12:23.765 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:12:23.765 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:12:23.766 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:12:39.768 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:12:39.769 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:12:39.769 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:12:39.770 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:12:39.770 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:12:55.773 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:12:55.773 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:12:55.774 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:12:55.774 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000001'
22:12:55.775 [SageTVTuningMonitor-48] DEBUG SageTVTuningMonitor - The consumer appears to be stuck at 0, file system length 0, file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-0.ts', copy protection NONE.
22:13:04.117 [SageTVRequestHandler-114:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'STOP virgin4 HDMI'
22:13:04.117 [SageTVRequestHandler-114:virgin4] DEBUG GenericHttpCaptureDevice - Stopping encoding...
22:13:04.117 [SageTVRequestHandler-114:virgin4] DEBUG HTTPCaptureDeviceServices - Stopping producer thread...
22:13:04.117 [NIOHTTPProducerImpl-100:virgin4] DEBUG NIOHTTPProducerImpl - The socket has been closed.
22:13:04.118 [NIOHTTPProducerImpl-100:virgin4] INFO  NIOHTTPProducerImpl - Producer thread has stopped.
22:13:04.119 [SageTVRequestHandler-114:virgin4] DEBUG BasicCaptureDevice - Stopping consumer thread...
22:13:04.119 [SageTVRequestHandler-114:virgin4] INFO  NIOSageTVMediaServer - Sending 'CLOSE' to SageTV server...
22:13:04.126 [SageTVRequestHandler-114:virgin4] INFO  NIOSageTVMediaServer - Sending 'QUIT' to SageTV server...
22:13:04.126 [SageTVRequestHandler-114:virgin4] DEBUG BasicCaptureDevice - Waiting for consumer thread to stop...
22:13:04.126 [MediaServerConsumerImpl-101:virgin4] DEBUG MediaServerConsumerImpl - MediaServer consumer was interrupted.
22:13:04.126 [MediaServerConsumerImpl-101:virgin4] INFO  MediaServerConsumerImpl - MediaServer thread stopped.
22:13:04.127 [SageTVRequestHandler-114:virgin4] DEBUG GenericHttpCaptureDevice - Capture device is now re-unlocked.
22:13:04.127 [StoppingThread-117] DEBUG GenericHttpCaptureDevice - Stopping executable will be run in 15000 milliseconds.
22:13:04.127 [SageTVRequestHandler-114:virgin4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:13:04.128 [SageTVRequestHandler-114:Unknown] DEBUG SageTVRequestHandler - SageTV sent: 'START virgin4 HDMI|1743543225|501|3046443968254|/var/media/Live The Masters, PGA Tour Golf - 23102438-1.ts|Great-H.264'
22:13:04.128 [SageTVRequestHandler-114:virgin4] DEBUG GenericHttpCaptureDevice - Capture device is now re-locked.
22:13:04.128 [SageTVRequestHandler-114:virgin4] DEBUG SageTVRequestHandler - Starting network encoder via upload ID '1743543225' to file name '/var/media/Live The Masters, PGA Tour Golf - 23102438-1.ts'.
22:13:04.128 [SageTVRequestHandler-114:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:13:04.128 [StoppingThread-117] DEBUG GenericHttpCaptureDevice - Stopping executable was cancelled.
22:13:04.128 [SageTVRequestHandler-114:virgin4] DEBUG DynamicConsumerImpl - Using default consumer 'opendct.consumer.MediaServerConsumerImpl' for channel '501'
22:13:04.129 [SageTVRequestHandler-114:virgin4] INFO  GenericHttpCaptureDevice - Starting the encoding for the channel '501' from the device 'virgin4' to the file '/var/media/Live The Masters, PGA Tour Golf - 23102438-1.ts' via the upload id '1743543225'...
22:13:04.129 [SageTVRequestHandler-114:virgin4] INFO  GenericHttpCaptureDevice - Configuring and starting the new SageTV producer...
22:13:04.129 [SageTVRequestHandler-114:virgin4] INFO  NIOHTTPProducerImpl - Connecting to source using the URL 'http://192.168.1.5:9981/stream/channelnumber/501'
22:13:04.130 [SageTVRequestHandler-114:virgin4] DEBUG NIOHttpDownloader - HTTP response: 'HTTP/1.1 200 OK', 'Server: HTS/tvheadend', 'Cache-Control: no-cache', 'Connection: Close', 'Content-Type: video/mp2t', 
22:13:04.130 [SageTVRequestHandler-114:virgin4] INFO  GenericHttpCaptureDevice - Configuring and starting the new SageTV consumer...
22:13:04.130 [SageTVRequestHandler-114:virgin4] DEBUG BasicCaptureDevice - Consumer is not in progress.
22:13:04.130 [NIOHTTPProducerImpl-118:virgin4] INFO  NIOHTTPProducerImpl - Producer thread is running.
22:13:04.157 [NIOHTTPProducerImpl-118:virgin4] DEBUG NIOHTTPProducerImpl - Thread priority is 9.
22:13:04.157 [MediaServerConsumerImpl-119:virgin4] INFO  MediaServerConsumerImpl - MediaServer thread started.
22:13:04.157 [MediaServerConsumerImpl-119:virgin4] DEBUG MediaServerConsumerImpl - Thread priority is 8.
22:13:04.157 [MediaServerConsumerImpl-119:virgin4] INFO  MediaServerConsumerImpl - Opening file via MediaServer...
22:13:04.157 [MediaServerConsumerImpl-119:virgin4] INFO  NIOSageTVMediaServer - Connecting to SageTV server on socket /192.168.1.5:7818...
22:13:04.158 [MediaServerConsumerImpl-119:virgin4] INFO  NIOSageTVMediaServer - Sending 'WRITEOPEN /var/media/Live The Masters, PGA Tour Golf - 23102438-1.ts 1743543225' to SageTV server...
22:13:04.161 [MediaServerConsumerImpl-119:virgin4] INFO  MediaServerConsumerImpl - Setting up remuxing on MediaServer...
22:13:04.161 [MediaServerConsumerImpl-119:virgin4] INFO  NIOSageTVMediaServer - Sending 'REMUX_SETUP AUTO TS TRUE' to SageTV server...
22:13:04.162 [MediaServerConsumerImpl-119:virgin4] INFO  MediaServerConsumerImpl - Media Server consumer is now streaming...
22:13:05.325 [SageTVRequestHandler-114:virgin4] DEBUG SageTVRequestHandler - Replied: 'OK'
22:13:25.780 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 0
22:13:25.781 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - Received 16 bytes from SageTV server.
22:13:25.781 [SageTVTuningMonitor-48] DEBUG NIOSageTVMediaServer - messageInBytes = 16
22:13:25.782 [SageTVTuningMonitor-48] INFO  NIOSageTVMediaServer - Received message from SageTV server '0 900000000002'
22:13:25.782 [SageTVTuningMonitor-48] INFO  SageTVTuningMonitor - 'virgin4' produced first 11929916 packets.