Satellite Occasional H7S freezing - how can I analyse?

smipx

Newbie
Hi all,

Occasionally my H7S freezes mid way through watching a recording - random times of the day.
Also occasionally I get up in the morning and the word "boot" is on the front panel - this presumably is following a failed nighttime EPG update

The only way to get it back is to power down with the on/off switch on the back. I tried to FTP to the box once when it froze and it timed out (no ping either). When I check the logs under home/root/logs (after the forced reboot) there is no log for the time of the freeze.

I am struggling to get some meaningful logs pointing me in the right place to see what is causing the freezes and could do with a hand please? Is there a set procedure I can foloow to debug / set logging / view stats/logs and generally troubleshoot?

I am running OpenATV 6.3 and mainly (solely) use the box for watching and recording Satellite FTA and also Freeview FTA.

For the EPG
In the morning I have a Power Timer setup to boot the box from "Deep standby" to "standby" at 03:55 and then go back to "deep standby" at 04:45. Inside this window I have a job to run ABM at 04:00. For ABM it is AutoBouquets E2 for 28.2E ; Area Westcountry, Scan mode FTA Only, Sort Option HD First, NIT Scan Ticked, Script errors unticked. It gets the bouquets from the satellite. Then at 04:15 i have CrossEPG set to run against openTV Sky channels on FTA only; Force EPG reload on Boot=Yes. I then have two timers setup in "timers" to do a recording of BBC1 HD on Freeview T2 tuner (to help populate the Freeview EPG) and a following recording to record Satellite C4 HD (as this was not always populating). Both Timers are set to save the recordings directly to .Trash under Media (Trash is set to keep for 5 days).

EPG set to save to my internal hard drive (500Gb) in file in media/HDD called epgatv.dat


Wifi dongle attached in USB at back and working fine for network
I also have the cacheflush plugin set as follows: drop type: pagecache, entries and inodes; clean dirty cache too: yes ; uncached mem size: 20480Kb
I also have movieorganiser set to run every 30 mins. Remove Text "New" = Yes; Run while in standby = Yes.

Versions info:
OpenATV 6.3.0 (2019-12-30)
ABM 2.2+git144
Cacheflush 2.0+git21
Movieorganiser 2.14
Skin: kiddac Slyk Onyx 1080 enigma2-plugin-skins-slyk-onyx-1080_2.25_20191117_all + enigma2-plugin-extensions-openatv-epgselection-mod_3.0_all.ipk Post Nov 2019 + enigma2-plugin-extensions-openatv-movieplanner-mod_2.0_all.ipk



Thanks to anyone willing to help.

Paul
 
Hi, Actually it is mainly freezing (and only very occasionally) when watching a recording. The picture froze and then about 10 seconds later the sound froze (stopped) and then I had to hard reboot. Its only happened twice in a few weeks so I can live with it but more annoying is never quite knowing if I will get up in the morning and see the dreaded "Boot" message on the front panel because of some issue with the EPG night-time work. Just using the standard player for OpenATV as far as I know.


thanks
Paul
 
Why deep standby/standby/ back into deep standby and all that?

Set epgimporter and ABM to run at 6am and 7am and leave the box in standby.
They will run as normal every morning without deep standby being involved and will probably eliminate the boot problem
 
Hi,

I use deep standby to try and save some energy. Is it a bit flakey? The normal recordings etc all seem to work okay when Deep standby is used. The wife gets annoyed if she sees it on when its not being used - you know how it is :-)
 
We are talking about a few pence per year
You can't turn the fridge/freezer off before you go to bed, just leave it in standby as I've a feeling that all these timers/on/off reboot caper are using twice as much juice as just leaving it in standby
 
Blimey - I must be calculating it wrong. I thought in deep standby it used about 2W and in Standby (essentially on) it used about 40W
at 12p/kwh on standby 14 hours per day that's about £23 per year. For one individual its peanuts of course but multiply that sort of figure by each device in a household and that is a lot of KwH and probably a whole powerstation or two.

Maybe I got the maths totally wrong though - I am totally guessing about the 2W and 40W for Deep standby and Standby (essentailly on) on the H7S.
To my mind - if a feature is there to save power - that can only be a good thing - or so Greta tells me :-) :-) :-)
Anyway - if it is there it should at least work properly - assuming that is the problem of course

ps. Don't rise to it (the bit about Greta) - ha ha.

Paul
 
Not sure it uses the same in standby as it does when its on and working but still not sure why you have it setup as laid out in post 1?
in autobouquetmaker/configure
Set it to
Schedule wake from deep standby? - Yes
Schedule return to deep standby? - Yes

In Epgimporter
When in deep standby? - Wake up and import
 
Go into the log settings and enable debug logs, it will tell you what was happening when the freeze occurred.
My Gigablue was doing the same thing when the cache was being cleaned.
 
Hi, Thanks all,

Bless Greta :fearscream:

I have enabled the twisted log and debug log to save to media/hdd and will report back when it next happens (either a freeze or the failure to boot or the failure of crossepg to run). btw I don't use EPGimporter at all. Didn't realize I needed to as I assumed that crossEPG did the import at start as its set like:
1578217185647.png

Do I need to use EPGimporter as well?
.
I have looked at the "AutoBouquets E2 for 28.2E " and also the CrossEPG settings and neither seem to have an option for

Schedule wake from deep standby? - Yes
Schedule return to deep standby? - Yes

That is why I was using a powertimer to turn the box on from Deepstandby to standby and then running "AutoBouquets E2 for 28.2E"
These are my screenshots of the plugin:

1578216406378.png
1578216451515.png
1578216478557.png
1578216519302.png

The "AutoBouquets E2 for 28.2E" ran fine this morning but looking at the crossEPG logs it loos like this did not run. Again, in my CrossEPG there is not an option for wake from Deepstandby to standby hence my methodology. I do want (if it will work) to keep using Deepstandby and making the box do what it is (I assume) meant to be able to do so if there is a better way to do this then I'm all ears :-)

I would prefer to get the EPG from the Satellite rather than Rytek and also go back to deep standby after the event
Not sure why CrossEPG did not run last night.

This is my CrossEPG log from last night - no debugging on at that point so I guess no more logs until it fails again (perhaps 2mo morning:

1578216759988.png
1578216866966.png
 
Hi, My machine has 2x SAT and 1x Freeview and I am only using the CrossEPG to import the satellite. I do a fake recording at 4am(ish) to populate the Freeview tuner EPG so its all populated daily. Hope I am doing right.
 
Well - it froze again this evening. Been using the box all evening watching recordings and live tv and then popped on a recording at 22:35 and at 22:37 the picture froze and then 10 seconds later the sound froze. Had to turn off/on with the power button on back.

Had debugging set but absolutely nada in the logs for 22:37. Just prior I was watching live TV and the logs showed the following.... 33:32 was the last entry..:

22:29:36.1183 [ ] dvb/pmt.cpp:215 PATready [eDVBServicePMTHandler] PATready
22:29:36.1183 [ ] dvb/pmt.cpp:226 PATready [eDVBServicePMTHandler] PAT TSID: 0x4082 (16514)
22:29:36.1183 [ ] dvb/pmt.cpp:255 PATready [eDVBServicePMTHandler] use pmtpid 0064 for service_id 4440
22:29:36.1184 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:29:36.1185 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=100
22:29:36.1190 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:29:36.1191 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=0
22:29:36.1331 [ ] service/servicedvb.cpp:1213 serviceEvent [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
22:29:36.1336 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:29:36.1337 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7108
22:29:36.1343 < > service/servicedvb.cpp:2912 updateDecoder [eDVBServicePlay] have 1 video stream(s) (0065), and 2 audio stream(s) (0066, 006a), and the pcr pid is 0065, and the text pid is ffffffff

22:29:36.1345 [ ] service/servicedvb.cpp:3597 setAC3Delay [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 75
22:29:36.1346 [ ] service/servicedvb.cpp:3608 setPCMDelay [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0
22:29:36.1346 [ ] dvb/decoder.cpp:870 setState [eTSMPEGDecoder] decoder state: play, vpid=0065, apid=0066
22:29:36.1347 < > dvb/decoder.cpp:488 setSlowMotion [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
22:29:36.1347 < > dvb/decoder.cpp:503 setFastForward [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
22:29:36.1347 < > dvb/decoder.cpp:476 unfreeze [eDVBVideo0] VIDEO_CONTINUE ok
22:29:36.1348 < > dvb/decoder.cpp:207 unfreeze [eDVBAudio0] AUDIO_CONTINUE ok
22:29:36.1364 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:29:36.1365 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7108
22:29:36.1438 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:29:36.1439 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=100
22:29:36.2213 [ ] dvb/pmt.cpp:301 AITready [eDVBServicePMTHandler] AITready
22:29:36.2214 [ ] dvb/pmt.cpp:320 AITready [eDVBServicePMTHandler] Section Length : 158, Total Section Length : 161
22:29:36.2214 [ ] dvb/pmt.cpp:331 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : 0, orgid : 384, appid : 101
22:29:36.2216 [ ] dvb/pmt.cpp:295 saveData [eDVBServicePMTHandler] Save Data Len : [161]
22:29:36.2217 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:29:36.2218 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7302
22:29:36.2222 [ ] dvb/pmt.cpp:331 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : 0, orgid : 384, appid : 2
22:29:36.2222 [ ] dvb/pmt.cpp:466 AITready [eDVBServicePMTHandler] Found : control[-2], name[BBC], url[https://www.live.bbctvapps.co.uk/tap/home?broadcast=true]
22:29:36.3197 [ ] service/servicedvb.cpp:1747 timeshift [eDVBServicePlay] timeshift
22:29:37.2514 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 1 160 1
22:29:37.2666 [ ] service/servicedvb.cpp:1630 seekTo [eDVBServicePlay] seekTo 173111541
22:29:37.2667 [ ] dvb/dvb.cpp:1960 cueSheetEvent [eDVBChannel] seek.
22:29:37.2667 [ ] dvb/filepush.cpp:332 pause [eFilePushThread] waiting for pause
22:29:37.2668 [ ] dvb/filepush.cpp:284 thread [eFilePushThread] PAUSED
22:29:37.2669 < > dvb/decoder.cpp:183 flush [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
22:29:37.2910 < > dvb/decoder.cpp:452 flush [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
22:29:37.2983 [ ] dvb/dvb.cpp:2210 getNextSourceSpan [eDVBChannel] ok, resolved skip (rel: 0, diff 173111541), now at 43d1b710
22:29:37.2996 [ ] dvb/dvb.cpp:1946 pvrEvent [eDVBChannel] pvrEvent evtStopped
22:29:37.5619 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 0 160 1
22:29:38.7144 [ ] dvb/frontend.cpp:933 closeFrontend [eDVBFrontend] try to close frontend 0
22:29:42.4511 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 1 160 1
22:29:42.7618 [ ] driver/rcinput.cpp:38 handleCode [eRCDeviceInputDev] 0 160 1
22:29:56.2226 [ ] dvb/pmt.cpp:477 OCready [eDVBServicePMTHandler] OCready
22:32:03.7762 [ ] dvb/pvrparse.cpp:1104 parseData [eMPEGStreamParserTS] SYNC LOST: skipped 188 bytes.

Twisted.log was empty

Don't know what to do next really :-(

Ta
Paul
 
Just in case it offers any clues. I then went on at 22:55 to go into the media player and "resume" watching the recording that hung just a few mins before (it was a BBC sat recording from some days ago). Here is the log file from that new start of the recording. In case it makes any difference - I started the recording remotely via Openwebif and this was refreshing the screen every 5 seconds.... and was also FTP's to the log files at the same time from my PC......

Also. I disabled cacheflush as advised.

Just FYI I also have timeshift set as:

1578265883224.png
1578265921012.png

I mention these things because I can see some references to the items above in the logs.......

22:54:29.8455 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux0
22:54:29.8457 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=20
22:54:31.6430 [ ] dvb/dvbtime.cpp:477 updateTime [eDVBLocalTimerHandler] Transponder time is 05.01.2020 22:54:31
22:54:31.6431 [ ] dvb/dvbtime.cpp:490 updateTime [eDVBLocalTimerHandler] diff is 0
22:54:31.6431 [ ] dvb/dvbtime.cpp:493 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
22:54:31.6432 [ ] dvb/dvbtime.cpp:554 updateTime [eDVBLocalTimerHandler] not changed
22:54:31.6435 [ ] dvb/dvb.cpp:2421 getDemux [eDVBChannel] getDemux cap=00
22:54:36.7630 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux1
22:54:36.7631 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=20
22:54:38.5004 [ ] dvb/dvbtime.cpp:477 updateTime [eDVBLocalTimerHandler] Transponder time is 05.01.2020 22:54:38
22:54:38.5005 [ ] dvb/dvbtime.cpp:490 updateTime [eDVBLocalTimerHandler] diff is 0
22:54:38.5005 [ ] dvb/dvbtime.cpp:493 updateTime [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time
22:54:38.5005 [ ] dvb/dvbtime.cpp:554 updateTime [eDVBLocalTimerHandler] not changed
22:54:38.5008 [ ] dvb/dvb.cpp:2421 getDemux [eDVBChannel] getDemux cap=00
22:56:18.5777 [ ] gdi/epng.cpp:24 loadPNG [ePNG] couldn't open mainmenu/missing.png
22:56:18.6259 [ E ] gdi/accel.cpp:463 accelAlloc [gAccel] accel alloc failed

22:56:18.6260 [ E ] gdi/gpixmap.cpp:216 gSurface [gSurface] ERROR: accelAlloc failed
22:56:19.9521 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:20.1924 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:25.9600 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:26.1852 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:27.4461 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:27.6660 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:31.9647 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:32.1838 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:37.9609 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:38.1820 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:38.4780 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:38.7208 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:43.9661 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:44.1922 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 123(16)
22:56:48.0137 [ ] service/servicedvb.cpp:1747 timeshift [eDVBServicePlay] timeshift
22:56:48.1935 [ E ] gdi/accel.cpp:463 accelAlloc [gAccel] accel alloc failed

22:56:48.1936 [ E ] gdi/gpixmap.cpp:216 gSurface [gSurface] ERROR: accelAlloc failed
22:56:48.7803 [ ] dvb/tstools.cpp:98 setSource [eDVBTSTools] setSource loading streaminfo for /media/hdd/movie/20200105 2159 - Channel 5 HD - Inferno.ts
22:56:49.3936 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:49.6269 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 127(16)
22:56:49.9451 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:50.1700 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 127(16)
22:56:53.9985 [ ] dvb/tstools.cpp:98 setSource [eDVBTSTools] setSource loading streaminfo for /media/hdd/movie/20200105 2159 - Channel 5 HD - Inferno_001.ts
22:56:55.2421 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:55.4757 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 127(16)
22:56:55.9622 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:56.1847 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 127(16)
22:56:57.4739 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:56:57.7046 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 127(16)
22:57:00.1083 [ ] service/servicedvb.cpp:1747 timeshift [eDVBServicePlay] timeshift
22:57:00.1541 [ ] service/servicedvb.cpp:1617 unpause [eDVBServicePlay] unpause
22:57:00.1719 [ ] dvb/filepush.cpp:627 stop [eFilePushThreadRecorder] stopping thread.
22:57:00.1723 [ ] dvb/demux.cpp:632 flush [eDVBRecordFileThread] waiting for aio to complete
22:57:00.1724 [ ] dvb/demux.cpp:501 wait [eDVBRecordFileThread] Waiting for I/O to complete
22:57:00.1729 [ ] dvb/demux.cpp:638 flush [eDVBRecordFileThread] buffer usage histogram (40 buffers of 192 kB)
22:57:00.1729 [ ] dvb/demux.cpp:642 flush [eDVBRecordFileThread] 0: 1
22:57:00.1729 [ ] dvb/demux.cpp:642 flush [eDVBRecordFileThread] 1: 4968
22:57:00.2711 [ ] dvb/filepush.cpp:611 thread [eFilePushThreadRecorder] THREAD STOP
22:57:00.2737 [ ] service/servicedvb.cpp:2610 stopTimeshift [eDVBServicePlay] remove timeshift files
22:57:00.2739 [ ] base/thread.cpp:66 runAsync [eThread] old thread joined 0
22:57:00.2740 [ ] base/ioprio.cpp:91 setIoPrio [setIoPrio] best-effort level 7 ok
22:57:00.2741 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/timeshift.ZRmG4d.del'
22:57:00.2742 [ ] components/file_eraser.cpp:88 gotMessage [eBackgroundFileEraser] deleting '/media/hdd/timeshift/timeshift.ZRmG4d.sc.del'
22:57:00.2742 [ ] dvb/cahandler.cpp:333 unregisterService [eDVBCAService] free slot 0 demux 1 for service 1:0:19:1B1D:802:2:11A0000:0:0:0:
22:57:00.2743 [ ] dvb/cahandler.cpp:497 ~eDVBCAService [eDVBCAService] free service 1:0:19:1B1D:802:2:11A0000:0:0:0:
22:57:00.2808 [ ] service/servicedvb.cpp:1747 timeshift [eDVBServicePlay] timeshift
22:57:00.2811 [ ] service/servicedvb.cpp:1747 timeshift [eDVBServicePlay] timeshift
22:57:00.2812 [ ] service/servicedvb.cpp:1747 timeshift [eDVBServicePlay] timeshift
22:57:00.2821 [ ] dvb/decoder.cpp:870 setState [eTSMPEGDecoder] decoder state: play, vpid=ffffffff, apid=ffffffff
22:57:00.2821 < > dvb/decoder.cpp:762 stop [eDVBPCR0] DEMUX_STOP ok
22:57:00.2822 [ ] dvb/decoder.cpp:773 ~eDVBPCR [eDVBPCR0] destroy
22:57:00.2822 < > dvb/decoder.cpp:431 stop [eDVBVideo0] DEMUX_STOP ok
22:57:00.2822 < > dvb/decoder.cpp:440 stop [eDVBVideo0] VIDEO_STOP ok
22:57:00.2833 [ ] dvb/decoder.cpp:532 ~eDVBVideo [eDVBVideo0] destroy
22:57:00.2834 < > dvb/decoder.cpp:163 stop [eDVBAudio0] AUDIO_STOP ok
22:57:00.3069 < > dvb/decoder.cpp:171 stop [eDVBAudio0] DEMUX_STOP ok
22:57:00.3070 < > dvb/decoder.cpp:207 unfreeze [eDVBAudio0] AUDIO_CONTINUE ok
22:57:00.3070 [ ] dvb/decoder.cpp:251 ~eDVBAudio [eDVBAudio0] destroy
22:57:00.3070 < > dvb/decoder.cpp:840 stop [eDVBText0] DEMUX_STOP ok
22:57:00.3071 [ ] dvb/decoder.cpp:851 ~eDVBTText [eDVBText0] destroy
22:57:00.3091 [ ] dvb/dvb.cpp:1497 DVBChannelStateChanged [eDVBResourceManager] start release channel timer
22:57:00.3098 [ ] dvb/pmt.cpp:1087 tuneExt [eDVBServicePMTHandler] alloc PVR
22:57:00.3099 [ ] dvb/dvbtime.cpp:700 DVBChannelStateChanged [eDVBLocalTimerHandler] remove channel 0x10de6a8
22:57:00.3099 [ ] dvb/epgcache.cpp:710 DVBChannelStateChanged [eEPGCache] remove channel 0x10de6a8
22:57:00.3107 [ ] dvb/epgcache.cpp:2400 abortEPG [eEPGCache] abort caching events !!
22:57:00.3141 [ ] dvb/dvb.cpp:2421 getDemux [eDVBChannel] getDemux cap=00
22:57:00.3141 [ ] dvb/dvb.cpp:1105 allocateDemux [eDVBResourceManager] allocate demux cap=00
22:57:00.3142 [ ] dvb/dvb.cpp:1281 allocateDemux [eDVBResourceManager] allocating demux adapter=0, demux=7, source=-1
22:57:00.3142 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.3143 [ ] dvb/tstools.cpp:98 setSource [eDVBTSTools] setSource loading streaminfo for /media/hdd/movie/20191222 2059 - BBC TWO HD (T) - The Christmas Misadventures of___.ts
22:57:00.3315 [ ] dvb/demux.cpp:98 openDVR [eDVBDemux] open dvr /dev/dvb/adapter0/dvr7
22:57:00.3320 [ ] service/servicedvb.cpp:3116 loadCuesheet [eDVBServicePlay] cuts file has 2 entries
22:57:00.3322 [ ] dvb/tstools.cpp:498 calcBegin [eDVBTSTools] calcBegin [@ML] m_streaminfo.getLastFrame returned 13912, 1400275400 (15558s), fixup to: 13912, 1941 (0s)
22:57:00.3350 [ ] base/ioprio.cpp:91 setIoPrio [setIoPrio] best-effort level 0 ok
22:57:00.3350 [ ] dvb/pmt.cpp:78 channelStateChanged [eDVBServicePMTHandler] ok ... now we start!!
22:57:00.3351 [ ] dvb/filepush.cpp:61 thread [eFilePushThread] START thread
22:57:00.3351 [ ] service/servicedvb.cpp:1213 serviceEvent [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
22:57:00.3351 < > service/servicedvb.cpp:2912 updateDecoder [eDVBServicePlay] have 1 video stream(s) (0065), and 1 audio stream(s) (0066), and the pcr pid is 0065, and the text pid is ffffffff

22:57:00.3388 [ ] service/servicedvb.cpp:3597 setAC3Delay [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 75
22:57:00.3390 [ ] service/servicedvb.cpp:3608 setPCMDelay [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0
22:57:00.3390 [ ] dvb/decoder.cpp:870 setState [eTSMPEGDecoder] decoder state: play, vpid=0065, apid=0066
22:57:00.3391 < > dvb/decoder.cpp:84 startPid [eDVBAudio0] DMX_SET_PES_FILTER pid=0x0066 ok
22:57:00.3392 < > dvb/decoder.cpp:92 startPid [eDVBAudio0] DEMUX_START ok
22:57:00.3393 < > dvb/decoder.cpp:142 startPid [eDVBAudio0] AUDIO_SET_BYPASS bypass=9 ok
22:57:00.3393 < > dvb/decoder.cpp:195 freeze [eDVBAudio0] AUDIO_PAUSE ok
22:57:00.3393 < > dvb/decoder.cpp:150 startPid [eDVBAudio0] AUDIO_PLAY ok
22:57:00.3415 [ ] dvb/decoder.cpp:269 eDVBVideo [eDVBVideo] Video Device: /dev/dvb/adapter0/video0
22:57:00.3416 [ ] dvb/decoder.cpp:280 eDVBVideo [eDVBVideo] demux device: /dev/dvb/adapter0/demux7
22:57:00.3417 < > dvb/decoder.cpp:360 startPid [eDVBVideo0] VIDEO_SET_STREAMTYPE 1 - ok
22:57:00.3417 < > dvb/decoder.cpp:395 startPid [eDVBVideo0] DMX_SET_PES_FILTER pid=0x0065 ok
22:57:00.3417 < > dvb/decoder.cpp:403 startPid [eDVBVideo0] DEMUX_START ok
22:57:00.3418 < > dvb/decoder.cpp:464 freeze [eDVBVideo0] VIDEO_FREEZE ok
22:57:00.3419 < > dvb/decoder.cpp:418 startPid [eDVBVideo0] VIDEO_PLAY ok
22:57:00.3471 < > dvb/decoder.cpp:488 setSlowMotion [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
22:57:00.3472 < > dvb/decoder.cpp:503 setFastForward [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
22:57:00.3472 < > dvb/decoder.cpp:476 unfreeze [eDVBVideo0] VIDEO_CONTINUE ok
22:57:00.3472 < > dvb/decoder.cpp:207 unfreeze [eDVBAudio0] AUDIO_CONTINUE ok
22:57:00.3474 [ ] dvb/teletext.cpp:222 eDVBTeletextParser [eDVBTeletextParser] Starting!
22:57:00.3474 [ ] dvb/teletext.cpp:645 setPageAndMagazine [eDVBTeletextParser] disable teletext subtitles page ffffffffffffffff (und)
22:57:00.3474 [!W!] dvb/demux.cpp:361 eDVBPESReader [eDVBPESReader] Created. Opening demux
22:57:00.3474 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.3475 [ ] dvb/teletext.cpp:230 eDVBTeletextParser [eDVBTeletextParser] created teletext subtitle PES reader!
22:57:00.3475 [!W!] dvb/demux.cpp:361 eDVBPESReader [eDVBPESReader] Created. Opening demux
22:57:00.3475 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.3476 [ ] dvb/teletext.cpp:463 start [eDVBTeletextParser] starting PES reader on pid=ffffffff
22:57:00.3477 [ ] service/servicedvb.cpp:1630 seekTo [eDVBServicePlay] seekTo 0
22:57:00.3477 [ ] dvb/dvb.cpp:1960 cueSheetEvent [eDVBChannel] seek.
22:57:00.3477 [ ] dvb/filepush.cpp:332 pause [eFilePushThread] waiting for pause
22:57:00.3525 [ ] dvb/filepush.cpp:284 thread [eFilePushThread] PAUSED
22:57:00.3526 < > dvb/decoder.cpp:183 flush [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
22:57:00.3742 < > dvb/decoder.cpp:452 flush [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
22:57:00.3802 [ ] dvb/dvb.cpp:2210 getNextSourceSpan [eDVBChannel] ok, resolved skip (rel: 0, diff 0), now at 00000000
22:57:00.3936 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.3937 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=0
22:57:00.3945 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.3946 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=18
22:57:00.4452 [ ] service/servicedvb.cpp:1617 unpause [eDVBServicePlay] unpause
22:57:00.6405 [ ] dvb/dvb.cpp:1946 pvrEvent [eDVBChannel] pvrEvent evtStopped
22:57:00.6407 < > dvb/decoder.cpp:547 video_event [eDVBVideo0] VIDEO_GET_EVENT FRAME_RATE_CHANGED 25000 fps

22:57:00.6411 < > dvb/decoder.cpp:547 video_event [eDVBVideo0] VIDEO_GET_EVENT SIZE_CHANGED 1920x1080 aspect 3

22:57:00.6528 < > dvb/decoder.cpp:547 video_event [eDVBVideo0] VIDEO_GET_EVENT PROGRESSIVE_CHANGED 1

22:57:00.6534 [ ] dvb/pmt.cpp:215 PATready [eDVBServicePMTHandler] PATready
22:57:00.6535 [ ] dvb/pmt.cpp:226 PATready [eDVBServicePMTHandler] PAT TSID: 0x4082 (16514)
22:57:00.6535 [ ] dvb/pmt.cpp:255 PATready [eDVBServicePMTHandler] use pmtpid 0064 for service_id 4440
22:57:00.6536 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.6537 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=100
22:57:00.6541 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.6541 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=0
22:57:00.6605 [ ] service/servicedvb.cpp:1213 serviceEvent [eDVBServicePlay] eventNewProgramInfo timeshift_enabled=0 timeshift_active=0
22:57:00.6610 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.6611 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7108
22:57:00.6618 < > service/servicedvb.cpp:2912 updateDecoder [eDVBServicePlay] have 1 video stream(s) (0065), and 2 audio stream(s) (0066, 006a), and the pcr pid is 0065, and the text pid is ffffffff

22:57:00.6622 [ ] service/servicedvb.cpp:3597 setAC3Delay [eDVBServicePlay] Setting audio delay: setAC3Delay, 0 + 75
22:57:00.6623 [ ] service/servicedvb.cpp:3608 setPCMDelay [eDVBServicePlay] Setting audio delay: setPCMDelay, 0 + 0
22:57:00.6623 [ ] dvb/decoder.cpp:870 setState [eTSMPEGDecoder] decoder state: play, vpid=0065, apid=0066
22:57:00.6624 < > dvb/decoder.cpp:488 setSlowMotion [eDVBVideo0] VIDEO_SLOWMOTION 0 ok
22:57:00.6624 < > dvb/decoder.cpp:503 setFastForward [eDVBVideo0] VIDEO_FAST_FORWARD 0 ok
22:57:00.6624 < > dvb/decoder.cpp:476 unfreeze [eDVBVideo0] VIDEO_CONTINUE ok
22:57:00.6625 < > dvb/decoder.cpp:207 unfreeze [eDVBAudio0] AUDIO_CONTINUE ok
22:57:00.6642 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.6643 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7108
22:57:00.6721 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.6722 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=100
22:57:00.7611 [ ] dvb/pmt.cpp:301 AITready [eDVBServicePMTHandler] AITready
22:57:00.7612 [ ] dvb/pmt.cpp:320 AITready [eDVBServicePMTHandler] Section Length : 158, Total Section Length : 161
22:57:00.7612 [ ] dvb/pmt.cpp:331 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : ad6f50a8, orgid : 384, appid : 101
22:57:00.7614 [ ] dvb/pmt.cpp:295 saveData [eDVBServicePMTHandler] Save Data Len : [161]
22:57:00.7614 [ ] dvb/demux.cpp:90 openDemux [eDVBDemux] open demux /dev/dvb/adapter0/demux7
22:57:00.7615 [ ] dvb/demux.cpp:286 start [eDVBSectionReader] DMX_SET_FILTER pid=7302
22:57:00.7620 [ ] dvb/pmt.cpp:331 AITready [eDVBServicePMTHandler] found applicaions ids >> pid : ad6f50a8, orgid : 384, appid : 2
22:57:00.7621 [ ] dvb/pmt.cpp:466 AITready [eDVBServicePMTHandler] Found : control[-2], name[BBC], url[https://www.live.bbctvapps.co.uk/tap/home?broadcast=true]
22:57:01.6935 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:57:01.9245 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 96(16)
22:57:01.9560 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:57:02.1614 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 96(16)
22:57:03.3140 [ ] dvb/frontend.cpp:933 closeFrontend [eDVBFrontend] try to close frontend 1
22:57:03.3985 [ ] service/servicedvb.cpp:1630 seekTo [eDVBServicePlay] seekTo 173111541
22:57:03.3985 [ ] dvb/dvb.cpp:1960 cueSheetEvent [eDVBChannel] seek.
22:57:03.3986 [ ] dvb/filepush.cpp:332 pause [eFilePushThread] waiting for pause
22:57:03.3987 [ ] dvb/filepush.cpp:284 thread [eFilePushThread] PAUSED
22:57:03.3987 < > dvb/decoder.cpp:183 flush [eDVBAudio0] AUDIO_CLEAR_BUFFER ok
22:57:03.4229 < > dvb/decoder.cpp:452 flush [eDVBVideo0] VIDEO_CLEAR_BUFFER ok
22:57:03.4299 [ ] dvb/dvb.cpp:2210 getNextSourceSpan [eDVBChannel] ok, resolved skip (rel: 0, diff 173111541), now at 43d1b710
22:57:03.4320 [ ] dvb/dvb.cpp:1946 pvrEvent [eDVBChannel] pvrEvent evtStopped
22:57:04.4872 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:57:04.6934 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 94(16)
.... Repeated 2 lines many times.......
22:59:43.9713 [ ] base/console.cpp:110 execute [eConsoleAppContainer] Starting /usr/bin/grab
22:59:44.1817 [ ] base/ebase.cpp:279 processOneEvent [eMainloop::processOneEvent] unhandled POLLERR/HUP/NVAL for fd 92(16)
 
twisted log:

Twisted log:
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET / HTTP/1.1" 200 25893 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /web/css HTTP/1.1" 200 41 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /api/statusinfo?_=1578264969888 HTTP/1.1" 200 360 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /api/getlocations HTTP/1.1" 200 92 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /api/gettags HTTP/1.1" 200 206 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /static/remotes/zgemma3/remote.html HTTP/1.1" 304 - "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /ajax/tv HTTP/1.1" 200 400 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /static/remotes/zgemma3/rc.png HTTP/1.1" 304 - "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:10+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /ajax/bouquets HTTP/1.1" 200 1237 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:18+0000 [-] KEY: 139 0 KEY_MENU MENU
2020-01-05 22:56:18+0000 [-] [ActionMap] Keymap 'InfobarMenuActions' -> Action = 'mainMenu'.
2020-01-05 22:56:18+0000 [-] [SKIN] processing screen Menu:
2020-01-05 22:56:18+0000 [-] warning, skin is missing element blue in <class 'Screens.Menu.MainMenu'>
2020-01-05 22:56:18+0000 [-] warning, skin is missing element green in <class 'Screens.Menu.MainMenu'>
2020-01-05 22:56:18+0000 [-] warning, skin is missing element yellow in <class 'Screens.Menu.MainMenu'>
2020-01-05 22:56:18+0000 [-] [SKIN] processing screen MenuSummary:
2020-01-05 22:56:18+0000 [-] [SCREENNAME] MenuSummary
2020-01-05 22:56:18+0000 [-] [SCREENNAME] ['menu_mainmenu', 'Menu']
2020-01-05 22:56:18+0000 [-] KEY: 139 1 KEY_MENU MENU
2020-01-05 22:56:18+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:09 +0000] "GET /api/remotecontrol?command=139&_=1578264969889 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:19+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:18 +0000] "GET /api/pipinfo?_=1578264969890 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:19+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:19 +0000] "GET /ajax/screenshot HTTP/1.1" 200 859 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:20+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:19 +0000] "GET /grab?format=jpg&mode=all&T=1578264980045 HTTP/1.1" 200 271072 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:24+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:20 +0000] "GET /api/statusinfo?_=1578264969891 HTTP/1.1" 200 360 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:25+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:24 +0000] "GET /api/pipinfo?_=1578264969892 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:26+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:25 +0000] "GET /grab?format=jpg&mode=all&T=1578264986048 HTTP/1.1" 200 284843 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:26+0000 [-] KEY: 174 0 KEY_EXIT EXIT
2020-01-05 22:56:26+0000 [-] [ActionMap] Keymap 'WizardActions' -> Unknown action 'back'! (Typo in keymap?)
2020-01-05 22:56:26+0000 [-] [ActionMap] Keymap 'OkCancelActions' -> Action = 'cancel'.
2020-01-05 22:56:26+0000 [-] [SCREENNAME] InfoBarSummary
2020-01-05 22:56:26+0000 [-] KEY: 174 1 KEY_EXIT EXIT
2020-01-05 22:56:26+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:25 +0000] "GET /api/remotecontrol?command=174&_=1578264969893 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:27+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:26 +0000] "GET /api/pipinfo?_=1578264969894 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:27+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:26 +0000] "GET /grab?format=jpg&mode=all&T=1578264987539 HTTP/1.1" 200 512990 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:31+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:26 +0000] "GET /api/pipinfo?_=1578264969895 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:32+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:31 +0000] "GET /grab?format=jpg&mode=all&T=1578264992052 HTTP/1.1" 200 452240 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:37+0000 [-] KEY: 59 0 KEY_F1
2020-01-05 22:56:37+0000 [-] KEY: 59 1 KEY_F1
2020-01-05 22:56:37+0000 [-] [ActionMap] Keymap 'InfobarExtensions' -> Unknown action 'RedPressed'! (Typo in keymap?)
2020-01-05 22:56:37+0000 [-] [ActionMap] Keymap 'InfobarActions' -> Action = 'showWWW'.
2020-01-05 22:56:37+0000 [-] [ActionMap] Keymap(s) 'MsgBoxActions, DirectionActions' -> Undefined action(s) 'downRepeated, upRepeated, rightRepeated, leftRepeated'.
2020-01-05 22:56:37+0000 [-] [SKIN] processing screen MessageBox:
2020-01-05 22:56:37+0000 [-] [SKIN] processing screen MessageBox_summary:
2020-01-05 22:56:37+0000 [-] [SCREENNAME] ['MessageBox_summary', 'SimpleSummary']
2020-01-05 22:56:37+0000 [-] [SCREENNAME] ['MessageBox']
2020-01-05 22:56:37+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:33 +0000] "GET /api/remotecontrol?command=59&_=1578264969896 HTTP/1.1" 200 85 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:37+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:37 +0000] "GET /api/pipinfo?_=1578264969897 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:38+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:37 +0000] "GET /grab?format=jpg&mode=all&T=1578264998054 HTTP/1.1" 200 544045 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:38+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:38 +0000] "GET /api/pipinfo?_=1578264969898 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:38+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:38 +0000] "GET /grab?format=jpg&mode=all&T=1578264998576 HTTP/1.1" 200 537543 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:39+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:39 +0000] "GET /api/statusinfo?_=1578264969899 HTTP/1.1" 200 360 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:43+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:39 +0000] "GET /api/pipinfo?_=1578264969900 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:44+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:43 +0000] "GET /grab?format=jpg&mode=all&T=1578265004057 HTTP/1.1" 200 312012 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:46+0000 [-] Timeout!
2020-01-05 22:56:46+0000 [-] [SCREENNAME] InfoBarSummary
2020-01-05 22:56:48+0000 [-] KEY: 393 0 KEY_VIDEO PVR
2020-01-05 22:56:48+0000 [-] KEY: 393 1 KEY_VIDEO PVR
2020-01-05 22:56:48+0000 [-] [ActionMap] Keymap 'InfobarActions' -> Action = 'showMovies'.
2020-01-05 22:56:48+0000 [-] [ActionMap] Keymap(s) 'InfobarActions' -> Undefined action(s) 'showText'.
2020-01-05 22:56:48+0000 [-] [ActionMap] Keymap(s) 'MovielistSeekActions' -> Undefined action(s) 'seekFwdManual, seekBackManual'.
2020-01-05 22:56:48+0000 [-] [SKIN] processing screen MovieSelection:
2020-01-05 22:56:48+0000 [-] warning, skin is missing element TrashcanSize in <class 'Screens.MovieSelection.MovieSelection'>
2020-01-05 22:56:48+0000 [-] warning, skin is missing element DescriptionBorder in <class 'Screens.MovieSelection.MovieSelection'>
2020-01-05 22:56:48+0000 [-] [SKIN] No skin to read...
2020-01-05 22:56:48+0000 [-] [SKIN] processing screen <embedded-in-'MovieSelectionSummary'>:
2020-01-05 22:56:48+0000 [-] [SCREENNAME] MovieSelectionSummary
2020-01-05 22:56:48+0000 [-] [SCREENNAME] MovieSelection
2020-01-05 22:56:48+0000 [-] [SCREENNAME] MovieSelection
2020-01-05 22:56:48+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:46 +0000] "GET /api/remotecontrol?command=393&_=1578264969901 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:48+0000 [-] setPixmapNum(7) failed! defined pixmaps: [<enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e8938> >, <enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e8950> >, <enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e8968> >, <enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e8998> >, <enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e89b0> >, <enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e8848> >, <enigma.gPixmapPtr; proxy of <Swig Object of type 'ePtr< gPixmap > *' at 0xad6e89c8> >]
2020-01-05 22:56:49+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:49 +0000] "GET /api/pipinfo?_=1578264969902 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:49+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:49 +0000] "GET /grab?format=jpg&mode=all&T=1578265009484 HTTP/1.1" 200 473582 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:49+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:49 +0000] "GET /api/pipinfo?_=1578264969903 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:50+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:49 +0000] "GET /grab?format=jpg&mode=all&T=1578265010046 HTTP/1.1" 200 477764 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:53+0000 [-] KEY: 108 0 KEY_DOWN DOWN
2020-01-05 22:56:53+0000 [-] [ActionMap] Keymap 'DirectionActions' -> Action = 'down'.
2020-01-05 22:56:53+0000 [-] KEY: 108 1 KEY_DOWN DOWN
2020-01-05 22:56:53+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:50 +0000] "GET /api/remotecontrol?command=108&_=1578264969904 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:54+0000 [-] KEY: 108 0 KEY_DOWN DOWN
2020-01-05 22:56:54+0000 [-] [ActionMap] Keymap 'DirectionActions' -> Action = 'down'.
2020-01-05 22:56:54+0000 [-] KEY: 108 1 KEY_DOWN DOWN
2020-01-05 22:56:54+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:53 +0000] "GET /api/remotecontrol?command=108&_=1578264969905 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:54+0000 [-] KEY: 108 0 KEY_DOWN DOWN
2020-01-05 22:56:54+0000 [-] [ActionMap] Keymap 'DirectionActions' -> Action = 'down'.
2020-01-05 22:56:54+0000 [-] KEY: 108 1 KEY_DOWN DOWN
2020-01-05 22:56:54+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:53 +0000] "GET /api/remotecontrol?command=108&_=1578264969906 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:54+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:54 +0000] "GET /api/statusinfo?_=1578264969907 HTTP/1.1" 200 360 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:55+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:54 +0000] "GET /api/pipinfo?_=1578264969908 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:55+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:54 +0000] "GET /grab?format=jpg&mode=all&T=1578265015333 HTTP/1.1" 200 483672 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:55+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:54 +0000] "GET /api/pipinfo?_=1578264969909 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:56+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:55 +0000] "GET /grab?format=jpg&mode=all&T=1578265016054 HTTP/1.1" 200 483288 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:56+0000 [-] KEY: 108 0 KEY_DOWN DOWN
2020-01-05 22:56:56+0000 [-] [ActionMap] Keymap 'DirectionActions' -> Action = 'down'.
2020-01-05 22:56:56+0000 [-] KEY: 108 1 KEY_DOWN DOWN
2020-01-05 22:56:56+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:55 +0000] "GET /api/remotecontrol?command=108&_=1578264969910 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:57+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:57 +0000] "GET /api/pipinfo?_=1578264969911 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:56:57+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:57 +0000] "GET /grab?format=jpg&mode=all&T=1578265017562 HTTP/1.1" 200 463610 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:00+0000 [-] KEY: 352 0 KEY_OK OK
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap 'OkCancelActions' -> Action = 'ok'.
2020-01-05 22:57:00+0000 [-] [SCREENNAME] InfoBarSummary
2020-01-05 22:57:00+0000 [-] KEY: 352 1 KEY_OK OK
2020-01-05 22:57:00+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:56:57 +0000] "GET /api/remotecontrol?command=352&_=1578264969912 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen Screensaver:
2020-01-05 22:57:00+0000 [-] [SKIN] Parsing embedded skin <embedded-in-'HideVBILine'>
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen <embedded-in-'HideVBILine'>:
2020-01-05 22:57:00+0000 [-] [SCREENNAME] HideVBILine
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'InfobarMenuActions' -> Undefined action(s) 'showHDMIRecord, showSystemSetup'.
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'InfobarEPGActions' -> Undefined action(s) 'showInfobarOrEpgWhenInfobarAlreadyVisible, showEventGuidePlugin'.
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'InfobarSeekActions' -> Undefined action(s) 'okButton'.
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'InfobarEPGActions' -> Undefined action(s) 'showEventInfo, showInfobarOrEpgWhenInfobarAlreadyVisible'.
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen PVRState:
2020-01-05 22:57:00+0000 [-] warning, skin is missing element eventname in <class 'Screens.PVRState.PVRState'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element state in <class 'Screens.PVRState.PVRState'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element statusicon in <class 'Screens.PVRState.PVRState'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element speed in <class 'Screens.PVRState.PVRState'>
2020-01-05 22:57:00+0000 [-] RemovePopup, id = ZapError
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'InfobarExtensions' -> Undefined action(s) 'showDreamPlex, showMediaPlayer'.
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'InfobarZoomActions' -> Undefined action(s) 'ZoomInOut, ZoomOff'.
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'ButtonSetupActions' -> Undefined action(s) 'skip_forward, end_long, skip_back'.
2020-01-05 22:57:00+0000 [-] playing 1:0:0:0:0:0:0:0:0:0:/media/hdd/movie/20191222 2059 - BBC TWO HD (T) - The Christmas Misadventures of___.ts
2020-01-05 22:57:00+0000 [-] RemovePopup, id = ZapError
2020-01-05 22:57:00+0000 [-] setPixmapNum(0) failed! defined pixmaps: []
2020-01-05 22:57:00+0000 [-] setPixmapNum(0) failed! defined pixmaps: []
2020-01-05 22:57:00+0000 [-] RemovePopup, id = ZapError
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen MoviePlayer:
2020-01-05 22:57:00+0000 [-] warning, skin is missing element key_blue in <class 'Screens.InfoBar.MoviePlayer'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element state in <class 'Screens.InfoBar.MoviePlayer'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element key_green in <class 'Screens.InfoBar.MoviePlayer'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element eventname in <class 'Screens.InfoBar.MoviePlayer'>
2020-01-05 22:57:00+0000 [-] warning, skin is missing element key_yellow in <class 'Screens.InfoBar.MoviePlayer'>
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen InfoBarMoviePlayerSummary:
2020-01-05 22:57:00+0000 [-] warning, skin is missing element statusicon_summary in <class 'Screens.InfoBarGenerics.InfoBarMoviePlayerSummary'>
2020-01-05 22:57:00+0000 [-] [SCREENNAME] InfoBarMoviePlayerSummary
2020-01-05 22:57:00+0000 [-] [ActionMap] Keymap(s) 'MsgBoxActions, DirectionActions' -> Undefined action(s) 'downRepeated, upRepeated, rightRepeated, leftRepeated'.
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen MessageBoxSimple:
2020-01-05 22:57:00+0000 [-] [SKIN] processing screen MessageBoxSimple_summary:
2020-01-05 22:57:00+0000 [-] [SCREENNAME] ['MessageBoxSimple_summary', 'SimpleSummary']
2020-01-05 22:57:00+0000 [-] [SCREENNAME] ['MessageBoxSimple']
2020-01-05 22:57:01+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:01 +0000] "GET /api/pipinfo?_=1578264969913 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:01+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:01 +0000] "GET /grab?format=jpg&mode=all&T=1578265021791 HTTP/1.1" 200 261505 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:01+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:01 +0000] "GET /api/pipinfo?_=1578264969914 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:02+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:01 +0000] "GET /grab?format=jpg&mode=all&T=1578265022046 HTTP/1.1" 200 294606 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:03+0000 [-] KEY: 352 0 KEY_OK OK
2020-01-05 22:57:03+0000 [-] [ActionMap] Keymap 'MsgBoxActions' -> Action = 'ok'.
2020-01-05 22:57:03+0000 [-] [SCREENNAME] InfoBarMoviePlayerSummary
2020-01-05 22:57:03+0000 [-] KEY: 352 1 KEY_OK OK
2020-01-05 22:57:03+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:01 +0000] "GET /api/remotecontrol?command=352&_=1578264969915 HTTP/1.1" 200 86 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:03+0000 [-] [SCREENNAME] MoviePlayer
2020-01-05 22:57:04+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:03 +0000] "GET /api/pipinfo?_=1578264969916 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:04+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:04 +0000] "GET /grab?format=jpg&mode=all&T=1578265024577 HTTP/1.1" 200 304053 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:07+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:04 +0000] "GET /api/pipinfo?_=1578264969917 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:08+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:07 +0000] "GET /grab?format=jpg&mode=all&T=1578265028048 HTTP/1.1" 200 291465 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:09+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:09 +0000] "GET /api/statusinfo?_=1578264969918 HTTP/1.1" 200 422 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:09+0000 [-] Timing out client: IPv6Address(TCP, '::ffff:192.168.1.13', 60789)
2020-01-05 22:57:13+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:09 +0000] "GET /api/pipinfo?_=1578264969919 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:13+0000 [-] Timing out client: IPv6Address(TCP, '::ffff:192.168.1.13', 60795)
2020-01-05 22:57:14+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:13 +0000] "GET /grab?format=jpg&mode=all&T=1578265034052 HTTP/1.1" 200 185256 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:19+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:13 +0000] "GET /api/pipinfo?_=1578264969920 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:20+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:19 +0000] "GET /grab?format=jpg&mode=all&T=1578265040057 HTTP/1.1" 200 187270 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:24+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:19 +0000] "GET /api/statusinfo?_=1578264969921 HTTP/1.1" 200 422 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:25+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:24 +0000] "GET /api/pipinfo?_=1578264969922 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:26+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:25 +0000] "GET /grab?format=jpg&mode=all&T=1578265046059 HTTP/1.1" 200 264425 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:31+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:31 +0000] "GET /api/pipinfo?_=1578264969923 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:32+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:31 +0000] "GET /grab?format=jpg&mode=all&T=1578265052059 HTTP/1.1" 200 265334 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:37+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:37 +0000] "GET /api/pipinfo?_=1578264969924 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:38+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:37 +0000] "GET /grab?format=jpg&mode=all&T=1578265058059 HTTP/1.1" 200 249501 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 22:57:39+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:22:57:38 +0000] "GET /api/statusinfo?_=1578264969925 HTTP/1.1" 200 422 "http://192.168.1.251/" "
 
..
..
2020-01-05 23:00:25+0000 [-] SerienRecorder plugin not found
2020-01-05 23:03:40+0000 [-] starting thread :writeable
2020-01-05 23:03:40+0000 [-] finished thread :writeable
2020-01-05 23:03:40+0000 [-] starting thread :freespace
2020-01-05 23:03:40+0000 [-] finished thread :freespace
2020-01-05 23:03:40+0000 [-] recording service: <enigma.eServiceReference; proxy of <Swig Object of type 'eServiceReference *' at 0xb16c4f20> >
2020-01-05 23:03:40+0000 [-] [Trashcan] Recording in progress 2
2020-01-05 23:03:40+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:39 +0000] "GET /api/statusinfo?_=1578264970009 HTTP/1.1" 200 422 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:44+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:40 +0000] "GET /api/pipinfo?_=1578264970010 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:44+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:44 +0000] "GET /grab?format=jpg&mode=all&T=1578265424605 HTTP/1.1" 200 453809 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:50+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:44 +0000] "GET /api/pipinfo?_=1578264970011 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:50+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:50 +0000] "GET /grab?format=jpg&mode=all&T=1578265430605 HTTP/1.1" 200 488958 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:55+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:50 +0000] "GET /api/statusinfo?_=1578264970012 HTTP/1.1" 200 422 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:56+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:55 +0000] "GET /api/pipinfo?_=1578264970013 HTTP/1.1" 200 33 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:03:56+0000 [-] "::ffff:192.168.1.13" - - [05/Jan/2020:23:03:56 +0000] "GET /grab?format=jpg&mode=all&T=1578265436605 HTTP/1.1" 200 299568 "http://192.168.1.251/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/79.0.3945.88 Safari/537.36"
2020-01-05 23:04:00+0000 [-] [TIMER] start recording
2020-01-05 23:04:00+0000 [-] AddPopup, id = None
2020-01-05 23:04:00+0000 [-] [ActionMap] Keymap(s) 'MsgBoxActions, DirectionActions' -> Undefined action(s) 'downRepeated, upRepeated, rightRepeated, leftRepeated'.
2020-01-05 23:04:00+0000 [-] [SKIN] processing screen MessageBoxSimple:
2020-01-05 23:04:00+0000 [-] [SKIN] processing screen MessageBoxSimple_summary:
2020-01-05 23:04:00+0000 [-] [SCREENNAME] ['MessageBoxSimple_summary', 'SimpleSummary']
2020-01-05 23:04:00+0000 [-] [SCREENNAME] ['MessageBoxSimple']
2020-01-05 23:04:02+0000 [-] Timeout!
2020-01-05 23:04:02+0000 [-] [SCREENNAME] InfoBarMoviePlayerSummary
 
Back
Top