MVP Log Files

Jun 2017

The log files for the Packager (and possibly the other v8 products) are held in the following location.

/var/log/mfvp/

If you list the files in this folder you will see the following;

drwxr-xr-x+ 3 envivio envivio 4096 Jun 12 07:59 cleanup-agent
drwxr-xr-x+ 3 envivio envivio 4096 Jun 12 07:59 dispatcher
drwxr-xr-x+ 4 root root 4096 Jun 8 15:59 license-manager
drwxr-xr-x+ 3 envivio envivio 4096 Jun 12 07:59 packaging-asset-recorder
drwxr-xr-x+ 4 envivio envivio 4096 Jun 12 07:59 packaging-config
drwxr-xr-x+ 4 root root 4096 Jun 8 15:59 packaging-controller
drwxr-xr-x+ 3 envivio envivio 4096 Jun 12 07:59 packaging-dvr-recorder
drwxr-xr-x+ 5 envivio envivio 4096 Jun 12 07:59 packaging-origin-server
drwxr-xr-x+ 5 envivio envivio 4096 Jun 12 07:59 packaging-playlist-generator
drwxr-xr-x+ 4 envivio envivio 4096 Jun 8 15:59 packaging-server
drwxr-xr-x+ 3 root root 4096 Jun 8 15:58 packaging-standalone
drwxr-xr-x+ 3 envivio envivio 4096 Jun 8 15:59 snmp
drwxr-xr-x+ 5 envivio envivio 4096 Jun 8 15:59 statistic

Each process has its own log files, so the recorder process of the packager, that created the mezzanine files, keeps its logs in packaging-dvr-recorder.
If you list the contents of packaging-dvr-recorder you will see the following:

-rwxr-xr-x+ 1 root root 0 Jun 12 07:59 install.log
drwxr-xr-x+ 7 envivio envivio 4096 Jun 16 01:01 private

The log files are in the private directory.

drwxr-xr-x+ 3 envivio envivio 4096 Jun 12 09:17 packagetest
drwxr-xr-x+ 3 envivio envivio 4096 Jun 16 08:49 service1
drwxr-xr-x. 3 envivio envivio 4096 Jun 16 08:48 service2
drwxr-xr-x. 3 envivio envivio 4096 Jun 14 12:33 service3
-rw-r--r--+ 1 envivio envivio 1743 Jun 14 07:44 serviceManager-20170612-000000.log
-rw-r--r--+ 1 envivio envivio 44475 Jun 14 03:27 serviceManager-20170612-000000.log-20170614.gz
-rw-r--r--+ 1 envivio envivio 0 Jun 15 03:28 serviceManager-20170614-000000.log
-rw-r--r--+ 1 envivio envivio 116062 Jun 15 03:28 serviceManager-20170614-000000.log-20170615.gz
-rw-r--r--. 1 envivio envivio 0 Jun 15 01:01 serviceManager-20170615-000000.log
-rw-r--r--. 1 envivio envivio 0 Jun 16 01:01 serviceManager-20170616-000000.log
-rw-r--r--+ 1 root root 40990 Jun 12 08:11 setup.log
-rw-r--r--+ 1 envivio envivio 263 Jun 14 12:13 startup.log
drwxr-xr-x+ 3 envivio envivio 4096 Jun 14 08:08 test
-rw-r--r--+ 1 envivio envivio 75432 Jun 12 23:59 trace-20170612-000.log
-rw-r--r--+ 1 envivio envivio 0 Jun 14 03:27 trace-20170613-000.log
-rw-r--r--+ 1 envivio envivio 17245 Jun 14 03:27 trace-20170613-000.log-20170614.gz
-rw-r--r--+ 1 envivio envivio 25948 Jun 14 12:59 trace-20170614-000.log

Their are general service log files in here for the packager-dvr-recorder. There are also logs specific to each service in folders that are the same as the service name. In this example we can look in the folder service1.

drwxr-xr-x+ 3 envivio envivio 4096 Jun 14 09:33 logs
-rw-r--r--+ 1 envivio envivio 1011188 Jun 14 11:12 trace-20170614-000.log
-rw-r--r--. 1 envivio envivio 1003358 Jun 14 23:01 trace-20170614-012.log
-rw-r--r--. 1 envivio envivio 1003474 Jun 14 23:49 trace-20170614-013.log
-rw-r--r--. 1 envivio envivio 128205 Jun 14 23:56 trace-20170614-014.log
-rw-r--r--. 1 envivio envivio 1002469 Jun 15 00:49 trace-20170615-000.log
-rw-r--r--. 1 envivio envivio 1003357 Jun 15 23:01 trace-20170615-025.log
-rw-r--r--. 1 envivio envivio 1003614 Jun 15 23:55 trace-20170615-026.log
-rw-r--r--. 1 envivio envivio 81877 Jun 15 23:56 trace-20170615-027.log
-rw-r--r--. 1 envivio envivio 1006286 Jun 16 00:49 trace-20170616-000.log
-rw-r--r--. 1 envivio envivio 1003596 Jun 16 07:55 trace-20170616-008.log
-rw-r--r--. 1 envivio envivio 1003442 Jun 16 08:49 trace-20170616-009.log
-rw-r--r--. 1 envivio envivio 719275 Jun 16 09:26 trace-20170616-010.log

If we open one of the log files, here is an example of what a log file might look like:

2017-06-14T09:32:57.577Z worker.js(199) INFO Initializing DVR service1
2017-06-14T09:32:57.577Z worker.js(144) INFO status: event.output.status.stopped service1
2017-06-14T09:32:57.578Z DVR_recorder.cpp(139) INFO service1 Minidump path initialized: /var/log/mfvp/packaging-dvr-recorder/private/service1_173e613a-b3c2-6ea7-30914800-4d2141a0.dmp
2017-06-14T09:32:57.583Z refCountController.js(132) INFO service1 Fail to getRefCountBuffer: "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/refcount'"
2017-06-14T09:32:57.603Z mongo.js(36) INFO service1 connected to mongodb
2017-06-14T09:32:57.604Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:32:57.604Z periodIndexController.js(148) ERROR service1 Fail to getPeriodList: "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:32:57.607Z CleanupAgent.js(111) INFO service1 CleanupAgent start @ /opt/mfvp/mnt/nas/sda3store/service1/: dvrDuration=3600, deleteSpeed=1.2, minCleanupInterval=1
2017-06-14T09:32:57.608Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:02.610Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:07.611Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:12.613Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:17.179Z worker.js(209) INFO service1 Start DVR service1 in 3.5642045536078513 seconds...
2017-06-14T09:33:17.613Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:20.744Z worker.js(214) INFO service1 Start DVR service1
2017-06-14T09:33:20.744Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:20.744Z worker.js(218) ERROR service1 No previous DVR found for DVR service1 : File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'
2017-06-14T09:33:20.747Z DVR_recorder.cpp(276) INFO service1 DVR config:
<?xml version="1.0" encoding="UTF-8"?>
<dvr>
<id>service1</id>
<name>Service1</name>
<inputId>service1</inputId>
<state>stopped</state>
<updatedAt>2017-06-14T09:32:55.278Z</updatedAt>
<gopSignaling>ALD</gopSignaling>
<gopDuration>2000</gopDuration>
<duration>3600</duration>
<sources>
<source>
<port>10001</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10002</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10003</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10004</port>
<address>239.0.200.1</address>
</source>
</sources>
<publishingPoints>
<publishingPoint>/opt/mfvp/mnt/nas/sda3store/service1</publishingPoint>
</publishingPoints>
<serviceId>594102c7818fca0ed83551dd</serviceId>
<tsPartDuration>2000</tsPartDuration>
<tsPartSizeAlignment>188</tsPartSizeAlignment>
<tsPartCount>1</tsPartCount>
<indexFileSizeAlignment>1</indexFileSizeAlignment>
<periodDuration>360</periodDuration>
<createDirectories>true</createDirectories>
<flipFlopIndexFiles>true</flipFlopIndexFiles>
<rozofsExtAttr>false</rozofsExtAttr>
<playlistGeneratorHost>127.0.0.1</playlistGeneratorHost>
<playlistGeneratorPort>8085</playlistGeneratorPort>
<alarmServerId>01</alarmServerId>
<alarmRedisSocket>/var/lib/envivio/alarm/alarm.sock</alarmRedisSocket>
<alarmRedisHost></alarmRedisHost>
<alarmRedisPort>0</alarmRedisPort>
<alarmRedisKeyExpire>10</alarmRedisKeyExpire>
</dvr>
2017-06-14T09:33:20.747Z UT_FileLogMonitor.cpp(115) INFO ADDON_LOG_MONITOR Log file monitoring thread created
2017-06-14T09:33:20.748Z CWA_PluginManager.cpp(160) INFO Preloading all plugins
2017-06-14T09:33:20.748Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=NonExistingFilter
2017-06-14T09:33:20.749Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libpngEncoder.so keys=pngEncoderFilter
2017-06-14T09:33:20.749Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsegmentIndexer.so keys=segmentIndexer
2017-06-14T09:33:20.751Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsamplequeue.so keys=sampleQueue
2017-06-14T09:33:20.759Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvbsubtitlecomposer.so keys=dvbSubtitleComposer
2017-06-14T09:33:20.768Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libwebdavPublisher.so keys=webdavPublisher
2017-06-14T09:33:20.768Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libttxtdecoder.so keys=teletextDecoder
2017-06-14T09:33:20.769Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvbsubtitledecoder.so keys=dvbSubtitleDecoder
2017-06-14T09:33:20.772Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libmpeg2tsanalyzer.so keys=mpeg2TsAnalyzerSession
2017-06-14T09:33:20.773Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libperiodIndexer.so keys=periodIndexerMimoSession
2017-06-14T09:33:20.794Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libplaylistGeneratorNotifier.so keys=playlistGeneratorNotifierSession
2017-06-14T09:33:20.796Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvrMpdGenerator.so keys=dvrMpdGeneratorSession
2017-06-14T09:33:20.797Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libttmlFormatter.so keys=ttmlFormatter
2017-06-14T09:33:20.802Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libfilePublisher.so keys=filePublisher
2017-06-14T09:33:20.803Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libvideostreamanalyzer.so keys=VideoStreamAnalyzer
2017-06-14T09:33:20.804Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsimpleScheduler.so keys=simpleSchedulerMimoSession
2017-06-14T09:33:20.804Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsubtitleIndexer.so keys=subtitleIndexer
2017-06-14T09:33:20.805Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libmultiipreceiver.so keys=captureMultiIpReceiver
2017-06-14T09:33:20.807Z CWA_PluginManager.cpp(408) WARNING /opt/mfvp/packaging-dvr-recorder/lib/addons/*.so not found. err=0x310002 - No such file or directory
2017-06-14T09:33:20.807Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libpngEncoder.so keys=pngEncoderFilter
2017-06-14T09:33:20.807Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsegmentIndexer.so keys=segmentIndexer
2017-06-14T09:33:20.808Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsamplequeue.so keys=sampleQueue
2017-06-14T09:33:20.813Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvbsubtitlecomposer.so keys=dvbSubtitleComposer
2017-06-14T09:33:20.819Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libwebdavPublisher.so keys=webdavPublisher
2017-06-14T09:33:20.819Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libttxtdecoder.so keys=teletextDecoder
2017-06-14T09:33:20.820Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvbsubtitledecoder.so keys=dvbSubtitleDecoder
2017-06-14T09:33:20.821Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libmpeg2tsanalyzer.so keys=mpeg2TsAnalyzerSession
2017-06-14T09:33:20.822Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libperiodIndexer.so keys=periodIndexerMimoSession
2017-06-14T09:33:20.839Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libplaylistGeneratorNotifier.so keys=playlistGeneratorNotifierSession
2017-06-14T09:33:20.840Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvrMpdGenerator.so keys=dvrMpdGeneratorSession
2017-06-14T09:33:20.841Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libttmlFormatter.so keys=ttmlFormatter
2017-06-14T09:33:20.845Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libfilePublisher.so keys=filePublisher
2017-06-14T09:33:20.845Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libvideostreamanalyzer.so keys=VideoStreamAnalyzer
2017-06-14T09:33:20.846Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsimpleScheduler.so keys=simpleSchedulerMimoSession
2017-06-14T09:33:20.846Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsubtitleIndexer.so keys=subtitleIndexer
2017-06-14T09:33:20.847Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libmultiipreceiver.so keys=captureMultiIpReceiver
2017-06-14T09:33:20.848Z CWA_PluginManager.cpp(408) WARNING /opt/mfvp/packaging-dvr-recorder/lib/addons/../lib/*.so not found. err=0x310002 - No such file or directory
2017-06-14T09:33:20.848Z DVR_GraphManager.cpp(106) INFO service1 Found configuration path: /opt/mfvp/packaging-dvr-recorder/resources/xslt
2017-06-14T09:33:20.849Z UT_XPathParser.cpp(64) ERROR Performing late non-reentrant initialization of xerces. Please modify the main to use UT_Global!!!
2017-06-14T09:33:20.852Z DVR_AlarmManager.cpp(94) INFO Redis alarm configuration : ServerId = 01 | Socket = /var/lib/envivio/alarm/alarm.sock | Host = | Port = 0 | Expiration delay = 10
2017-06-14T09:33:20.853Z CWO_RedisAlarmDispatcher.cpp(204) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Redis alarm dispatcher initialized.
2017-06-14T09:33:20.853Z CWO_RedisAlarmDispatcher.cpp(245) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Alarm refresh thread running
2017-06-14T09:33:20.854Z UWA_GraphBuilder.cpp(3480) ERROR Performing late non-reentrant initialization of xerces. Please modify the main to use UT_Global!!!
2017-06-14T09:33:20.856Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=MultiIpReceiver type=captureMultiIpReceiver newType=FilterTypeCapture
2017-06-14T09:33:20.856Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=captureMultiIpReceiver
2017-06-14T09:33:20.856Z CAP_MultiIpReceiverSource.cpp(222) INFO service1 No NIC entry, use any local interface
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10001 parsed : protocol=udp host=239.0.200.1 port=10001 multicastsources=(null)
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10002 parsed : protocol=udp host=239.0.200.1 port=10002 multicastsources=(null)
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10003 parsed : protocol=udp host=239.0.200.1 port=10003 multicastsources=(null)
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10004 parsed : protocol=udp host=239.0.200.1 port=10004 multicastsources=(null)
2017-06-14T09:33:20.857Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:33:20.857Z UT_AsyncEventDispatcherTemplate.h(132) INFO MultiIpReceiverSource.eventDispatcher new dispatcher thread (timeoutQ 1000 - maxSizeQ 50 - prio normal)
2017-06-14T09:33:20.857Z OS_Thread.cpp(358) WARNING pthread_create failed - prio=5: ret=1 - retrying with lower prio
2017-06-14T09:33:20.858Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_1 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:33:20.858Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:33:20.858Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_2 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:33:20.858Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:33:20.858Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_3 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:33:20.858Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:33:20.858Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_4 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:33:20.858Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream1' index=0
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10001 URL udp://239.0.200.1:10001 parsed : protocol=udp host=239.0.200.1 port=10001 multicastsources=(null)
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10001 useTransient=yes
2017-06-14T09:33:20.859Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:20.859Z UT_FileLogger.cpp(251) WARNING Path does not exist. logPath=/var/log/mfvp/packaging-dvr-recorder/private/service1/logs/privateLogs/
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream2' index=1
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10002 URL udp://239.0.200.1:10002 parsed : protocol=udp host=239.0.200.1 port=10002 multicastsources=(null)
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10002 useTransient=yes
2017-06-14T09:33:20.859Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream3' index=2
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10003 URL udp://239.0.200.1:10003 parsed : protocol=udp host=239.0.200.1 port=10003 multicastsources=(null)
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10003 useTransient=yes
2017-06-14T09:33:20.859Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream4' index=3
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10004 URL udp://239.0.200.1:10004 parsed : protocol=udp host=239.0.200.1 port=10004 multicastsources=(null)
2017-06-14T09:33:20.859Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10004 useTransient=yes
2017-06-14T09:33:20.859Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:20.859Z DX_Mpeg2TsAnalyzerSession.cpp(1655) INFO service1:239.0.200.1:10002 Use external clock
2017-06-14T09:33:20.859Z DX_Mpeg2TsAnalyzerSession.cpp(1655) INFO service1:239.0.200.1:10003 Use external clock
2017-06-14T09:33:20.859Z DX_Mpeg2TsAnalyzerSession.cpp(1655) INFO service1:239.0.200.1:10004 Use external clock
2017-06-14T09:33:20.860Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10001 Start
2017-06-14T09:33:20.860Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10002 Start
2017-06-14T09:33:20.860Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10003 Start
2017-06-14T09:33:20.860Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10004 Start
2017-06-14T09:33:20.860Z worker.js(144) INFO service1 status: event.output.status.starting service1
2017-06-14T09:33:20.868Z CAP_MultiIpReceiverSource.cpp(702) INFO service1 Start capturing the source
2017-06-14T09:33:20.868Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10001 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:33:20.870Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10002 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:33:20.870Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10003 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:33:20.870Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10004 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:33:21.014Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:33:21.014Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10004 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:33:21.015Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:33:21.015Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:33:21.015Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:33:21.015Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=139745350909954
2017-06-14T09:33:21.016Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:33:21.016Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10003 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:33:21.016Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:33:21.016Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:33:21.016Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:33:21.016Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=139745350909954
2017-06-14T09:33:21.017Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:33:21.017Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10002 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:33:21.017Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:33:21.017Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:33:21.017Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:33:21.017Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=139745350909954
2017-06-14T09:33:21.020Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:33:21.020Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10001 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:33:21.020Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:33:21.020Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:33:21.020Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:33:21.020Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=139745350909954
2017-06-14T09:33:21.265Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10004 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=352x576 par=32:11 vopInc=0 videoFormat=pal fr=25
2017-06-14T09:33:21.265Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10004 pcrClockEnabled=1 offset=10406 (before=10406 after=0)
2017-06-14T09:33:21.265Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10004 Send event.demuxer.initialized
2017-06-14T09:33:21.265Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:33:21.289Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10004 PCR discontinuity. external=false internal=true
2017-06-14T09:33:21.774Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10002 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=1280x720 par=1:1 vopInc=139745350909952 videoFormat=? fr=29_97
2017-06-14T09:33:21.774Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10002 pcrClockEnabled=1 offset=10915 (before=10915 after=0)
2017-06-14T09:33:21.774Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10002 Send event.demuxer.initialized
2017-06-14T09:33:21.774Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:33:21.807Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10002 PCR discontinuity. external=false internal=true
2017-06-14T09:33:22.057Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10001 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=1920x1080 par=1:1 vopInc=139745350909952 videoFormat=? fr=29_97
2017-06-14T09:33:22.057Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10001 pcrClockEnabled=1 offset=11199 (before=11199 after=0)
2017-06-14T09:33:22.057Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10001 Send event.demuxer.initialized
2017-06-14T09:33:22.057Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:33:22.060Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10001 PCR discontinuity. external=false internal=true
2017-06-14T09:33:22.325Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10004 error=-0.003789 errorV=-0.003657 aveErrorV=-0.000366 rateChange=-0.000004 rate=0.999996
2017-06-14T09:33:22.616Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:22.823Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10002 error=0.000100 errorV=0.000098 aveErrorV=0.000010 rateChange=0.000000 rate=1.000000
2017-06-14T09:33:22.942Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10003 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=720x576 par=64:45 vopInc=139745350909952 videoFormat=pal fr=25
2017-06-14T09:33:22.942Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10003 pcrClockEnabled=1 offset=12083 (before=12083 after=0)
2017-06-14T09:33:22.942Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10003 Send event.demuxer.initialized
2017-06-14T09:33:22.942Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:33:22.966Z DVR_GraphManager.cpp(471) INFO service1 Demuxer initialized
2017-06-14T09:33:22.966Z DVR_GraphManager.cpp(494) INFO service1 ES list:
<esList><es demuxed="false" pid="1001" type="VisualStream" streamType="27" streamTypeStr="H264_VIDEO" descriptorTag="0" descriptorTagStr=""/><es demuxed="false" pid="2001" type="AudioStream" streamType="15" streamTypeStr="MPEG2_AAC" descriptorTag="0" descriptorTagStr="" language="und"/></esList>
2017-06-14T09:33:22.966Z DVR_GraphManager.cpp(508) INFO service1 Analyzer initialized, add output
2017-06-14T09:33:22.966Z DVR_GraphManager.cpp(106) INFO service1 Found configuration path: /opt/mfvp/packaging-dvr-recorder/resources/xslt
2017-06-14T09:33:22.974Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10003 PCR discontinuity. external=false internal=true
2017-06-14T09:33:22.976Z DVR_GraphManager.cpp(344) INFO service1 addOutputGraph
2017-06-14T09:33:22.976Z UWA_GraphBuilder.cpp(3480) ERROR Performing late non-reentrant initialization of xerces. Please modify the main to use UT_Global!!!
2017-06-14T09:33:22.982Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10001 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:33:22.982Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:33:22.982Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10001:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:33:22.983Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:INFO Created 64 buffers of size 255
2017-06-14T09:33:22.983Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.983Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.983Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.983Z UT_AsyncEventDispatcherTemplate.h(132) INFO SampleQueuePerformance new dispatcher thread (timeoutQ 1000 - maxSizeQ 50 - prio normal)
2017-06-14T09:33:22.983Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:INFO Starting...
2017-06-14T09:33:22.983Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.983Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.983Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.983Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.984Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:DATA Starting...
2017-06-14T09:33:22.984Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.984Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.984Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10002 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:33:22.984Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:33:22.984Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10002:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:33:22.984Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:INFO Created 64 buffers of size 255
2017-06-14T09:33:22.984Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.984Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.984Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.985Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.985Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:INFO Starting...
2017-06-14T09:33:22.985Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.985Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.985Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.985Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:DATA Starting...
2017-06-14T09:33:22.985Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.985Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.986Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10003 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:33:22.986Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:33:22.986Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10003:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:33:22.986Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:INFO Created 64 buffers of size 255
2017-06-14T09:33:22.986Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.986Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.986Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.986Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.986Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:INFO Starting...
2017-06-14T09:33:22.986Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.986Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.986Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.986Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:DATA Starting...
2017-06-14T09:33:22.987Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.987Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.987Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10004 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:33:22.987Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:33:22.987Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10004:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:33:22.987Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:INFO Created 64 buffers of size 255
2017-06-14T09:33:22.987Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.987Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.987Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.987Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:INFO Starting...
2017-06-14T09:33:22.987Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.987Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.988Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.988Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.988Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:DATA Starting...
2017-06-14T09:33:22.988Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.988Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.988Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SimpleScheduler type=simpleSchedulerMimoSession newType=FilterTypeMimo
2017-06-14T09:33:22.988Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=simpleSchedulerMimoSession
2017-06-14T09:33:22.988Z MM_SimpleSchedulerMimoSession.cpp(128) INFO service1 Initialized
2017-06-14T09:33:22.988Z MM_SimpleSchedulerMimoSession.cpp(425) INFO service1 Simple Scheduling thread started
2017-06-14T09:33:22.988Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.988Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.988Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.988Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.988Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=PeriodIndexer type=periodIndexerMimoSession newType=FilterTypeMimo
2017-06-14T09:33:22.988Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=periodIndexerMimoSession
2017-06-14T09:33:22.989Z MM_PeriodIndexerMimoSession.cpp(185) INFO service1 Initialized: periodDuration=360000ms initialChunkIndex=0 initialRealPts=0
2017-06-14T09:33:22.989Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10001:DATA Initialized: initialByteOffset=0
2017-06-14T09:33:22.989Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10002:DATA Initialized: initialByteOffset=0
2017-06-14T09:33:22.989Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10003:DATA Initialized: initialByteOffset=0
2017-06-14T09:33:22.989Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10004:DATA Initialized: initialByteOffset=0
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.989Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.989Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.990Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:TS Starting...
2017-06-14T09:33:22.990Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.990Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.991Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10001 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.991Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.991Z OS_Thread.cpp(358) WARNING pthread_create failed - prio=5: ret=1 - retrying with lower prio
2017-06-14T09:33:22.991Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.991Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10001 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:33:22.991Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:33:22.991Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.991Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.991Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.991Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:SIDX Starting...
2017-06-14T09:33:22.991Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.991Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.991Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10001 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.992Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.992Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:SIDX Created 10 buffers of size 4096
2017-06-14T09:33:22.992Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.992Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.992Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.992Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:TS Starting...
2017-06-14T09:33:22.992Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.992Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.992Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10002 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.992Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.992Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.992Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10002 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:33:22.992Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:33:22.992Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.992Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.992Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.992Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:SIDX Starting...
2017-06-14T09:33:22.992Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.993Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.993Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10002 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.993Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.993Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:SIDX Created 10 buffers of size 4096
2017-06-14T09:33:22.993Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.993Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.993Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.993Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:TS Starting...
2017-06-14T09:33:22.993Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.993Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.993Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10003 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.993Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.993Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.993Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10003 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:33:22.993Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:33:22.993Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.994Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.994Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.994Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:SIDX Starting...
2017-06-14T09:33:22.994Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.994Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.995Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10003 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.995Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.995Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:SIDX Created 10 buffers of size 4096
2017-06-14T09:33:22.995Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.995Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.995Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.995Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:TS Starting...
2017-06-14T09:33:22.995Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.995Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.995Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10004 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.995Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.995Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.995Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10004 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:33:22.995Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:33:22.995Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.995Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:33:22.995Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:33:22.995Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:SIDX Starting...
2017-06-14T09:33:22.995Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:33:22.996Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.996Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10004 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.996Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.996Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:SIDX Created 10 buffers of size 4096
2017-06-14T09:33:22.996Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.996Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=MpdGenerator type=dvrMpdGeneratorSession newType=FilterTypeMixer
2017-06-14T09:33:22.996Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=dvrMpdGeneratorSession
2017-06-14T09:33:22.996Z CWA_BufferAllocator.cpp(116) INFO service1:MPD Created 2 buffers of size 12
2017-06-14T09:33:22.996Z CWA_SampleAllocator.cpp(179) INFO dvrMpdGeneratorSessionMpdGenerator debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.996Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=MpdPublisher type=filePublisher newType=FilterTypeTransform
2017-06-14T09:33:22.996Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:33:22.997Z CWA_SampleAllocator.cpp(179) INFO filePublisherMpdPublisher debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.997Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=PlGenNotifier type=playlistGeneratorNotifierSession newType=FilterTypeMixer
2017-06-14T09:33:22.997Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=playlistGeneratorNotifierSession
2017-06-14T09:33:22.997Z MX_PlaylistGeneratorNotifierSession.cpp(157) INFO service1 Playlist Generator notification URL: http://127.0.0.1:8085/api/catalogs/*/assets/service1/generate
2017-06-14T09:33:22.998Z CWA_SampleAllocator.cpp(179) INFO playlistGeneratorNotifierSessionPlGenNotifier debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.998Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:22.998Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_1 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:DATA sampleSize=1531824 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:TS sampleSize=1531824 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.998Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_1 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.998Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:22.999Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_2 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:DATA sampleSize=1281972 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:TS sampleSize=1281972 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_2 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:22.999Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_3 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:DATA sampleSize=781892 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:TS sampleSize=781892 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_3 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:22.999Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_4 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:DATA sampleSize=406832 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:22.999Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:TS sampleSize=406832 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:23.000Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_4 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:33:23.000Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:23.000Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:23.000Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:33:23.000Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:33:23.000Z UT_TaskScheduler.cpp(129) INFO service1 TaskScheduler thread started
2017-06-14T09:33:23.000Z DVR_AlarmManager.cpp(470) INFO service1 Send "event.dvr.status" : reason :
2017-06-14T09:33:23.000Z DVR_GraphManager.cpp(385) INFO service1 Received event event.dvr.status
2017-06-14T09:33:23.000Z DVR_AlarmManager.cpp(431) INFO service1 Raise file create dir error alarm (pp=/opt/mfvp/mnt/nas/sda3store/service1 [path:/opt/mfvp/mnt/nas/sda3store/service1][info:Permission denied])
2017-06-14T09:33:23.000Z worker.js(144) INFO service1 status: event.output.status.alarmError service1
2017-06-14T09:33:23.001Z worker.js(159) ERROR service1 Alarm error: event.output.status.alarmError - "service1" - ""
2017-06-14T09:33:23.002Z TX_FilePublisherFilter.cpp(1282) ERROR service1:239.0.200.1:10004:SIDX Failed to (recursively) create directory /opt/mfvp/mnt/nas/sda3store/service1: err=3211277 - Permission denied
2017-06-14T09:33:23.002Z CWA_FilterGraph.cpp(778) ERROR Failed to start filter periodIndexerMimoOutput_service1:239.0.200.1:10004:INFO. err=0x31000d: Permission denied
2017-06-14T09:33:23.002Z CWA_FilterGraph.cpp(851) ERROR Failed to Start. err=0x31000d: Permission denied
2017-06-14T09:33:23.002Z DVR_AlarmManager.cpp(470) INFO service1 Send "event.dvr.status" : reason :
2017-06-14T09:33:23.002Z DVR_GraphManager.cpp(385) INFO service1 Received event event.dvr.status
2017-06-14T09:33:23.002Z TX_FilePublisherFilter.cpp(1282) ERROR service1:239.0.200.1:10004:SIDX Failed to (recursively) create directory /opt/mfvp/mnt/nas/sda3store/service1: err=3211277 - Permission denied
2017-06-14T09:33:23.002Z CWA_FilterGraph.cpp(778) ERROR Failed to start filter periodIndexerMimoOutput_service1:239.0.200.1:10004:INFO. err=0x31000d: Permission denied
2017-06-14T09:33:23.002Z CWA_FilterGraph.cpp(851) ERROR Failed to Start. err=0x31000d: Permission denied
2017-06-14T09:33:23.002Z DVR_GraphManager.cpp(526) ERROR service1 Failed to add output graph. err=0x31000d - Permission denied
2017-06-14T09:33:23.002Z worker.js(144) INFO service1 status: event.output.status.alarmError service1
2017-06-14T09:33:23.002Z DVR_AlarmManager.cpp(555) INFO service1 Notify DVR recorder error [Record processing creation failure [Permission denied]]
2017-06-14T09:33:23.002Z worker.js(159) ERROR service1 Alarm error: event.output.status.alarmError - "service1" - ""
2017-06-14T09:33:23.003Z worker.js(144) INFO service1 status: event.output.status.error service1
2017-06-14T09:33:23.056Z CWO_RedisAlarmDispatcher.cpp(279) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Key alarms/servers/01/dvrRecorder/service1 refreshed for 10s
2017-06-14T09:33:23.056Z CWO_RedisAlarmDispatcher.cpp(302) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Key "alarms/servers/01/dvrRecorder/service1" available again
2017-06-14T09:33:23.075Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10001 error=0.000633 errorV=0.000624 aveErrorV=0.000062 rateChange=0.000001 rate=1.000001
2017-06-14T09:33:24.002Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10003 error=0.000144 errorV=0.000141 aveErrorV=0.000014 rateChange=0.000000 rate=1.000000
2017-06-14T09:33:25.004Z UT_LegacyLogImpl.cpp(310) INFO stopping the log system
2017-06-14T09:33:48.753Z worker.js(199) INFO Initializing DVR service1
2017-06-14T09:33:48.753Z worker.js(144) INFO status: event.output.status.stopped service1
2017-06-14T09:33:48.753Z DVR_recorder.cpp(139) INFO service1 Minidump path initialized: /var/log/mfvp/packaging-dvr-recorder/private/service1_7b20d00b-dd6b-d0b7-5aabbc43-5f025c13.dmp
2017-06-14T09:33:48.758Z refCountController.js(132) INFO service1 Fail to getRefCountBuffer: "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/refcount'"
2017-06-14T09:33:48.779Z mongo.js(36) INFO service1 connected to mongodb
2017-06-14T09:33:48.780Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:48.780Z periodIndexController.js(148) ERROR service1 Fail to getPeriodList: "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:48.783Z CleanupAgent.js(111) INFO service1 CleanupAgent start @ /opt/mfvp/mnt/nas/sda3store/service1/: dvrDuration=3600, deleteSpeed=1.2, minCleanupInterval=1
2017-06-14T09:33:48.784Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:53.786Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:33:58.787Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:34:03.789Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:34:08.790Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:34:12.899Z worker.js(209) INFO service1 Start DVR service1 in 0.315804161131382 seconds...
2017-06-14T09:34:13.215Z worker.js(214) INFO service1 Start DVR service1
2017-06-14T09:34:13.215Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:34:13.216Z worker.js(218) ERROR service1 No previous DVR found for DVR service1 : File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'
2017-06-14T09:34:13.218Z DVR_recorder.cpp(276) INFO service1 DVR config:
<?xml version="1.0" encoding="UTF-8"?>
<dvr>
<id>service1</id>
<name>Service1</name>
<inputId>service1</inputId>
<state>stopped</state>
<updatedAt>2017-06-14T09:32:55.278Z</updatedAt>
<gopSignaling>ALD</gopSignaling>
<gopDuration>2000</gopDuration>
<duration>3600</duration>
<sources>
<source>
<port>10001</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10002</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10003</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10004</port>
<address>239.0.200.1</address>
</source>
</sources>
<publishingPoints>
<publishingPoint>/opt/mfvp/mnt/nas/sda3store/service1</publishingPoint>
</publishingPoints>
<serviceId>594102c7818fca0ed83551dd</serviceId>
<tsPartDuration>2000</tsPartDuration>
<tsPartSizeAlignment>188</tsPartSizeAlignment>
<tsPartCount>1</tsPartCount>
<indexFileSizeAlignment>1</indexFileSizeAlignment>
<periodDuration>360</periodDuration>
<createDirectories>true</createDirectories>
<flipFlopIndexFiles>true</flipFlopIndexFiles>
<rozofsExtAttr>false</rozofsExtAttr>
<playlistGeneratorHost>127.0.0.1</playlistGeneratorHost>
<playlistGeneratorPort>8085</playlistGeneratorPort>
<alarmServerId>01</alarmServerId>
<alarmRedisSocket>/var/lib/envivio/alarm/alarm.sock</alarmRedisSocket>
<alarmRedisHost></alarmRedisHost>
<alarmRedisPort>0</alarmRedisPort>
<alarmRedisKeyExpire>10</alarmRedisKeyExpire>
</dvr>
2017-06-14T09:34:13.219Z UT_FileLogMonitor.cpp(115) INFO ADDON_LOG_MONITOR Log file monitoring thread created
2017-06-14T09:34:13.219Z CWA_PluginManager.cpp(160) INFO Preloading all plugins
2017-06-14T09:34:13.219Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=NonExistingFilter
2017-06-14T09:34:13.220Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libpngEncoder.so keys=pngEncoderFilter
2017-06-14T09:34:13.221Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsegmentIndexer.so keys=segmentIndexer
2017-06-14T09:34:13.222Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsamplequeue.so keys=sampleQueue
2017-06-14T09:34:13.230Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvbsubtitlecomposer.so keys=dvbSubtitleComposer
2017-06-14T09:34:13.239Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libwebdavPublisher.so keys=webdavPublisher
2017-06-14T09:34:13.240Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libttxtdecoder.so keys=teletextDecoder
2017-06-14T09:34:13.240Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvbsubtitledecoder.so keys=dvbSubtitleDecoder
2017-06-14T09:34:13.243Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libmpeg2tsanalyzer.so keys=mpeg2TsAnalyzerSession
2017-06-14T09:34:13.244Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libperiodIndexer.so keys=periodIndexerMimoSession
2017-06-14T09:34:13.266Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libplaylistGeneratorNotifier.so keys=playlistGeneratorNotifierSession
2017-06-14T09:34:13.267Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvrMpdGenerator.so keys=dvrMpdGeneratorSession
2017-06-14T09:34:13.269Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libttmlFormatter.so keys=ttmlFormatter
2017-06-14T09:34:13.274Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libfilePublisher.so keys=filePublisher
2017-06-14T09:34:13.275Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libvideostreamanalyzer.so keys=VideoStreamAnalyzer
2017-06-14T09:34:13.276Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsimpleScheduler.so keys=simpleSchedulerMimoSession
2017-06-14T09:34:13.276Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsubtitleIndexer.so keys=subtitleIndexer
2017-06-14T09:34:13.278Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libmultiipreceiver.so keys=captureMultiIpReceiver
2017-06-14T09:34:13.280Z CWA_PluginManager.cpp(408) WARNING /opt/mfvp/packaging-dvr-recorder/lib/addons/*.so not found. err=0x310002 - No such file or directory
2017-06-14T09:34:13.280Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libpngEncoder.so keys=pngEncoderFilter
2017-06-14T09:34:13.280Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsegmentIndexer.so keys=segmentIndexer
2017-06-14T09:34:13.281Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsamplequeue.so keys=sampleQueue
2017-06-14T09:34:13.286Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvbsubtitlecomposer.so keys=dvbSubtitleComposer
2017-06-14T09:34:13.293Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libwebdavPublisher.so keys=webdavPublisher
2017-06-14T09:34:13.293Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libttxtdecoder.so keys=teletextDecoder
2017-06-14T09:34:13.293Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvbsubtitledecoder.so keys=dvbSubtitleDecoder
2017-06-14T09:34:13.295Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libmpeg2tsanalyzer.so keys=mpeg2TsAnalyzerSession
2017-06-14T09:34:13.295Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libperiodIndexer.so keys=periodIndexerMimoSession
2017-06-14T09:34:13.313Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libplaylistGeneratorNotifier.so keys=playlistGeneratorNotifierSession
2017-06-14T09:34:13.314Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvrMpdGenerator.so keys=dvrMpdGeneratorSession
2017-06-14T09:34:13.315Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libttmlFormatter.so keys=ttmlFormatter
2017-06-14T09:34:13.319Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libfilePublisher.so keys=filePublisher
2017-06-14T09:34:13.320Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libvideostreamanalyzer.so keys=VideoStreamAnalyzer
2017-06-14T09:34:13.320Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsimpleScheduler.so keys=simpleSchedulerMimoSession
2017-06-14T09:34:13.320Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsubtitleIndexer.so keys=subtitleIndexer
2017-06-14T09:34:13.321Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libmultiipreceiver.so keys=captureMultiIpReceiver
2017-06-14T09:34:13.323Z CWA_PluginManager.cpp(408) WARNING /opt/mfvp/packaging-dvr-recorder/lib/addons/../lib/*.so not found. err=0x310002 - No such file or directory
2017-06-14T09:34:13.323Z DVR_GraphManager.cpp(106) INFO service1 Found configuration path: /opt/mfvp/packaging-dvr-recorder/resources/xslt
2017-06-14T09:34:13.324Z UT_XPathParser.cpp(64) ERROR Performing late non-reentrant initialization of xerces. Please modify the main to use UT_Global!!!
2017-06-14T09:34:13.327Z DVR_AlarmManager.cpp(94) INFO Redis alarm configuration : ServerId = 01 | Socket = /var/lib/envivio/alarm/alarm.sock | Host = | Port = 0 | Expiration delay = 10
2017-06-14T09:34:13.328Z CWO_RedisAlarmDispatcher.cpp(204) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Redis alarm dispatcher initialized.
2017-06-14T09:34:13.328Z CWO_RedisAlarmDispatcher.cpp(245) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Alarm refresh thread running
2017-06-14T09:34:13.329Z UWA_GraphBuilder.cpp(3480) ERROR Performing late non-reentrant initialization of xerces. Please modify the main to use UT_Global!!!
2017-06-14T09:34:13.331Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=MultiIpReceiver type=captureMultiIpReceiver newType=FilterTypeCapture
2017-06-14T09:34:13.331Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=captureMultiIpReceiver
2017-06-14T09:34:13.331Z CAP_MultiIpReceiverSource.cpp(222) INFO service1 No NIC entry, use any local interface
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10001 parsed : protocol=udp host=239.0.200.1 port=10001 multicastsources=(null)
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10002 parsed : protocol=udp host=239.0.200.1 port=10002 multicastsources=(null)
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10003 parsed : protocol=udp host=239.0.200.1 port=10003 multicastsources=(null)
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1 URL udp://239.0.200.1:10004 parsed : protocol=udp host=239.0.200.1 port=10004 multicastsources=(null)
2017-06-14T09:34:13.332Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1 useTransient=yes
2017-06-14T09:34:13.332Z UT_AsyncEventDispatcherTemplate.h(132) INFO MultiIpReceiverSource.eventDispatcher new dispatcher thread (timeoutQ 1000 - maxSizeQ 50 - prio normal)
2017-06-14T09:34:13.332Z OS_Thread.cpp(358) WARNING pthread_create failed - prio=5: ret=1 - retrying with lower prio
2017-06-14T09:34:13.332Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_1 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:34:13.332Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:34:13.332Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_2 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:34:13.332Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:34:13.333Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_3 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:34:13.333Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:34:13.333Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=Analyzer_4 type=mpeg2TsAnalyzerSession newType=FilterTypeDemuxer
2017-06-14T09:34:13.333Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=mpeg2TsAnalyzerSession
2017-06-14T09:34:13.333Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream1' index=0
2017-06-14T09:34:13.333Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10001 URL udp://239.0.200.1:10001 parsed : protocol=udp host=239.0.200.1 port=10001 multicastsources=(null)
2017-06-14T09:34:13.333Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10001 useTransient=yes
2017-06-14T09:34:13.333Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream2' index=1
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10002 URL udp://239.0.200.1:10002 parsed : protocol=udp host=239.0.200.1 port=10002 multicastsources=(null)
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10002 useTransient=yes
2017-06-14T09:34:13.334Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream3' index=2
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10003 URL udp://239.0.200.1:10003 parsed : protocol=udp host=239.0.200.1 port=10003 multicastsources=(null)
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10003 useTransient=yes
2017-06-14T09:34:13.334Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverSource.cpp(417) INFO service1 Opened device name='multiIpReceiverStream4' index=3
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverDevice.cpp(338) INFO service1:239.0.200.1:10004 URL udp://239.0.200.1:10004 parsed : protocol=udp host=239.0.200.1 port=10004 multicastsources=(null)
2017-06-14T09:34:13.334Z CAP_MultiIpReceiverDevice.cpp(95) INFO service1:239.0.200.1:10004 useTransient=yes
2017-06-14T09:34:13.334Z CWA_SampleAllocator.cpp(179) INFO captureMultiIpReceiverMultiIpReceiver debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(1655) INFO service1:239.0.200.1:10002 Use external clock
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(1655) INFO service1:239.0.200.1:10003 Use external clock
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(1655) INFO service1:239.0.200.1:10004 Use external clock
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10001 Start
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10002 Start
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10003 Start
2017-06-14T09:34:13.335Z DX_Mpeg2TsAnalyzerSession.cpp(765) INFO service1:239.0.200.1:10004 Start
2017-06-14T09:34:13.335Z worker.js(144) INFO service1 status: event.output.status.starting service1
2017-06-14T09:34:13.342Z CAP_MultiIpReceiverSource.cpp(702) INFO service1 Start capturing the source
2017-06-14T09:34:13.342Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10001 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:34:13.343Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10002 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:34:13.343Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10003 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:34:13.343Z CAP_MultiIpReceiverSource.cpp(1517) INFO service1 Connecting to 239.0.200.1:10004 on local interface 0.0.0.0 (0x0), source: all
2017-06-14T09:34:13.513Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:34:13.513Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10004 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:34:13.519Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:34:13.519Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10003 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:34:13.519Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:34:13.519Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:34:13.519Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:34:13.519Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=140307991625730
2017-06-14T09:34:13.519Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:34:13.519Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:34:13.519Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:34:13.519Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=140307991625730
2017-06-14T09:34:13.520Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:34:13.520Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10002 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:34:13.520Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:34:13.520Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:34:13.520Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:34:13.520Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=140307991625730
2017-06-14T09:34:13.522Z mpeg2_transport.c(997) INFO : Selected default program. progNum=1, PMT pid=0xBB9
2017-06-14T09:34:13.522Z mpeg2_transport.c(710) INFO service1:239.0.200.1:10001 : PMT Change: adding progNum=0x1 pid=0xbb9
2017-06-14T09:34:13.522Z mpeg2_transport.c(853) INFO : Created es. pid=0x3e9 st=27
2017-06-14T09:34:13.522Z mpeg2_transport.c(853) INFO : Created es. pid=0x7d1 st=15
2017-06-14T09:34:13.522Z mpeg2_transport.c(1415) INFO : PCR PID has changed from 0x0 to 0x3e9
2017-06-14T09:34:13.522Z mpeg2_transport.c(1440) INFO : Pid's in pmap changed. oldCnt=0 newCnt=140307991625730
2017-06-14T09:34:13.791Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:34:13.903Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10001 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=1920x1080 par=1:1 vopInc=0 videoFormat=? fr=29_97
2017-06-14T09:34:13.903Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10001 pcrClockEnabled=1 offset=10571 (before=10571 after=0)
2017-06-14T09:34:13.903Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10001 Send event.demuxer.initialized
2017-06-14T09:34:13.903Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:34:13.912Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10001 PCR discontinuity. external=false internal=true
2017-06-14T09:34:14.185Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10002 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=1280x720 par=1:1 vopInc=140307991625728 videoFormat=? fr=29_97
2017-06-14T09:34:14.185Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10002 pcrClockEnabled=1 offset=10852 (before=10852 after=0)
2017-06-14T09:34:14.185Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10002 Send event.demuxer.initialized
2017-06-14T09:34:14.185Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:34:14.185Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10002 PCR discontinuity. external=false internal=true
2017-06-14T09:34:14.927Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10001 error=0.000867 errorV=0.000854 aveErrorV=0.000085 rateChange=0.000001 rate=1.000001
2017-06-14T09:34:15.200Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10002 error=0.000256 errorV=0.000252 aveErrorV=0.000025 rateChange=0.000000 rate=1.000000
2017-06-14T09:34:16.463Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10003 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=720x576 par=64:45 vopInc=140307991625728 videoFormat=pal fr=25
2017-06-14T09:34:16.463Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10003 pcrClockEnabled=1 offset=13130 (before=13130 after=0)
2017-06-14T09:34:16.463Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10003 Send event.demuxer.initialized
2017-06-14T09:34:16.463Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:34:16.482Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10003 PCR discontinuity. external=false internal=true
2017-06-14T09:34:16.504Z DX_Mpeg2TsAnalyzerOutputInfo.cpp(176) INFO service1:239.0.200.1:10004 PID 0x03E9 - objType=MPEG-4 Visual ISO/IEC 14496-10 | H.264 res=352x576 par=32:11 vopInc=140307991625728 videoFormat=pal fr=25
2017-06-14T09:34:16.504Z DX_Mpeg2TsAnalyzerClock.cpp(102) INFO service1:239.0.200.1:10004 pcrClockEnabled=1 offset=13171 (before=13171 after=0)
2017-06-14T09:34:16.504Z DX_Mpeg2TsAnalyzerSession.cpp(806) INFO service1:239.0.200.1:10004 Send event.demuxer.initialized
2017-06-14T09:34:16.504Z DVR_GraphManager.cpp(385) INFO service1 Received event event.demuxer.initialized
2017-06-14T09:34:16.506Z DVR_GraphManager.cpp(471) INFO service1 Demuxer initialized
2017-06-14T09:34:16.506Z DVR_GraphManager.cpp(494) INFO service1 ES list:
<esList><es demuxed="false" pid="1001" type="VisualStream" streamType="27" streamTypeStr="H264_VIDEO" descriptorTag="0" descriptorTagStr=""/><es demuxed="false" pid="2001" type="AudioStream" streamType="15" streamTypeStr="MPEG2_AAC" descriptorTag="0" descriptorTagStr="" language="und"/></esList>
2017-06-14T09:34:16.506Z DVR_GraphManager.cpp(508) INFO service1 Analyzer initialized, add output
2017-06-14T09:34:16.506Z DVR_GraphManager.cpp(106) INFO service1 Found configuration path: /opt/mfvp/packaging-dvr-recorder/resources/xslt
2017-06-14T09:34:16.515Z DVR_GraphManager.cpp(344) INFO service1 addOutputGraph
2017-06-14T09:34:16.516Z UWA_GraphBuilder.cpp(3480) ERROR Performing late non-reentrant initialization of xerces. Please modify the main to use UT_Global!!!
2017-06-14T09:34:16.521Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10001 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:34:16.522Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:34:16.522Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10001:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:34:16.522Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:INFO Created 64 buffers of size 255
2017-06-14T09:34:16.522Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.522Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.522Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.522Z UT_AsyncEventDispatcherTemplate.h(132) INFO SampleQueuePerformance new dispatcher thread (timeoutQ 1000 - maxSizeQ 50 - prio normal)
2017-06-14T09:34:16.523Z DX_Mpeg2TsAnalyzerLiveClock.cpp(109) WARNING service1:239.0.200.1:10004 PCR discontinuity. external=false internal=true
2017-06-14T09:34:16.523Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:INFO Starting...
2017-06-14T09:34:16.523Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.523Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.523Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.524Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.524Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:DATA Starting...
2017-06-14T09:34:16.524Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.524Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.524Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10002 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:34:16.524Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:34:16.524Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10002:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:34:16.524Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:INFO Created 64 buffers of size 255
2017-06-14T09:34:16.524Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.524Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.524Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.524Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.524Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:INFO Starting...
2017-06-14T09:34:16.525Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.525Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.525Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.525Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:DATA Starting...
2017-06-14T09:34:16.525Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.525Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.525Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10003 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:34:16.525Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:34:16.525Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10003:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:34:16.525Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:INFO Created 64 buffers of size 255
2017-06-14T09:34:16.525Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.525Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.526Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.526Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.526Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:INFO Starting...
2017-06-14T09:34:16.526Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.526Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.526Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.526Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:DATA Starting...
2017-06-14T09:34:16.526Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.526Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.526Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=VideoStreamAnalyzer_INFO_239.0.200.1:10004 type=VideoStreamAnalyzer newType=FilterTypeTransform
2017-06-14T09:34:16.526Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=VideoStreamAnalyzer
2017-06-14T09:34:16.526Z TX_VideoStreamAnalyzer.cpp(90) INFO service1:239.0.200.1:10004:INFO extractCC=yes extractWSS=yes vbiExtractor=active
2017-06-14T09:34:16.527Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:INFO Created 64 buffers of size 255
2017-06-14T09:34:16.527Z CWA_SampleAllocator.cpp(179) INFO VideoStreamAnalyzerVideoStreamAnalyzer_INFO_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.527Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_INFO_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.527Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.527Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:INFO Starting...
2017-06-14T09:34:16.527Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:INFO.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.528Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_INFO_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.528Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SampleQueue_DATA_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.528Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.528Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:DATA Starting...
2017-06-14T09:34:16.528Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:DATA.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.528Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSampleQueue_DATA_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.528Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SimpleScheduler type=simpleSchedulerMimoSession newType=FilterTypeMimo
2017-06-14T09:34:16.528Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=simpleSchedulerMimoSession
2017-06-14T09:34:16.528Z MM_SimpleSchedulerMimoSession.cpp(425) INFO service1 Simple Scheduling thread started
2017-06-14T09:34:16.528Z MM_SimpleSchedulerMimoSession.cpp(128) INFO service1 Initialized
2017-06-14T09:34:16.528Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.528Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.528Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.528Z CWA_SampleAllocator.cpp(179) INFO simpleSchedulerMimoSessionSimpleScheduleroutputPin 239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=PeriodIndexer type=periodIndexerMimoSession newType=FilterTypeMimo
2017-06-14T09:34:16.529Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=periodIndexerMimoSession
2017-06-14T09:34:16.529Z MM_PeriodIndexerMimoSession.cpp(185) INFO service1 Initialized: periodDuration=360000ms initialChunkIndex=0 initialRealPts=0
2017-06-14T09:34:16.529Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10001:DATA Initialized: initialByteOffset=0
2017-06-14T09:34:16.529Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10002:DATA Initialized: initialByteOffset=0
2017-06-14T09:34:16.529Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10003:DATA Initialized: initialByteOffset=0
2017-06-14T09:34:16.529Z MM_PeriodIndexerMimoInputData.cpp(76) INFO service1:239.0.200.1:10004:DATA Initialized: initialByteOffset=0
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin INFO 239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z CWA_SampleAllocator.cpp(179) INFO periodIndexerMimoSessionPeriodIndexeroutputPin DATA 239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.529Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.529Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.530Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.530Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:TS Starting...
2017-06-14T09:34:16.530Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.530Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10001 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.530Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.530Z OS_Thread.cpp(358) WARNING pthread_create failed - prio=5: ret=1 - retrying with lower prio
2017-06-14T09:34:16.530Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.530Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10001 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:34:16.530Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:34:16.530Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.530Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10001 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.530Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.531Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10001:SIDX Starting...
2017-06-14T09:34:16.531Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.531Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.531Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10001 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.531Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.531Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10001:SIDX Created 10 buffers of size 4096
2017-06-14T09:34:16.531Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10001 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.531Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.531Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.532Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:TS Starting...
2017-06-14T09:34:16.532Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.532Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.532Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10002 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.532Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.532Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.532Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10002 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:34:16.532Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:34:16.532Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.532Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10002 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.532Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.532Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10002:SIDX Starting...
2017-06-14T09:34:16.532Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.532Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.533Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10002 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.533Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.533Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10002:SIDX Created 10 buffers of size 4096
2017-06-14T09:34:16.533Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10002 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.533Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.533Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.533Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:TS Starting...
2017-06-14T09:34:16.533Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.533Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.533Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10003 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.533Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.533Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.533Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10003 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:34:16.533Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:34:16.533Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.533Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10003 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.533Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.534Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10003:SIDX Starting...
2017-06-14T09:34:16.534Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.534Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.534Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10003 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.534Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.534Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10003:SIDX Created 10 buffers of size 4096
2017-06-14T09:34:16.534Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10003 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.534Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPubQueue_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.534Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.534Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:TS Starting...
2017-06-14T09:34:16.534Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:TS.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.534Z CWA_SampleAllocator.cpp(179) INFO sampleQueueTsPartPubQueue_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.534Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=TsPartPublisher_239.0.200.1:10004 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.534Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.534Z CWA_SampleAllocator.cpp(179) INFO filePublisherTsPartPublisher_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.534Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SegmentIndexer_239.0.200.1:10004 type=segmentIndexer newType=FilterTypeTransform
2017-06-14T09:34:16.534Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=segmentIndexer
2017-06-14T09:34:16.535Z CWA_SampleAllocator.cpp(179) INFO segmentIndexerSegmentIndexer_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.535Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPubQueue_239.0.200.1:10004 type=sampleQueue newType=FilterTypeTransform
2017-06-14T09:34:16.535Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=sampleQueue
2017-06-14T09:34:16.535Z CWA_SampleQueue.cpp(853) INFO service1:239.0.200.1:10004:SIDX Starting...
2017-06-14T09:34:16.535Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:SIDX.postponedSpliceInfo Created 3 buffers of size 1024
2017-06-14T09:34:16.535Z CWA_SampleAllocator.cpp(179) INFO sampleQueueSidxPubQueue_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.535Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=SidxPublisher_239.0.200.1:10004 type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.535Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.535Z CWA_BufferAllocator.cpp(116) INFO service1:239.0.200.1:10004:SIDX Created 10 buffers of size 4096
2017-06-14T09:34:16.535Z CWA_SampleAllocator.cpp(179) INFO filePublisherSidxPublisher_239.0.200.1:10004 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.535Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=MpdGenerator type=dvrMpdGeneratorSession newType=FilterTypeMixer
2017-06-14T09:34:16.535Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=dvrMpdGeneratorSession
2017-06-14T09:34:16.535Z CWA_BufferAllocator.cpp(116) INFO service1:MPD Created 2 buffers of size 12
2017-06-14T09:34:16.536Z CWA_SampleAllocator.cpp(179) INFO dvrMpdGeneratorSessionMpdGenerator debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.536Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=MpdPublisher type=filePublisher newType=FilterTypeTransform
2017-06-14T09:34:16.536Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=filePublisher
2017-06-14T09:34:16.536Z CWA_SampleAllocator.cpp(179) INFO filePublisherMpdPublisher debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.536Z UWA_GraphBuilder.cpp(1026) INFO Changed unknown filter type. id=PlGenNotifier type=playlistGeneratorNotifierSession newType=FilterTypeMixer
2017-06-14T09:34:16.536Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=playlistGeneratorNotifierSession
2017-06-14T09:34:16.536Z MX_PlaylistGeneratorNotifierSession.cpp(157) INFO service1 Playlist Generator notification URL: http://127.0.0.1:8085/api/catalogs/*/assets/service1/generate
2017-06-14T09:34:16.537Z CWA_SampleAllocator.cpp(179) INFO playlistGeneratorNotifierSessionPlGenNotifier debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.538Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.538Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_1 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:DATA sampleSize=1531824 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:TS sampleSize=1531824 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.538Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_1 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10001:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10001:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.538Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.538Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_2 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:DATA sampleSize=1281972 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.538Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:TS sampleSize=1281972 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_2 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10002:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10002:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.539Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_3 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:DATA sampleSize=781892 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:TS sampleSize=781892 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_3 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10003:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10003:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.539Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_4 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:DATA bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:DATA sampleSize=406832 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:TS bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:TS sampleSize=406832 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleAllocator.cpp(179) INFO mpeg2TsAnalyzerSessionAnalyzer_4 debug=false sampleCnt=0 maxSampleCnt=0 maxSampleSz=0 adapSampleCnt=true adapSampleSz=true
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:INFO bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:INFO sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.539Z CWA_SampleQueue.cpp(299) INFO service1:239.0.200.1:10004:SIDX bufferSize=10 bufferDuration=0 waitWhenFull=0 realTime=0 resyncOnRap=0 updateDelayInFormat=0
2017-06-14T09:34:16.540Z CWA_SampleQueue.cpp(300) INFO service1:239.0.200.1:10004:SIDX sampleSize=0 copySample=0 mtCopy=disabled mtCopySampleSizeThreshold=10000000
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UWA_GraphBuilder.cpp(4043) INFO Add graph reusing clock of existing element
2017-06-14T09:34:16.540Z UT_TaskScheduler.cpp(129) INFO service1 TaskScheduler thread started
2017-06-14T09:34:16.540Z DVR_AlarmManager.cpp(470) INFO service1 Send "event.dvr.status" : reason :
2017-06-14T09:34:16.540Z DVR_GraphManager.cpp(385) INFO service1 Received event event.dvr.status
2017-06-14T09:34:16.540Z DVR_AlarmManager.cpp(431) INFO service1 Raise file create dir error alarm (pp=/opt/mfvp/mnt/nas/sda3store/service1 [path:/opt/mfvp/mnt/nas/sda3store/service1/4][info:Permission denied])
2017-06-14T09:34:16.540Z worker.js(144) INFO service1 status: event.output.status.alarmError service1
2017-06-14T09:34:16.541Z TX_FilePublisherFilter.cpp(1282) ERROR service1:239.0.200.1:10004:TS Failed to (recursively) create directory /opt/mfvp/mnt/nas/sda3store/service1/4: err=3211277 - Permission denied
2017-06-14T09:34:16.541Z CWA_FilterGraph.cpp(778) ERROR Failed to start filter periodIndexerMimoOutput_service1:239.0.200.1:10004:DATA. err=0x31000d: Permission denied
2017-06-14T09:34:16.541Z CWA_FilterGraph.cpp(851) ERROR Failed to Start. err=0x31000d: Permission denied
2017-06-14T09:34:16.541Z worker.js(159) ERROR service1 Alarm error: event.output.status.alarmError - "service1" - ""
2017-06-14T09:34:16.541Z DVR_AlarmManager.cpp(470) INFO service1 Send "event.dvr.status" : reason :
2017-06-14T09:34:16.541Z DVR_GraphManager.cpp(385) INFO service1 Received event event.dvr.status
2017-06-14T09:34:16.541Z TX_FilePublisherFilter.cpp(1282) ERROR service1:239.0.200.1:10004:TS Failed to (recursively) create directory /opt/mfvp/mnt/nas/sda3store/service1/4: err=3211277 - Permission denied
2017-06-14T09:34:16.541Z CWA_FilterGraph.cpp(778) ERROR Failed to start filter periodIndexerMimoOutput_service1:239.0.200.1:10004:DATA. err=0x31000d: Permission denied
2017-06-14T09:34:16.541Z CWA_FilterGraph.cpp(851) ERROR Failed to Start. err=0x31000d: Permission denied
2017-06-14T09:34:16.541Z DVR_GraphManager.cpp(526) ERROR service1 Failed to add output graph. err=0x31000d - Permission denied
2017-06-14T09:34:16.541Z DVR_AlarmManager.cpp(555) INFO service1 Notify DVR recorder error [Record processing creation failure [Permission denied]]
2017-06-14T09:34:16.541Z worker.js(144) INFO service1 status: event.output.status.alarmError service1
2017-06-14T09:34:16.541Z worker.js(159) ERROR service1 Alarm error: event.output.status.alarmError - "service1" - ""
2017-06-14T09:34:16.541Z worker.js(144) INFO service1 status: event.output.status.error service1
2017-06-14T09:34:16.633Z CWO_RedisAlarmDispatcher.cpp(279) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Key alarms/servers/01/dvrRecorder/service1 refreshed for 10s
2017-06-14T09:34:16.633Z CWO_RedisAlarmDispatcher.cpp(302) INFO alarmDispatcher@/var/lib/envivio/alarm/alarm.sock Key "alarms/servers/01/dvrRecorder/service1" available again
2017-06-14T09:34:17.513Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10003 error=0.001211 errorV=0.001175 aveErrorV=0.000117 rateChange=0.000001 rate=1.000001
2017-06-14T09:34:17.558Z DX_Mpeg2TsAnalyzerLiveClock.cpp(161) INFO service1:239.0.200.1:10004 error=0.002422 errorV=0.002340 aveErrorV=0.000234 rateChange=0.000003 rate=1.000003
2017-06-14T09:34:18.543Z UT_LegacyLogImpl.cpp(310) INFO stopping the log system
2017-06-14T09:35:29.937Z worker.js(199) INFO Initializing DVR service1
2017-06-14T09:35:29.938Z worker.js(144) INFO status: event.output.status.stopped service1
2017-06-14T09:35:29.938Z DVR_recorder.cpp(139) INFO service1 Minidump path initialized: /var/log/mfvp/packaging-dvr-recorder/private/service1_13e3c3d3-19c9-0352-44960b38-09b95682.dmp
2017-06-14T09:35:29.943Z refCountController.js(132) INFO service1 Fail to getRefCountBuffer: "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/refcount'"
2017-06-14T09:35:29.964Z mongo.js(36) INFO service1 connected to mongodb
2017-06-14T09:35:29.965Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:29.965Z periodIndexController.js(148) ERROR service1 Fail to getPeriodList: "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:29.968Z CleanupAgent.js(111) INFO service1 CleanupAgent start @ /opt/mfvp/mnt/nas/sda3store/service1/: dvrDuration=3600, deleteSpeed=1.2, minCleanupInterval=1
2017-06-14T09:35:29.969Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:34.970Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:39.970Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:44.973Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:49.974Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:54.976Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:35:59.203Z worker.js(209) INFO service1 Start DVR service1 in 5.0107989041134715 seconds...
2017-06-14T09:35:59.978Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:36:04.214Z worker.js(214) INFO service1 Start DVR service1
2017-06-14T09:36:04.215Z periodIndexController.js(115) ERROR service1 Fail to read period index (/opt/mfvp/mnt/nas/sda3store/service1/index): "File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'"
2017-06-14T09:36:04.215Z worker.js(218) ERROR service1 No previous DVR found for DVR service1 : File open error: Error: ENOENT, open '/opt/mfvp/mnt/nas/sda3store/service1/index'
2017-06-14T09:36:04.217Z DVR_recorder.cpp(276) INFO service1 DVR config:
<?xml version="1.0" encoding="UTF-8"?>
<dvr>
<id>service1</id>
<name>Service1</name>
<inputId>service1</inputId>
<state>stopped</state>
<updatedAt>2017-06-14T09:32:55.278Z</updatedAt>
<gopSignaling>ALD</gopSignaling>
<gopDuration>2000</gopDuration>
<duration>3600</duration>
<sources>
<source>
<port>10001</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10002</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10003</port>
<address>239.0.200.1</address>
</source>
<source>
<port>10004</port>
<address>239.0.200.1</address>
</source>
</sources>
<publishingPoints>
<publishingPoint>/opt/mfvp/mnt/nas/sda3store/service1</publishingPoint>
</publishingPoints>
<serviceId>594102c7818fca0ed83551dd</serviceId>
<tsPartDuration>2000</tsPartDuration>
<tsPartSizeAlignment>188</tsPartSizeAlignment>
<tsPartCount>1</tsPartCount>
<indexFileSizeAlignment>1</indexFileSizeAlignment>
<periodDuration>360</periodDuration>
<createDirectories>true</createDirectories>
<flipFlopIndexFiles>true</flipFlopIndexFiles>
<rozofsExtAttr>false</rozofsExtAttr>
<playlistGeneratorHost>127.0.0.1</playlistGeneratorHost>
<playlistGeneratorPort>8085</playlistGeneratorPort>
<alarmServerId>01</alarmServerId>
<alarmRedisSocket>/var/lib/envivio/alarm/alarm.sock</alarmRedisSocket>
<alarmRedisHost></alarmRedisHost>
<alarmRedisPort>0</alarmRedisPort>
<alarmRedisKeyExpire>10</alarmRedisKeyExpire>
</dvr>
2017-06-14T09:36:04.218Z UT_FileLogMonitor.cpp(115) INFO ADDON_LOG_MONITOR Log file monitoring thread created
2017-06-14T09:36:04.218Z CWA_PluginManager.cpp(160) INFO Preloading all plugins
2017-06-14T09:36:04.218Z CWA_PluginManager.cpp(511) INFO Looking to create object. name=NonExistingFilter
2017-06-14T09:36:04.219Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libpngEncoder.so keys=pngEncoderFilter
2017-06-14T09:36:04.219Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsegmentIndexer.so keys=segmentIndexer
2017-06-14T09:36:04.220Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsamplequeue.so keys=sampleQueue
2017-06-14T09:36:04.227Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvbsubtitlecomposer.so keys=dvbSubtitleComposer
2017-06-14T09:36:04.235Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libwebdavPublisher.so keys=webdavPublisher
2017-06-14T09:36:04.236Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libttxtdecoder.so keys=teletextDecoder
2017-06-14T09:36:04.237Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvbsubtitledecoder.so keys=dvbSubtitleDecoder
2017-06-14T09:36:04.239Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libmpeg2tsanalyzer.so keys=mpeg2TsAnalyzerSession
2017-06-14T09:36:04.240Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libperiodIndexer.so keys=periodIndexerMimoSession
2017-06-14T09:36:04.261Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libplaylistGeneratorNotifier.so keys=playlistGeneratorNotifierSession
2017-06-14T09:36:04.263Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libdvrMpdGenerator.so keys=dvrMpdGeneratorSession
2017-06-14T09:36:04.264Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libttmlFormatter.so keys=ttmlFormatter
2017-06-14T09:36:04.269Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libfilePublisher.so keys=filePublisher
2017-06-14T09:36:04.270Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libvideostreamanalyzer.so keys=VideoStreamAnalyzer
2017-06-14T09:36:04.271Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsimpleScheduler.so keys=simpleSchedulerMimoSession
2017-06-14T09:36:04.271Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libsubtitleIndexer.so keys=subtitleIndexer
2017-06-14T09:36:04.273Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/libmultiipreceiver.so keys=captureMultiIpReceiver
2017-06-14T09:36:04.274Z CWA_PluginManager.cpp(408) WARNING /opt/mfvp/packaging-dvr-recorder/lib/addons/*.so not found. err=0x310002 - No such file or directory
2017-06-14T09:36:04.275Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libpngEncoder.so keys=pngEncoderFilter
2017-06-14T09:36:04.275Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsegmentIndexer.so keys=segmentIndexer
2017-06-14T09:36:04.275Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libsamplequeue.so keys=sampleQueue
2017-06-14T09:36:04.279Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvbsubtitlecomposer.so keys=dvbSubtitleComposer
2017-06-14T09:36:04.285Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libwebdavPublisher.so keys=webdavPublisher
2017-06-14T09:36:04.286Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libttxtdecoder.so keys=teletextDecoder
2017-06-14T09:36:04.286Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvbsubtitledecoder.so keys=dvbSubtitleDecoder
2017-06-14T09:36:04.287Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libmpeg2tsanalyzer.so keys=mpeg2TsAnalyzerSession
2017-06-14T09:36:04.288Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libperiodIndexer.so keys=periodIndexerMimoSession
2017-06-14T09:36:04.305Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libplaylistGeneratorNotifier.so keys=playlistGeneratorNotifierSession
2017-06-14T09:36:04.306Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libdvrMpdGenerator.so keys=dvrMpdGeneratorSession
2017-06-14T09:36:04.306Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libttmlFormatter.so keys=ttmlFormatter
2017-06-14T09:36:04.311Z CWA_PluginManager.cpp(771) INFO Stored plugin. name=/opt/mfvp/packaging-dvr-recorder/lib/../lib/libfilePublisher.so keys=filePublisher