-- Logs begin at Mon 2018-12-17 22:19:11 CET. --
Dec 18 18:18:17 dm7080 enigma2[7118]: reply: 'HTTP/1.1 200 OK\r\n'
Dec 18 18:18:17 dm7080 enigma2[7118]: header: CONTENT-LENGTH: 407
Dec 18 18:18:17 dm7080 enigma2[7118]: header: CONTENT-TYPE: text/xml; charset="utf-8"
Dec 18 18:18:17 dm7080 enigma2[7118]: header: EXT:
Dec 18 18:18:17 dm7080 enigma2[7118]: header: Server: Linux UPnP/1.0 Sonos/47.2-59120 (ZPS6)
Dec 18 18:18:17 dm7080 enigma2[7118]: header: Connection: close
Dec 18 18:18:23 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5457995320, m_skipmode_m = 0!
Dec 18 18:18:23 dm7080 enigma2[7118]: NO CUESHEET. (5457995320, 10485700)
Dec 18 18:18:27 dm7080 enigma2[7118]: [eFileMonitor] 1 events queued
Dec 18 18:18:27 dm7080 enigma2[7118]: [eFileMonitor] File {/tmp/enigma2.log} has been created
Dec 18 18:18:29 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5468481020, m_skipmode_m = 0!
Dec 18 18:18:29 dm7080 enigma2[7118]: NO CUESHEET. (5468481020, 10485700)
Dec 18 18:18:35 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5478966720, m_skipmode_m = 0!
Dec 18 18:18:35 dm7080 enigma2[7118]: NO CUESHEET. (5478966720, 10485700)
Dec 18 18:18:40 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5489452420, m_skipmode_m = 0!
Dec 18 18:18:40 dm7080 enigma2[7118]: NO CUESHEET. (5489452420, 10485700)
Dec 18 18:18:46 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5499938120, m_skipmode_m = 0!
Dec 18 18:18:46 dm7080 enigma2[7118]: NO CUESHEET. (5499938120, 10485700)
Dec 18 18:18:47 dm7080 enigma2[7118]: [EPGC] cleanupOutdated
Dec 18 18:18:51 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5510423820, m_skipmode_m = 0!
Dec 18 18:18:51 dm7080 enigma2[7118]: NO CUESHEET. (5510423820, 10485700)
Dec 18 18:18:57 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5520909520, m_skipmode_m = 0!
Dec 18 18:18:57 dm7080 enigma2[7118]: NO CUESHEET. (5520909520, 10485700)
Dec 18 18:18:59 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 82 32 cd 24 db
Dec 18 18:18:59 dm7080 enigma2[7118]: action: playpauseService
Dec 18 18:18:59 dm7080 enigma2[7118]: action ->  InfobarSeekActions playpauseService
Dec 18 18:18:59 dm7080 enigma2[7118]: eDVBServicePlay::pause
Dec 18 18:18:59 dm7080 enigma2[7118]: decoder state: pause, vpid=5101, apid=5106
Dec 18 18:18:59 dm7080 enigma2[7118]: VIDEO_SLOWMOTION(0) - ok
Dec 18 18:18:59 dm7080 enigma2[7118]: VIDEO_FAST_FORWARD(0) - ok
Dec 18 18:18:59 dm7080 enigma2[7118]: VIDEO_FREEZE - ok
Dec 18 18:18:59 dm7080 enigma2[7118]: AUDIO_PAUSE - ok
Dec 18 18:18:59 dm7080 enigma2[7118]: I/  [PhotoScreensaver.setEnabled] :: False
Dec 18 18:18:59 dm7080 enigma2[7118]: [eDBusInterface::Private::sendEvent 2
Dec 18 18:18:59 dm7080 enigma2[7118]: resolved to PAUSE 0
Dec 18 18:18:59 dm7080 enigma2[7118]: create buffer for widget 230 x 35
Dec 18 18:18:59 dm7080 enigma2[7118]: create buffer for widget 1116 x 37
Dec 18 18:18:59 dm7080 enigma2[7118]: create buffer for widget 1116 x 37
Dec 18 18:18:59 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 83 32 cd 24 db
Dec 18 18:18:59 dm7080 enigma2[7118]: before: 1
Dec 18 18:18:59 dm7080 enigma2[7118]: [ePicLoad] decode picture... /usr/share/enigma2/picon/1_0_19_283D_3FB_1_C00000_0_0_0.png
Dec 18 18:18:59 dm7080 enigma2[7118]: libpng warning: iCCP: known incorrect sRGB profile
Dec 18 18:18:59 dm7080 enigma2[7118]: libpng warning: iCCP: cHRM chunk does not match sRGB
Dec 18 18:18:59 dm7080 enigma2[7118]: realpath /media/hdd/movie/Robert/20181218 1400 - Das Erste HD - Rote Rosen (2795).txt: No such file or directory
Dec 18 18:18:59 dm7080 enigma2[7118]: [ePicLoad] finished decoding /usr/share/enigma2/picon/1_0_19_283D_3FB_1_C00000_0_0_0.png.
Dec 18 18:18:59 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 82 32 cd a4 5b
Dec 18 18:19:02 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 83 32 cd a4 5b
Dec 18 18:19:08 dm7080 enigma2[7118]: 179138 ms latency per 1000 blits (5,582289 fps)
Dec 18 18:19:22 dm7080 enigma2[7118]: send: 'POST /MediaRenderer/AVTransport/Control HTTP/1.1\r\nHost: 192.168.178.51:1400\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.11.1\r\nSOAPACTION: urn:schemas-upnp-org:service:AVTransport:1#GetTransportInfo\r\nContent-Type: text/xml; charset="utf-8"\r\nContent-Length: 295\r\n\r\n<?xml version="1.0"?><s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetTransportInfo xmlns:u="urn:schemas-upnp-org:service:AVTransport:1"><InstanceID>0</InstanceID></u:GetTransportInfo></s:Body></s:Envelope>'
Dec 18 18:19:22 dm7080 enigma2[7118]: reply: 'HTTP/1.1 200 OK\r\n'
Dec 18 18:19:22 dm7080 enigma2[7118]: header: CONTENT-LENGTH: 407
Dec 18 18:19:22 dm7080 enigma2[7118]: header: CONTENT-TYPE: text/xml; charset="utf-8"
Dec 18 18:19:22 dm7080 enigma2[7118]: header: EXT:
Dec 18 18:19:22 dm7080 enigma2[7118]: header: Server: Linux UPnP/1.0 Sonos/47.2-59120 (ZPS6)
Dec 18 18:19:22 dm7080 enigma2[7118]: header: Connection: close
Dec 18 18:19:24 dm7080 enigma2[7118]: 15347 ms latency per 1000 blits (65,159317 fps)
Dec 18 18:19:39 dm7080 enigma2[7118]: 15296 ms latency per 1000 blits (65,376572 fps)
Dec 18 18:19:40 dm7080 enigma2[7118]: no version filtering
Dec 18 18:19:40 dm7080 enigma2[7118]: 0014:  70 00 00 00 00 00
Dec 18 18:19:40 dm7080 enigma2[7118]: mask:  fc 00 00 00 00 00
Dec 18 18:19:40 dm7080 enigma2[7118]: mode:  00 00 00 00 00 00
Dec 18 18:19:40 dm7080 enigma2[7118]: [eDVBLocalTimeHandler] Receiver time is 'Tue Dec 18 18:19:40 2018'
Dec 18 18:19:40 dm7080 enigma2[7118]: [eDVBLocalTimeHandler] Transponder time is 'Tue Dec 18 18:19:40 2018'
Dec 18 18:19:40 dm7080 enigma2[7118]: [eDVBLocalTimeHandler] diff is 0
Dec 18 18:19:40 dm7080 enigma2[7118]: [eDVBLocalTimeHandler] diff < 120 .. use Transponder Time
Dec 18 18:19:40 dm7080 enigma2[7118]: [eDVBLocalTimeHandler] not changed
Dec 18 18:19:47 dm7080 enigma2[7118]: [EPGC] cleanupOutdated
Dec 18 18:19:50 dm7080 enigma2[7118]: It's now  Di 18 Dez 2018 18:19:49 CET
Dec 18 18:19:50 dm7080 enigma2[7118]: [timer.py] next activation: 1545153689 (in 99000 ms)
Dec 18 18:19:54 dm7080 enigma2[7118]: 15332 ms latency per 1000 blits (65,223061 fps)
Dec 18 18:19:56 dm7080 enigma2[7118]: It's now  Di 18 Dez 2018 18:19:56 CET
Dec 18 18:19:56 dm7080 enigma2[7118]: [EPGRefreshTimer] next real activation is Mi 19 Dez 2018 04:15:01 CET
Dec 18 18:19:56 dm7080 enigma2[7118]: [timer.py] next activation: 1545153696 (in 99997 ms)
Dec 18 18:19:57 dm7080 enigma2[7118]: It's now  Di 18 Dez 2018 18:19:57 CET
Dec 18 18:19:57 dm7080 enigma2[7118]: next real activation is Di 18 Dez 2018 19:20:00 CET
Dec 18 18:19:57 dm7080 enigma2[7118]: [timer.py] next activation: 1545153697 (in 99993 ms)
Dec 18 18:20:10 dm7080 enigma2[7118]: 15447 ms latency per 1000 blits (64,737488 fps)
Dec 18 18:20:13 dm7080 enigma2[7118]: nr_read 157700 nr_write 377964
Dec 18 18:20:13 dm7080 enigma2[7118]: sum 535664 prev_sum 535664
Dec 18 18:20:13 dm7080 enigma2[7118]: hdd IDLE!
Dec 18 18:20:13 dm7080 enigma2[7118]: [IDLE] 7379.97705317 1800 True
Dec 18 18:20:14 dm7080 enigma2[7118]: nr_read 1201433 nr_write 736060
Dec 18 18:20:14 dm7080 enigma2[7118]: sum 1937493 prev_sum 1934608
Dec 18 18:20:14 dm7080 enigma2[7118]: hdd was accessed since previous check!
Dec 18 18:20:14 dm7080 enigma2[7118]: [IDLE] 0 1800 False
Dec 18 18:20:25 dm7080 enigma2[7118]: 15559 ms latency per 1000 blits (64,271484 fps)
Dec 18 18:20:27 dm7080 enigma2[7118]: send: 'POST /ZoneGroupTopology/Control HTTP/1.1\r\nHost: 192.168.178.51:1400\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.11.1\r\nSOAPACTION: urn:schemas-upnp-org:service:ZoneGroupTopology:1#GetZoneGroupState\r\nContent-Type: text/xml; charset="utf-8"\r\nContent-Length: 277\r\n\r\n<?xml version="1.0"?><s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetZoneGroupState xmlns:u="urn:schemas-upnp-org:service:ZoneGroupTopology:1"></u:GetZoneGroupState></s:Body></s:Envelope>'
Dec 18 18:20:27 dm7080 enigma2[7118]: reply: 'HTTP/1.1 200 OK\r\n'
Dec 18 18:20:27 dm7080 enigma2[7118]: header: CONTENT-LENGTH: 5054
Dec 18 18:20:27 dm7080 enigma2[7118]: header: CONTENT-TYPE: text/xml; charset="utf-8"
Dec 18 18:20:27 dm7080 enigma2[7118]: header: EXT:
Dec 18 18:20:27 dm7080 enigma2[7118]: header: Server: Linux UPnP/1.0 Sonos/47.2-59120 (ZPS6)
Dec 18 18:20:27 dm7080 enigma2[7118]: header: Connection: close
Dec 18 18:20:27 dm7080 enigma2[7118]: send: 'POST /MediaRenderer/AVTransport/Control HTTP/1.1\r\nHost: 192.168.178.51:1400\r\nConnection: keep-alive\r\nAccept-Encoding: gzip, deflate\r\nAccept: */*\r\nUser-Agent: python-requests/2.11.1\r\nSOAPACTION: urn:schemas-upnp-org:service:AVTransport:1#GetTransportInfo\r\nContent-Type: text/xml; charset="utf-8"\r\nContent-Length: 295\r\n\r\n<?xml version="1.0"?><s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetTransportInfo xmlns:u="urn:schemas-upnp-org:service:AVTransport:1"><InstanceID>0</InstanceID></u:GetTransportInfo></s:Body></s:Envelope>'
Dec 18 18:20:27 dm7080 enigma2[7118]: reply: 'HTTP/1.1 200 OK\r\n'
Dec 18 18:20:27 dm7080 enigma2[7118]: header: CONTENT-LENGTH: 407
Dec 18 18:20:27 dm7080 enigma2[7118]: header: CONTENT-TYPE: text/xml; charset="utf-8"
Dec 18 18:20:27 dm7080 enigma2[7118]: header: EXT:
Dec 18 18:20:27 dm7080 enigma2[7118]: header: Server: Linux UPnP/1.0 Sonos/47.2-59120 (ZPS6)
Dec 18 18:20:27 dm7080 enigma2[7118]: header: Connection: close
Dec 18 18:20:34 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 82 32 cd 14 eb
Dec 18 18:20:34 dm7080 enigma2[7118]: action: unPauseService
Dec 18 18:20:34 dm7080 enigma2[7118]: action ->  InfobarSeekActions unPauseService
Dec 18 18:20:34 dm7080 enigma2[7118]: unpause
Dec 18 18:20:34 dm7080 enigma2[7118]: eDVBServicePlay::unpause
Dec 18 18:20:34 dm7080 enigma2[7118]: decoder state: play, vpid=5101, apid=5106
Dec 18 18:20:34 dm7080 enigma2[7118]: VIDEO_SLOWMOTION(0) - ok
Dec 18 18:20:34 dm7080 enigma2[7118]: VIDEO_FAST_FORWARD(0) - ok
Dec 18 18:20:34 dm7080 enigma2[7118]: VIDEO_CONTINUE - ok
Dec 18 18:20:34 dm7080 enigma2[7118]: AUDIO_CONTINUE - ok
Dec 18 18:20:34 dm7080 enigma2[7118]: W/  [MoviePlayerExtended.__servicePlaying] :: <no detail>
Dec 18 18:20:34 dm7080 enigma2[7118]: I/  [MoviePlayerExtended.setPlaybackState] :: None None 1545153634.11
Dec 18 18:20:34 dm7080 enigma2[7118]: I/  [PhotoScreensaver.setEnabled] :: False
Dec 18 18:20:34 dm7080 enigma2[7118]: [eDBusInterface::Private::sendEvent 1
Dec 18 18:20:34 dm7080 enigma2[7118]: resolved to PLAY 0
Dec 18 18:20:34 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 83 32 cd 14 eb
Dec 18 18:20:34 dm7080 enigma2[7118]: [eInputDevicehandlerDreamBLE::onCommand] 0x13 (5): 82 32 cd 94 6b
Dec 18 18:20:34 dm7080 enigma2[7118]: action ->  QuickButtonActions ok
Dec 18 18:20:34 dm7080 enigma2[7118]: unknown action QuickButtonActions/ok! typo in keymap?
Dec 18 18:20:37 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5531395220, m_skipmode_m = 0!
Dec 18 18:20:38 dm7080 enigma2[7118]: NO CUESHEET. (5531395220, 10485700)
Dec 18 18:20:43 dm7080 enigma2[7118]: getNextSourceSpan, current offset is 5541880920, m_skipmode_m = 0!
Dec 18 18:20:43 dm7080 enigma2[7118]: NO CUESHEET. (5541880920, 10485700)