This is an old revision of the document!
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