search

adafruit / pi_video_looper

Application to turn your Raspberry Pi into a dedicated looping video playback device, good for art installations, information displays, or just playing cat videos all day.
GNU General Public License v2.0
443 stars 240 forks source link

Video plays with omxplayer directly, but script shows black screen #219

Open githubarooski opened 3 months ago

githubarooski commented 3 months ago

If I call omxplayer directly with my custom resolution, it works fine. If I use your script to call omxplayer with the same arguments, it just shows a black screen and it seems to keep crashing based on looking at ps aux

If I kill your script and then run it manually, I see this:

root@latent:/home/pi/pi_video_looper# python3 -u -m Adafruit_Video_Looper.video_looper
pygame 1.9.4.post1
Hello from the pygame community. https://www.pygame.org/contribute.html
Starting Adafruit Video Looper.

You can see that the script is using my custom args successfully from your config file:


root@latent:/home/pi/pi_video_looper# ps axu | grep omx
root      4084  0.0  0.0   7672  2952 ?        S    17:35   0:00 /bin/bash /usr/bin/omxplayer -o both --win "0 0 1600 1200" -g --loop /home/pi/video/video.mp4
root      4095  0.0  0.3 108840 14972 ?        RL   17:35   0:00 /usr/bin/omxplayer.bin -o both --win "0 0 1600 1200" -g --loop /home/pi/video/video.mp4
root      4097  0.0  0.0   7344   508 pts/0    S+   17:35   0:00 grep omx

But nothing appears on my screen and I can't find the omxplayer.log file on the filesystem anywhere, so I don't think it's creating the log file, even though I am passing it -g in the custom arguments config.

When I run the command manually, the video plays just fine

root@latent:/home/pi/pi_video_looper# /bin/bash /usr/bin/omxplayer -o both --win "0 0 1600 1200" -g --loop /home/pi/video/video.mp4
Video codec omx-h264 width 616 height 816 profile 100 fps 8.000000
Subtitle count: 0, state: off, index: 1, delay: 0
V:PortSettingsChanged: 616x816@8.00 interlace:0 deinterlace:0 anaglyph:0 par:1.00 display:0 layer:0 alpha:255 aspectMode:0

When I run my omxplayer command manually and create a log file, I see this in the log file

# cat omxplayer.log 
09:32:32 T:1714500514   DEBUG: DllBcm: Using omx system library
09:32:32 T:1714501705   DEBUG: DllOMX: Using omx system library
09:32:32 T:1714502345   DEBUG: DllAvFormat: Using libavformat system library
09:32:32 T:1714503696   DEBUG: DBus connection succeeded
09:32:32 T:1714504452   DEBUG: Keyboard: DBus connection succeeded
09:32:32 T:1714504614   DEBUG: OMXThread::Create - Thread with id -1388339520 started
09:32:32 T:1714504716   DEBUG: DllAvUtilBase: Using libavutil system library
09:32:32 T:1714504739   DEBUG: DllAvCodec: Using libavcodec system library
09:32:32 T:1714504756   DEBUG: DllAvFormat: Using libavformat system library
09:32:32 T:1714546422   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.clock input port 80 output port 81 m_handle 0x1385990
09:32:32 T:1714546673   DEBUG: OMXClock::OMXStop
09:32:32 T:1714546782   DEBUG: OMXClock::OMXSetSpeed(0.00) pause_resume:1
09:32:32 T:1714546977   DEBUG: DllAvUtilBase: Using libavutil system library
09:32:32 T:1714547000   DEBUG: DllAvCodec: Using libavcodec system library
09:32:32 T:1714547018   DEBUG: DllAvFormat: Using libavformat system library
09:32:32 T:1714548272   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_decode input port 130 output port 131 m_handle 0x13858a0
09:32:32 T:1714549126   DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.video_decode) - port(130), nBufferCountMin(1), nBufferCountActual(60), nBufferSize(81920), nBufferAlignmen(16)
09:32:32 T:1714558318   DEBUG: COMXVideo::Open - decoder_component(0x0x13858a0), input_port(0x82), output_port(0x83) deinterlace 1 hdmiclocksync 0
09:32:32 T:1714558538   DEBUG: OMXThread::Create - Thread with id -1400901952 started
09:32:32 T:1714558600  NOTICE: OMXClock using video as reference
09:32:32 T:1714558828   DEBUG: OMXClock::OMXReset audio / video : 0 / 1 wait mask 0->2 state : 2->1
09:32:32 T:1714559131   DEBUG: Popped message member: NameAcquired interface: org.freedesktop.DBus type: 4 path: /org/freedesktop/DBus
09:32:32 T:1714559170 WARNING: Unhandled dbus message, member: NameAcquired interface: org.freedesktop.DBus type: 4 path: /org/freedesktop/DBus
09:32:32 T:1714559284   DEBUG: Normal M:0 (A:0 V:-4503599627370496) P:1 A:0.00 V:0.00/T:0.20 (0,0,1,0) A:0% V:0% (0.00,0.00)
09:32:32 T:1714559636    INFO: CDVDPlayerVideo::Decode dts:-250000 pts:0 cur:0, size:94041
09:32:32 T:1714559749   DEBUG: OMXVideo::Decode VDec : setStartTime 0.000000
09:32:32 T:1714560669    INFO: CDVDPlayerVideo::Decode dts:-125000 pts:500000 cur:500000, size:104944
09:32:32 T:1714561643    INFO: CDVDPlayerVideo::Decode dts:0 pts:250000 cur:250000, size:98915
09:32:32 T:1714562610    INFO: CDVDPlayerVideo::Decode dts:125000 pts:125000 cur:125000, size:30321
09:32:32 T:1714563186    INFO: CDVDPlayerVideo::Decode dts:250000 pts:375000 cur:375000, size:28025
09:32:32 T:1714563688    INFO: CDVDPlayerVideo::Decode dts:375000 pts:1000000 cur:1000000, size:106607
09:32:32 T:1714564826    INFO: CDVDPlayerVideo::Decode dts:500000 pts:750000 cur:750000, size:103248
09:32:32 T:1714566167    INFO: CDVDPlayerVideo::Decode dts:625000 pts:625000 cur:625000, size:23891
09:32:32 T:1714567098    INFO: CDVDPlayerVideo::Decode dts:750000 pts:875000 cur:875000, size:27867
09:32:32 T:1714567818    INFO: CDVDPlayerVideo::Decode dts:875000 pts:1500000 cur:1500000, size:104816
09:32:32 T:1714569213    INFO: CDVDPlayerVideo::Decode dts:1000000 pts:1250000 cur:1250000, size:100910
09:32:32 T:1714570418    INFO: CDVDPlayerVideo::Decode dts:1125000 pts:1125000 cur:1125000, size:25813
09:32:32 T:1714570940    INFO: CDVDPlayerVideo::Decode dts:1250000 pts:1375000 cur:1375000, size:22860
09:32:32 T:1714573332   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_render input port 90 output port 90 m_handle 0xac900670
09:32:32 T:1714573485   DEBUG: COMXVideo::PortSettingsChangedLogger - 616x816@8.00 interlace:0 deinterlace:0 anaglyph:0 par:1.00 display:0 layer:0 alpha:255 aspectMode:0
09:32:32 T:1714574871   DEBUG: COMXCoreComponent::Initialize OMX.broadcom.video_scheduler input port 10 output port 11 m_handle 0xac900cb0
09:32:32 T:1714578423    INFO: CDVDPlayerVideo::Decode dts:1375000 pts:2000000 cur:2000000, size:105711
09:32:32 T:1714579567   DEBUG: Popped message member: NameAcquired interface: org.freedesktop.DBus type: 4 path: /org/freedesktop/DBus
09:32:32 T:1714579771    INFO: CDVDPlayerVideo::Decode dts:1500000 pts:1750000 cur:1750000, size:98995
09:32:32 T:1714579874 WARNING: Unhandled dbus message, member: NameAcquired interface: org.freedesktop.DBus type: 4 path: /org/freedesktop/DBus
09:32:32 T:1714580138   DEBUG: Normal M:-200000 (A:0 V:1750000) P:1 A:0.20 V:1.95/T:0.20 (0,0,1,1) A:0% V:19% (0.00,0.00)
09:32:32 T:1714580198   DEBUG: Resume 0.20,1.95 (0,0,1,1) EOF:0 PKT:(nil)
09:32:32 T:1714580244   DEBUG: OMXClock::OMXSetSpeed(1.00) pause_resume:1
09:32:32 T:1714582510    INFO: CDVDPlayerVideo::Decode dts:1625000 pts:1625000 cur:1625000, size:21987
09:32:32 T:1714582932    INFO: CDVDPlayerVideo::Decode dts:1750000 pts:1875000 cur:1875000, size:19694
09:32:32 T:1714583310    INFO: CDVDPlayerVideo::Decode dts:1875000 pts:2500000 cur:2500000, size:105080
09:32:32 T:1714585810    INFO: CDVDPlayerVideo::Decode dts:2000000 pts:2250000 cur:2250000, size:97249
09:32:32 T:1714587326    INFO: CDVDPlayerVideo::Decode dts:2125000 pts:2125000 cur:2125000, size:18027
09:32:32 T:1714587983    INFO: CDVDPlayerVideo::Decode dts:2250000 pts:2375000 cur:2375000, size:17805
09:32:32 T:1714588688    INFO: CDVDPlayerVideo::Decode dts:2375000 pts:3000000 cur:3000000, size:105124
09:32:32 T:1714590559    INFO: CDVDPlayerVideo::Decode dts:2500000 pts:2750000 cur:2750000, size:101405
09:32:32 T:1714591909    INFO: CDVDPlayerVideo::Decode dts:2625000 pts:2625000 cur:2625000, size:20725
09:32:32 T:1714592896    INFO: CDVDPlayerVideo::Decode dts:2750000 pts:2875000 cur:2875000, size:24257
09:32:32 T:1714593840    INFO: CDVDPlayerVideo::Decode dts:2875000 pts:3500000 cur:3500000, size:106417
09:32:32 T:1714595145    INFO: CDVDPlayerVideo::Decode dts:3000000 pts:3250000 cur:3250000, size:99579
09:32:32 T:1714596393    INFO: CDVDPlayerVideo::Decode dts:3125000 pts:3125000 cur:3125000, size:20203
09:32:32 T:1714596906    INFO: CDVDPlayerVideo::Decode dts:3250000 pts:3375000 cur:3375000, size:20574
09:32:32 T:1714597314    INFO: CDVDPlayerVideo::Decode dts:3375000 pts:4000000 cur:4000000, size:105884
09:32:32 T:1714598470    INFO: CDVDPlayerVideo::Decode dts:3500000 pts:3750000 cur:3750000, size:98615
09:32:32 T:1714599540    INFO: CDVDPlayerVideo::Decode dts:3625000 pts:3625000 cur:3625000, size:20758
09:32:32 T:1714599792   DEBUG: Normal M:-180612 (A:0 V:3625000) P:0 A:0.18 V:3.81/T:0.20 (0,0,1,1) A:0% V:34% (0.00,0.00)
09:32:32 T:1714599986    INFO: CDVDPlayerVideo::Decode dts:3750000 pts:3875000 cur:3875000, size:23958
09:32:32 T:1714600381    INFO: CDVDPlayerVideo::Decode dts:3875000 pts:4500000 cur:4500000, size:100540
09:32:32 T:1714601369    INFO: CDVDPlayerVideo::Decode dts:4000000 pts:4250000 cur:4250000, size:97671
09:32:32 T:1714602429    INFO: CDVDPlayerVideo::Decode dts:4125000 pts:4125000 cur:4125000, size:19362
09:32:32 T:1714602913    INFO: CDVDPlayerVideo::Decode dts:4250000 pts:4375000 cur:4375000, size:18446
09:32:32 T:1714603301    INFO: CDVDPlayerVideo::Decode dts:4375000 pts:5000000 cur:5000000, size:103526
09:32:32 T:1714604422    INFO: CDVDPlayerVideo::Decode dts:4500000 pts:4750000 cur:4750000, size:88583
09:32:32 T:1714605475    INFO: CDVDPlayerVideo::Decode dts:4625000 pts:4625000 cur:4625000, size:18642
09:32:32 T:1714605836    INFO: CDVDPlayerVideo::Decode dts:4750000 pts:4875000 cur:4875000, size:17409
09:32:32 T:1714606155    INFO: CDVDPlayerVideo::Decode dts:4875000 pts:5500000 cur:5500000, size:107900
09:32:32 T:1714606960    INFO: CDVDPlayerVideo::Decode dts:5000000 pts:5250000 cur:5250000, size:93364
09:32:32 T:1714607763    INFO: CDVDPlayerVideo::Decode dts:5125000 pts:5125000 cur:5125000, size:17588
09:32:32 T:1714608101    INFO: CDVDPlayerVideo::Decode dts:5250000 pts:5375000 cur:5375000, size:20685
09:32:32 T:1714608442    INFO: CDVDPlayerVideo::Decode dts:5375000 pts:6000000 cur:6000000, size:102081
09:32:32 T:1714609243    INFO: CDVDPlayerVideo::Decode dts:5500000 pts:5750000 cur:5750000, size:97003
09:32:32 T:1714610108    INFO: CDVDPlayerVideo::Decode dts:5625000 pts:5625000 cur:5625000, size:20947
09:32:32 T:1714610460    INFO: CDVDPlayerVideo::Decode dts:5750000 pts:5875000 cur:5875000, size:20795
09:32:32 T:1714610794    INFO: CDVDPlayerVideo::Decode dts:5875000 pts:6500000 cur:6500000, size:102958
09:32:32 T:1714611601    INFO: CDVDPlayerVideo::Decode dts:6000000 pts:6250000 cur:6250000, size:96631
09:32:32 T:1714612397    INFO: CDVDPlayerVideo::Decode dts:6125000 pts:6125000 cur:6125000, size:19704
09:32:32 T:1714612741    INFO: CDVDPlayerVideo::Decode dts:6250000 pts:6375000 cur:6375000, size:20103
09:32:32 T:1714613085    INFO: CDVDPlayerVideo::Decode dts:6375000 pts:7000000 cur:7000000, size:105423
09:32:32 T:1714613875    INFO: CDVDPlayerVideo::Decode dts:6500000 pts:6750000 cur:6750000, size:101602
09:32:32 T:1714614673    INFO: CDVDPlayerVideo::Decode dts:6625000 pts:6625000 cur:6625000, size:21087
09:32:32 T:1714615054    INFO: CDVDPlayerVideo::Decode dts:6750000 pts:6875000 cur:6875000, size:21927
09:32:32 T:1714615414    INFO: CDVDPlayerVideo::Decode dts:6875000 pts:7500000 cur:7500000, size:108260
09:32:32 T:1714616248    INFO: CDVDPlayerVideo::Decode dts:7000000 pts:7250000 cur:7250000, size:102201
09:32:32 T:1714617132    INFO: CDVDPlayerVideo::Decode dts:7125000 pts:7125000 cur:7125000, size:20095
09:32:32 T:1714617499    INFO: CDVDPlayerVideo::Decode dts:7250000 pts:7375000 cur:7375000, size:18438
09:32:32 T:1714617814    INFO: CDVDPlayerVideo::Decode dts:7375000 pts:8000000 cur:8000000, size:106127
09:32:32 T:1714618644    INFO: CDVDPlayerVideo::Decode dts:7500000 pts:7750000 cur:7750000, size:97296
09:32:32 T:1714619457    INFO: CDVDPlayerVideo::Decode dts:7625000 pts:7625000 cur:7625000, size:19502
09:32:32 T:1714619816    INFO: CDVDPlayerVideo::Decode dts:7750000 pts:7875000 cur:7875000, size:18490
09:32:32 T:1714619985   DEBUG: Normal M:-160306 (A:0 V:7875000) P:0 A:0.16 V:8.04/T:0.20 (0,0,1,1) A:0% V:56% (0.00,0.00)
09:32:32 T:1714620140    INFO: CDVDPlayerVideo::Decode dts:7875000 pts:8500000 cur:8500000, size:97510
09:32:32 T:1714620986    INFO: CDVDPlayerVideo::Decode dts:8000000 pts:8250000 cur:8250000, size:92319
09:32:32 T:1714621813    INFO: CDVDPlayerVideo::Decode dts:8125000 pts:8125000 cur:8125000, size:16713
09:32:32 T:1714622162    INFO: CDVDPlayerVideo::Decode dts:8250000 pts:8375000 cur:8375000, size:16719
09:32:32 T:1714640323   DEBUG: Normal M:-139971 (A:0 V:9000000) P:0 A:0.14 V:9.14/T:0.20 (0,0,1,1) A:0% V:90% (0.00,0.00)
09:32:32 T:1714665978   DEBUG: Normal M:-114314 (A:0 V:9000000) P:0 A:0.11 V:9.11/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:32 T:1714686208   DEBUG: Normal M:-94080 (A:0 V:9000000) P:0 A:0.09 V:9.09/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:32 T:1714706505   DEBUG: Normal M:-73896 (A:0 V:9000000) P:0 A:0.07 V:9.07/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:32 T:1714726685   DEBUG: Normal M:-53596 (A:0 V:9000000) P:0 A:0.05 V:9.05/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:32 T:1714746860   DEBUG: Normal M:-33421 (A:0 V:9000000) P:0 A:0.03 V:9.03/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:32 T:1714767036   DEBUG: Normal M:-13245 (A:0 V:9000000) P:0 A:0.01 V:9.01/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714787211   DEBUG: Normal M:6930 (A:0 V:9000000) P:0 A:-0.01 V:8.99/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714807481   DEBUG: Normal M:27094 (A:0 V:9000000) P:0 A:-0.03 V:8.97/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714827658   DEBUG: Normal M:47366 (A:0 V:9000000) P:0 A:-0.05 V:8.95/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714847832   DEBUG: Normal M:67540 (A:0 V:9000000) P:0 A:-0.07 V:8.93/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714868005   DEBUG: Normal M:87713 (A:0 V:9000000) P:0 A:-0.09 V:8.91/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714888182   DEBUG: Normal M:107890 (A:0 V:9000000) P:0 A:-0.11 V:8.89/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714908454   DEBUG: Normal M:128066 (A:0 V:9000000) P:0 A:-0.13 V:8.87/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714928662   DEBUG: Normal M:148372 (A:0 V:9000000) P:0 A:-0.15 V:8.85/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714948838   DEBUG: Normal M:168547 (A:0 V:9000000) P:0 A:-0.17 V:8.83/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714969013   DEBUG: Normal M:188722 (A:0 V:9000000) P:0 A:-0.19 V:8.81/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1714989187   DEBUG: Normal M:208896 (A:0 V:9000000) P:0 A:-0.21 V:8.79/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715009457   DEBUG: Normal M:229069 (A:0 V:9000000) P:0 A:-0.23 V:8.77/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715029630   DEBUG: Normal M:249341 (A:0 V:9000000) P:0 A:-0.25 V:8.75/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715049803   DEBUG: Normal M:269513 (A:0 V:9000000) P:0 A:-0.27 V:8.73/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715069975   DEBUG: Normal M:289685 (A:0 V:9000000) P:0 A:-0.29 V:8.71/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715090147   DEBUG: Normal M:309857 (A:0 V:9000000) P:0 A:-0.31 V:8.69/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715110409   DEBUG: Normal M:330025 (A:0 V:9000000) P:0 A:-0.33 V:8.67/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715130582   DEBUG: Normal M:350288 (A:0 V:9000000) P:0 A:-0.35 V:8.65/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715145840    INFO: CDVDPlayerVideo::Decode dts:8375000 pts:9000000 cur:9000000, size:92423
09:32:33 T:1715150756   DEBUG: Normal M:370462 (A:0 V:8750000) P:0 A:-0.37 V:8.38/T:0.20 (0,0,1,1) A:0% V:98% (0.00,0.00)
09:32:33 T:1715171694   DEBUG: Normal M:391396 (A:0 V:8750000) P:0 A:-0.39 V:8.36/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715191964   DEBUG: Normal M:411668 (A:0 V:8750000) P:0 A:-0.41 V:8.34/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715212384   DEBUG: Normal M:431935 (A:0 V:8750000) P:0 A:-0.43 V:8.32/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715232625   DEBUG: Normal M:452359 (A:0 V:8750000) P:0 A:-0.45 V:8.30/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715252862   DEBUG: Normal M:472595 (A:0 V:8750000) P:0 A:-0.47 V:8.28/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715267529    INFO: CDVDPlayerVideo::Decode dts:8500000 pts:8750000 cur:8750000, size:92367
09:32:33 T:1715270461    INFO: CDVDPlayerVideo::Decode dts:8625000 pts:8625000 cur:8625000, size:16885
09:32:33 T:1715271065    INFO: CDVDPlayerVideo::Decode dts:8750000 pts:8875000 cur:8875000, size:17764
09:32:33 T:1715273183   DEBUG: Normal M:492914 (A:0 V:9500000) P:0 A:-0.49 V:9.01/T:0.20 (0,0,1,1) A:0% V:98% (0.00,0.00)
09:32:33 T:1715294182   DEBUG: Normal M:513913 (A:0 V:9500000) P:0 A:-0.51 V:8.99/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715314727   DEBUG: Normal M:534242 (A:0 V:9500000) P:0 A:-0.53 V:8.97/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715335058   DEBUG: Normal M:554801 (A:0 V:9500000) P:0 A:-0.55 V:8.95/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715355351   DEBUG: Normal M:575094 (A:0 V:9500000) P:0 A:-0.58 V:8.92/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715375674   DEBUG: Normal M:595417 (A:0 V:9500000) P:0 A:-0.60 V:8.90/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715395966   DEBUG: Normal M:615709 (A:0 V:9500000) P:0 A:-0.62 V:8.88/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715416477   DEBUG: Normal M:635999 (A:0 V:9500000) P:0 A:-0.64 V:8.86/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715436770   DEBUG: Normal M:656517 (A:0 V:9500000) P:0 A:-0.66 V:8.84/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715457059   DEBUG: Normal M:676806 (A:0 V:9500000) P:0 A:-0.68 V:8.82/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715477376   DEBUG: Normal M:697123 (A:0 V:9500000) P:0 A:-0.70 V:8.80/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715497662   DEBUG: Normal M:717408 (A:0 V:9500000) P:0 A:-0.72 V:8.78/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715518173   DEBUG: Normal M:737695 (A:0 V:9500000) P:0 A:-0.74 V:8.76/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715538530   DEBUG: Normal M:758273 (A:0 V:9500000) P:0 A:-0.76 V:8.74/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715558822   DEBUG: Normal M:778565 (A:0 V:9500000) P:0 A:-0.78 V:8.72/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715579139   DEBUG: Normal M:798882 (A:0 V:9500000) P:0 A:-0.80 V:8.70/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715599429   DEBUG: Normal M:819171 (A:0 V:9500000) P:0 A:-0.82 V:8.68/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715619926   DEBUG: Normal M:839454 (A:0 V:9500000) P:0 A:-0.84 V:8.66/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715640219   DEBUG: Normal M:859955 (A:0 V:9500000) P:0 A:-0.86 V:8.64/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715654771    INFO: CDVDPlayerVideo::Decode dts:8875000 pts:9500000 cur:9500000, size:100676
09:32:33 T:1715660502   DEBUG: Normal M:880237 (A:0 V:9250000) P:0 A:-0.88 V:8.37/T:0.20 (0,0,1,1) A:0% V:98% (0.00,0.00)
09:32:33 T:1715681222   DEBUG: Normal M:900956 (A:0 V:9250000) P:0 A:-0.90 V:8.35/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715701532   DEBUG: Normal M:921266 (A:0 V:9250000) P:0 A:-0.92 V:8.33/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715722044   DEBUG: Normal M:941562 (A:0 V:9250000) P:0 A:-0.94 V:8.31/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715742328   DEBUG: Normal M:962075 (A:0 V:9250000) P:0 A:-0.96 V:8.29/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715762610   DEBUG: Normal M:982357 (A:0 V:9250000) P:0 A:-0.98 V:8.27/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:33 T:1715767231    INFO: CDVDPlayerVideo::Decode dts:9000000 pts:9250000 cur:9250000, size:95125
09:32:33 T:1715768649    INFO: CDVDPlayerVideo::Decode dts:9125000 pts:9125000 cur:9125000, size:18223
09:32:33 T:1715769198    INFO: CDVDPlayerVideo::Decode dts:9250000 pts:9375000 cur:9375000, size:20251
09:32:34 T:1715784245   DEBUG: Normal M:1003989 (A:0 V:10000000) P:0 A:-1.00 V:9.00/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715804573   DEBUG: Normal M:1024320 (A:0 V:10000000) P:0 A:-1.02 V:8.98/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715825098   DEBUG: Normal M:1044609 (A:0 V:10000000) P:0 A:-1.04 V:8.96/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715845394   DEBUG: Normal M:1065145 (A:0 V:10000000) P:0 A:-1.07 V:8.93/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715865684   DEBUG: Normal M:1085435 (A:0 V:10000000) P:0 A:-1.09 V:8.91/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715885974   DEBUG: Normal M:1105725 (A:0 V:10000000) P:0 A:-1.11 V:8.89/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715906260   DEBUG: Normal M:1126010 (A:0 V:10000000) P:0 A:-1.13 V:8.87/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715926774   DEBUG: Normal M:1146295 (A:0 V:10000000) P:0 A:-1.15 V:8.85/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715947066   DEBUG: Normal M:1166812 (A:0 V:10000000) P:0 A:-1.17 V:8.83/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715967354   DEBUG: Normal M:1187101 (A:0 V:10000000) P:0 A:-1.19 V:8.81/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1715987642   DEBUG: Normal M:1207389 (A:0 V:10000000) P:0 A:-1.21 V:8.79/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716007930   DEBUG: Normal M:1227677 (A:0 V:10000000) P:0 A:-1.23 V:8.77/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716028444   DEBUG: Normal M:1247962 (A:0 V:10000000) P:0 A:-1.25 V:8.75/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716048733   DEBUG: Normal M:1268478 (A:0 V:10000000) P:0 A:-1.27 V:8.73/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716069020   DEBUG: Normal M:1288765 (A:0 V:10000000) P:0 A:-1.29 V:8.71/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716089309   DEBUG: Normal M:1309054 (A:0 V:10000000) P:0 A:-1.31 V:8.69/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716109596   DEBUG: Normal M:1329341 (A:0 V:10000000) P:0 A:-1.33 V:8.67/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716130096   DEBUG: Normal M:1349621 (A:0 V:10000000) P:0 A:-1.35 V:8.65/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716150458   DEBUG: Normal M:1370197 (A:0 V:10000000) P:0 A:-1.37 V:8.63/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716153024    INFO: CDVDPlayerVideo::Decode dts:9375000 pts:10000000 cur:10000000, size:99085
09:32:34 T:1716171567   DEBUG: Normal M:1391305 (A:0 V:9750000) P:0 A:-1.39 V:8.36/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716191891   DEBUG: Normal M:1411631 (A:0 V:9750000) P:0 A:-1.41 V:8.34/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716212179   DEBUG: Normal M:1431919 (A:0 V:9750000) P:0 A:-1.43 V:8.32/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716232687   DEBUG: Normal M:1452208 (A:0 V:9750000) P:0 A:-1.45 V:8.30/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716252980   DEBUG: Normal M:1472727 (A:0 V:9750000) P:0 A:-1.47 V:8.28/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716273281   DEBUG: Normal M:1493028 (A:0 V:9750000) P:0 A:-1.49 V:8.26/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716275465    INFO: CDVDPlayerVideo::Decode dts:9500000 pts:9750000 cur:9750000, size:96612
09:32:34 T:1716276854    INFO: CDVDPlayerVideo::Decode dts:9625000 pts:9625000 cur:9625000, size:17901
09:32:34 T:1716277418    INFO: CDVDPlayerVideo::Decode dts:9750000 pts:9875000 cur:9875000, size:17082
09:32:34 T:1716277976    INFO: CDVDPlayerVideo::Decode dts:9875000 pts:10125000 cur:10125000, size:57984
09:32:34 T:1716294841   DEBUG: Normal M:1514585 (A:0 V:10625000) P:0 A:-1.51 V:9.11/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716315183   DEBUG: Normal M:1534929 (A:0 V:10625000) P:0 A:-1.53 V:9.09/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716335717   DEBUG: Normal M:1555232 (A:0 V:10625000) P:0 A:-1.56 V:9.07/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716356015   DEBUG: Normal M:1575767 (A:0 V:10625000) P:0 A:-1.58 V:9.05/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716376304   DEBUG: Normal M:1596057 (A:0 V:10625000) P:0 A:-1.60 V:9.03/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716396593   DEBUG: Normal M:1616346 (A:0 V:10625000) P:0 A:-1.62 V:9.01/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716416882   DEBUG: Normal M:1636634 (A:0 V:10625000) P:0 A:-1.64 V:8.99/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716437390   DEBUG: Normal M:1656914 (A:0 V:10625000) P:0 A:-1.66 V:8.97/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716457679   DEBUG: Normal M:1677426 (A:0 V:10625000) P:0 A:-1.68 V:8.95/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716477964   DEBUG: Normal M:1697711 (A:0 V:10625000) P:0 A:-1.70 V:8.93/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716498249   DEBUG: Normal M:1717997 (A:0 V:10625000) P:0 A:-1.72 V:8.91/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716518533   DEBUG: Normal M:1738281 (A:0 V:10625000) P:0 A:-1.74 V:8.89/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716539041   DEBUG: Normal M:1758565 (A:0 V:10625000) P:0 A:-1.76 V:8.87/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716559330   DEBUG: Normal M:1779077 (A:0 V:10625000) P:0 A:-1.78 V:8.85/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716579615   DEBUG: Normal M:1799363 (A:0 V:10625000) P:0 A:-1.80 V:8.83/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716599901   DEBUG: Normal M:1819648 (A:0 V:10625000) P:0 A:-1.82 V:8.81/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716620190   DEBUG: Normal M:1839938 (A:0 V:10625000) P:0 A:-1.84 V:8.79/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716640702   DEBUG: Normal M:1860228 (A:0 V:10625000) P:0 A:-1.86 V:8.76/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716651834    INFO: CDVDPlayerVideo::Decode dts:10000000 pts:10625000 cur:10625000, size:72573
09:32:34 T:1716660988   DEBUG: Normal M:1880742 (A:0 V:10375000) P:0 A:-1.88 V:8.49/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716681279   DEBUG: Normal M:1901033 (A:0 V:10375000) P:0 A:-1.90 V:8.47/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716701564   DEBUG: Normal M:1921318 (A:0 V:10375000) P:0 A:-1.92 V:8.45/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716721913   DEBUG: Normal M:1941666 (A:0 V:10375000) P:0 A:-1.94 V:8.43/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716742423   DEBUG: Normal M:1961948 (A:0 V:10375000) P:0 A:-1.96 V:8.41/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716762706   DEBUG: Normal M:1982452 (A:0 V:10375000) P:0 A:-1.98 V:8.39/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:34 T:1716773743    INFO: CDVDPlayerVideo::Decode dts:10125000 pts:10375000 cur:10375000, size:42628
09:32:34 T:1716774460    INFO: CDVDPlayerVideo::Decode dts:10250000 pts:10250000 cur:10250000, size:42693
09:32:34 T:1716775260    INFO: CDVDPlayerVideo::Decode dts:10375000 pts:10500000 cur:10500000, size:39085
09:32:34 T:1716776053    INFO: CDVDPlayerVideo::Decode dts:10500000 pts:11125000 cur:11125000, size:69613
09:32:34 T:1716777096    INFO: CDVDPlayerVideo::Decode dts:10625000 pts:10875000 cur:10875000, size:42528
09:32:35 T:1716782994   DEBUG: Normal M:2002737 (A:0 V:10750000) P:0 A:-2.00 V:8.75/T:0.20 (0,0,1,1) A:0% V:97% (0.00,0.00)
09:32:35 T:1716804969   DEBUG: Normal M:2024709 (A:0 V:10750000) P:0 A:-2.02 V:8.73/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716825312   DEBUG: Normal M:2045058 (A:0 V:10750000) P:0 A:-2.05 V:8.70/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716845840   DEBUG: Normal M:2065362 (A:0 V:10750000) P:0 A:-2.07 V:8.68/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716866136   DEBUG: Normal M:2085895 (A:0 V:10750000) P:0 A:-2.09 V:8.66/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716886426   DEBUG: Normal M:2106185 (A:0 V:10750000) P:0 A:-2.11 V:8.64/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716906716   DEBUG: Normal M:2126476 (A:0 V:10750000) P:0 A:-2.13 V:8.62/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716927004   DEBUG: Normal M:2146764 (A:0 V:10750000) P:0 A:-2.15 V:8.60/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716947510   DEBUG: Normal M:2167042 (A:0 V:10750000) P:0 A:-2.17 V:8.58/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716967798   DEBUG: Normal M:2187550 (A:0 V:10750000) P:0 A:-2.19 V:8.56/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1716988125   DEBUG: Normal M:2207876 (A:0 V:10750000) P:0 A:-2.21 V:8.54/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717008414   DEBUG: Normal M:2228165 (A:0 V:10750000) P:0 A:-2.23 V:8.52/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717028698   DEBUG: Normal M:2248450 (A:0 V:10750000) P:0 A:-2.25 V:8.50/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717049214   DEBUG: Normal M:2268735 (A:0 V:10750000) P:0 A:-2.27 V:8.48/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717069502   DEBUG: Normal M:2289253 (A:0 V:10750000) P:0 A:-2.29 V:8.46/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717089786   DEBUG: Normal M:2309538 (A:0 V:10750000) P:0 A:-2.31 V:8.44/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717110071   DEBUG: Normal M:2329823 (A:0 V:10750000) P:0 A:-2.33 V:8.42/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717130355   DEBUG: Normal M:2350107 (A:0 V:10750000) P:0 A:-2.35 V:8.40/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717150879    INFO: CDVDPlayerVideo::Decode dts:10750000 pts:10750000 cur:10750000, size:39616
09:32:35 T:1717151001   DEBUG: Normal M:2370382 (A:0 V:10750000) P:0 A:-2.37 V:8.38/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717151704    INFO: CDVDPlayerVideo::Decode dts:10875000 pts:11000000 cur:11000000, size:42608
09:32:35 T:1717172728   DEBUG: Normal M:2392470 (A:0 V:11625000) P:0 A:-2.39 V:9.23/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717193052   DEBUG: Normal M:2412799 (A:0 V:11625000) P:0 A:-2.41 V:9.21/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717213339   DEBUG: Normal M:2433085 (A:0 V:11625000) P:0 A:-2.43 V:9.19/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717233620   DEBUG: Normal M:2453367 (A:0 V:11625000) P:0 A:-2.45 V:9.17/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717254201   DEBUG: Normal M:2473693 (A:0 V:11625000) P:0 A:-2.47 V:9.15/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717273493    INFO: CDVDPlayerVideo::Decode dts:11000000 pts:11625000 cur:11625000, size:68796
09:32:35 T:1717274571   DEBUG: Normal M:2494362 (A:0 V:11625000) P:0 A:-2.49 V:9.13/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717274683    INFO: CDVDPlayerVideo::Decode dts:11125000 pts:11375000 cur:11375000, size:44461
09:32:35 T:1717275573    INFO: CDVDPlayerVideo::Decode dts:11250000 pts:11250000 cur:11250000, size:41762
09:32:35 T:1717276362    INFO: CDVDPlayerVideo::Decode dts:11375000 pts:11500000 cur:11500000, size:44067
09:32:35 T:1717295968   DEBUG: Normal M:2515757 (A:0 V:12125000) P:0 A:-2.52 V:9.61/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717316299   DEBUG: Normal M:2536091 (A:0 V:12125000) P:0 A:-2.54 V:9.59/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717336593   DEBUG: Normal M:2556386 (A:0 V:12125000) P:0 A:-2.56 V:9.57/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717357098   DEBUG: Normal M:2576635 (A:0 V:12125000) P:0 A:-2.58 V:9.55/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717377395   DEBUG: Normal M:2597150 (A:0 V:12125000) P:0 A:-2.60 V:9.53/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717397686   DEBUG: Normal M:2617441 (A:0 V:12125000) P:0 A:-2.62 V:9.51/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717417974   DEBUG: Normal M:2637729 (A:0 V:12125000) P:0 A:-2.64 V:9.49/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717438259   DEBUG: Normal M:2658014 (A:0 V:12125000) P:0 A:-2.66 V:9.47/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717458759   DEBUG: Normal M:2678302 (A:0 V:12125000) P:0 A:-2.68 V:9.45/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717479044   DEBUG: Normal M:2698799 (A:0 V:12125000) P:0 A:-2.70 V:9.43/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717499326   DEBUG: Normal M:2719083 (A:0 V:12125000) P:0 A:-2.72 V:9.41/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717519607   DEBUG: Normal M:2739364 (A:0 V:12125000) P:0 A:-2.74 V:9.39/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717539887   DEBUG: Normal M:2759644 (A:0 V:12125000) P:0 A:-2.76 V:9.37/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717560376   DEBUG: Normal M:2779921 (A:0 V:12125000) P:0 A:-2.78 V:9.35/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717580659   DEBUG: Normal M:2800411 (A:0 V:12125000) P:0 A:-2.80 V:9.32/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717600939   DEBUG: Normal M:2820692 (A:0 V:12125000) P:0 A:-2.82 V:9.30/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717621221   DEBUG: Normal M:2840974 (A:0 V:12125000) P:0 A:-2.84 V:9.28/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717641500   DEBUG: Normal M:2861253 (A:0 V:12125000) P:0 A:-2.86 V:9.26/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717650178    INFO: CDVDPlayerVideo::Decode dts:11500000 pts:12125000 cur:12125000, size:68387
09:32:35 T:1717651274    INFO: CDVDPlayerVideo::Decode dts:11625000 pts:11875000 cur:11875000, size:45803
09:32:35 T:1717662556   DEBUG: Normal M:2882102 (A:0 V:11750000) P:0 A:-2.88 V:8.87/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717682874   DEBUG: Normal M:2902619 (A:0 V:11750000) P:0 A:-2.90 V:8.85/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717703163   DEBUG: Normal M:2922908 (A:0 V:11750000) P:0 A:-2.92 V:8.83/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717723444   DEBUG: Normal M:2943190 (A:0 V:11750000) P:0 A:-2.94 V:8.81/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717743724   DEBUG: Normal M:2963470 (A:0 V:11750000) P:0 A:-2.96 V:8.79/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717764228   DEBUG: Normal M:2983762 (A:0 V:11750000) P:0 A:-2.98 V:8.77/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:35 T:1717773050    INFO: CDVDPlayerVideo::Decode dts:11750000 pts:11750000 cur:11750000, size:46012
09:32:35 T:1717773973    INFO: CDVDPlayerVideo::Decode dts:11875000 pts:12000000 cur:12000000, size:44228
09:32:35 T:1717774749    INFO: CDVDPlayerVideo::Decode dts:12000000 pts:12625000 cur:12625000, size:67526
09:32:35 T:1717775941    INFO: CDVDPlayerVideo::Decode dts:12125000 pts:12375000 cur:12375000, size:47211
09:32:36 T:1717785330   DEBUG: Normal M:3005086 (A:0 V:12250000) P:0 A:-3.01 V:9.24/T:0.20 (0,0,1,1) A:0% V:98% (0.00,0.00)
09:32:36 T:1717806513   DEBUG: Normal M:3026270 (A:0 V:12250000) P:0 A:-3.03 V:9.22/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717826825   DEBUG: Normal M:3046582 (A:0 V:12250000) P:0 A:-3.05 V:9.20/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717847115   DEBUG: Normal M:3066872 (A:0 V:12250000) P:0 A:-3.07 V:9.18/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717867613   DEBUG: Normal M:3087155 (A:0 V:12250000) P:0 A:-3.09 V:9.16/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717887900   DEBUG: Normal M:3107651 (A:0 V:12250000) P:0 A:-3.11 V:9.14/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717908182   DEBUG: Normal M:3127933 (A:0 V:12250000) P:0 A:-3.13 V:9.12/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717928462   DEBUG: Normal M:3148213 (A:0 V:12250000) P:0 A:-3.15 V:9.10/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717948743   DEBUG: Normal M:3168494 (A:0 V:12250000) P:0 A:-3.17 V:9.08/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717969244   DEBUG: Normal M:3188778 (A:0 V:12250000) P:0 A:-3.19 V:9.06/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1717989531   DEBUG: Normal M:3209287 (A:0 V:12250000) P:0 A:-3.21 V:9.04/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718009817   DEBUG: Normal M:3229571 (A:0 V:12250000) P:0 A:-3.23 V:9.02/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718030133   DEBUG: Normal M:3249888 (A:0 V:12250000) P:0 A:-3.25 V:9.00/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718050415   DEBUG: Normal M:3270171 (A:0 V:12250000) P:0 A:-3.27 V:8.98/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718070944   DEBUG: Normal M:3290463 (A:0 V:12250000) P:0 A:-3.29 V:8.96/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718091233   DEBUG: Normal M:3310999 (A:0 V:12250000) P:0 A:-3.31 V:8.94/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718111516   DEBUG: Normal M:3331282 (A:0 V:12250000) P:0 A:-3.33 V:8.92/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718131796   DEBUG: Normal M:3351562 (A:0 V:12250000) P:0 A:-3.35 V:8.90/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718149951    INFO: CDVDPlayerVideo::Decode dts:12250000 pts:12250000 cur:12250000, size:45420
09:32:36 T:1718150931    INFO: CDVDPlayerVideo::Decode dts:12375000 pts:12500000 cur:12500000, size:48349
09:32:36 T:1718152084   DEBUG: Normal M:3371849 (A:0 V:13125000) P:0 A:-3.37 V:9.75/T:0.20 (0,0,1,1) A:0% V:98% (0.00,0.00)
09:32:36 T:1718173325   DEBUG: Normal M:3392869 (A:0 V:13125000) P:0 A:-3.39 V:9.73/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718193627   DEBUG: Normal M:3413395 (A:0 V:13125000) P:0 A:-3.41 V:9.71/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718213910   DEBUG: Normal M:3433678 (A:0 V:13125000) P:0 A:-3.43 V:9.69/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718234192   DEBUG: Normal M:3453959 (A:0 V:13125000) P:0 A:-3.45 V:9.67/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718254472   DEBUG: Normal M:3474240 (A:0 V:13125000) P:0 A:-3.47 V:9.65/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718272657    INFO: CDVDPlayerVideo::Decode dts:12500000 pts:13125000 cur:13125000, size:73877
09:32:36 T:1718273907    INFO: CDVDPlayerVideo::Decode dts:12625000 pts:12875000 cur:12875000, size:46258
09:32:36 T:1718274932    INFO: CDVDPlayerVideo::Decode dts:12750000 pts:12750000 cur:12750000, size:47795
09:32:36 T:1718275049   DEBUG: Normal M:3494509 (A:0 V:12750000) P:0 A:-3.49 V:9.26/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718296410   DEBUG: Normal M:3516158 (A:0 V:13000000) P:0 A:-3.52 V:9.48/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718316738   DEBUG: Normal M:3536488 (A:0 V:13000000) P:0 A:-3.54 V:9.46/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718337029   DEBUG: Normal M:3556779 (A:0 V:13000000) P:0 A:-3.56 V:9.44/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718357376   DEBUG: Normal M:3577126 (A:0 V:13000000) P:0 A:-3.58 V:9.42/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718377886   DEBUG: Normal M:3597423 (A:0 V:13000000) P:0 A:-3.60 V:9.40/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718398176   DEBUG: Normal M:3617940 (A:0 V:13000000) P:0 A:-3.62 V:9.38/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718418461   DEBUG: Normal M:3638226 (A:0 V:13000000) P:0 A:-3.64 V:9.36/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718438742   DEBUG: Normal M:3658507 (A:0 V:13000000) P:0 A:-3.66 V:9.34/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718459023   DEBUG: Normal M:3678787 (A:0 V:13000000) P:0 A:-3.68 V:9.32/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718479513   DEBUG: Normal M:3699061 (A:0 V:13000000) P:0 A:-3.70 V:9.30/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718499795   DEBUG: Normal M:3719553 (A:0 V:13000000) P:0 A:-3.72 V:9.28/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718520075   DEBUG: Normal M:3739833 (A:0 V:13000000) P:0 A:-3.74 V:9.26/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718540357   DEBUG: Normal M:3760114 (A:0 V:13000000) P:0 A:-3.76 V:9.24/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718560638   DEBUG: Normal M:3780396 (A:0 V:13000000) P:0 A:-3.78 V:9.22/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718581126   DEBUG: Normal M:3800677 (A:0 V:13000000) P:0 A:-3.80 V:9.20/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718601410   DEBUG: Normal M:3821169 (A:0 V:13000000) P:0 A:-3.82 V:9.18/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718621691   DEBUG: Normal M:3841450 (A:0 V:13000000) P:0 A:-3.84 V:9.16/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718641972   DEBUG: Normal M:3861730 (A:0 V:13000000) P:0 A:-3.86 V:9.14/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718659140    INFO: CDVDPlayerVideo::Decode dts:12875000 pts:13000000 cur:13000000, size:43223
09:32:36 T:1718659853    INFO: CDVDPlayerVideo::Decode dts:13000000 pts:13625000 cur:13625000, size:74990
09:32:36 T:1718662261   DEBUG: Normal M:3882019 (A:0 V:13375000) P:0 A:-3.88 V:9.49/T:0.20 (0,0,1,1) A:0% V:98% (0.00,0.00)
09:32:36 T:1718683537   DEBUG: Normal M:3903087 (A:0 V:13375000) P:0 A:-3.90 V:9.47/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718703844   DEBUG: Normal M:3923604 (A:0 V:13375000) P:0 A:-3.92 V:9.45/T:0.20 (0,0,1,1) A:0% V:99% (0.00,0.00)
09:32:36 T:1718714119   DEBUG: OMXClock::OMXStop
09:32:36 T:1718730038   DEBUG: OMXThread::Run - Exited thread with  id -1400901952
09:32:36 T:1718730653   DEBUG: OMXThread::StopThread - Thread stopped
09:32:36 T:1718753322   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_scheduler handle 0xac900cb0
09:32:36 T:1718766500   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0x13858a0
09:32:36 T:1718768603   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_render handle 0xac900670
09:32:36 T:1718768973   DEBUG: OMXThread::Run - Exited thread with  id -1388339520
09:32:36 T:1718769284   DEBUG: OMXThread::StopThread - Thread stopped
09:32:36 T:1718772825   DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.clock handle 0x1385990
githubarooski commented 3 months ago

for now I'm just hard coding it like this, but any tips on fixing it properly would be appreciated

$ cat /etc/supervisor/conf.d/video_looper.conf 
# Supervisord configuration to run video looper at boot and
# ensure it runs continuously.
[program:video_looper]
command=/bin/bash /usr/bin/omxplayer -o both --win "0 0 1600 1200" -g --loop /home/pi/video/video.mp4
#command=python3 -u -m Adafruit_Video_Looper.video_looper
autostart=true
autorestart=unexpected
startsecs=5
tofuSCHNITZEL commented 3 months ago

Hi have you tried adding --win "0 0 1600 1200" to the videolooper.ini? there is a setting called extra_args that is passed to omxplayer and I think this is exactly what you need in this case...

githubarooski commented 3 months ago

Hi have you tried adding --win "0 0 1600 1200" to the videolooper.ini? there is a setting called extra_args that is passed to omxplayer and I think this is exactly what you need in this case...

Thanks for your quick response. If you read my report, you can see that I've added the argument to the extra_args config and your script is adding those arguments correctly.

Can you help me figure out how to get omxplayer to generate a log file when it is called from your script? I add the -g to the extra_args, but I can't find the log file anywhere.

tofuSCHNITZEL commented 3 months ago

sorry for missing that.. please check the output of the videolooper log file like described here: https://github.com/adafruit/pi_video_looper?tab=readme-ov-file#troubleshooting maybe we can also see the omxplayer output there

tofuSCHNITZEL commented 3 months ago

hm, I read your post now properly - not sure why you would get a black screen as far as I can see pivideolooper runs omxplayer exactly like you so there is no reason why it would not display an image... not that it should matter (because it works with directly calling omx) what OS are you using? is the screen connected to a normal hdmi output?

githubarooski commented 3 months ago

Using HDMI0 of a raspberry pi 4 connected to the HDMI input of a very niche e-ink monitor from philips.

Here are the only active entries in my config.txt which I pulled from a similar monitor's info via WaveShare. 

max_usb_current=1
hdmi_group=2
hdmi_force_hotplug=1
hdmi_mode=87
hdmi_timings=1600 1 64 192 144 1200 1 4 5 41 0 0 0 60 0 150000000 1

nothing is written to video_looper-stderr..

supervisord.log

2024-05-03 19:55:50,631 INFO waiting for video_looper to die
2024-05-03 19:55:50,664 WARN received SIGTERM indicating exit request
2024-05-03 19:55:51,863 INFO stopped: video_looper (exit status 0)
2024-05-03 19:55:52,321 CRIT Supervisor is running as root.  Privileges were not dropped because no user is specified in the config file.  If you intend to run as root, you can set user=root in the config file to avoid this message.
2024-05-03 19:55:52,321 INFO Included extra file "/etc/supervisor/conf.d/video_looper.conf" during parsing
2024-05-03 19:55:52,343 INFO RPC interface 'supervisor' initialized
2024-05-03 19:55:52,344 CRIT Server 'unix_http_server' running without any HTTP authentication checking
2024-05-03 19:55:52,344 INFO supervisord started with pid 9882
2024-05-03 19:55:53,349 INFO spawned: 'video_looper' with pid 9885
2024-05-03 19:55:58,916 INFO success: video_looper entered RUNNING state, process has stayed up for > than 5 seconds (startsecs)

video_looper-stdout constantly repeats the same log entry 5 times per second:

==> video_looper-stdout---supervisor-_9joFZ.log <==
[2024-05-03 19:56:56.891073] Playing movie: video.mp4 (video) (endless loop)
[2024-05-03 19:56:57.075756] Playing movie: video.mp4 (video) (endless loop)
[2024-05-03 19:56:57.260718] Playing movie: video.mp4 (video) (endless loop)
[2024-05-03 19:56:57.479736] Playing movie: video.mp4 (video) (endless loop)
[2024-05-03 19:56:57.671416] Playing movie: video.mp4 (video) (endless loop)
githubarooski commented 3 months ago

Oh, and I'm using your distro .zip file directly for the install, not compiling it myself.

tofuSCHNITZEL commented 3 months ago

hm yes the playing movie entry appears because omxplayer is constantly crashing so pi_video_looper is starting the "next" video... basically pi_video_looper is just a "fancy" process launcher so if you can run omxplayer manually I cant think of a reason why it would not run over pi_video_looper.... what if you remove the -g from custom_args?

githubarooski commented 2 months ago

removing -g doesn't fix the issue. the only tiny outlier I can think of is that when I initially configured the OS, I set the username as an alternative username. I subsequently added the pi user back to the OS and the script is now running out of /home/pi/ like it expects to. 

$ grep extra_args /home/pi/pi_video_looper/assets/video_looper.ini 
extra_args = --no-osd --audio_fifo 0.01 --video_fifo 0.01 --align center --font-size 55
root@hostname:/var/log/supervisor# tail -f *
==> supervisord.log <==
2024-06-03 15:40:37,807 INFO Included extra file "/etc/supervisor/conf.d/video_looper.conf" during parsing
2024-06-03 15:40:37,875 INFO RPC interface 'supervisor' initialized
2024-06-03 15:40:37,875 CRIT Server 'unix_http_server' running without any HTTP authentication checking
2024-06-03 15:40:37,876 INFO supervisord started with pid 468
2024-06-03 15:40:38,884 INFO spawned: 'video_looper' with pid 478
2024-06-03 15:40:44,299 INFO success: video_looper entered RUNNING state, process has stayed up for > than 5 seconds (startsecs)
2024-06-03 15:44:36,104 INFO waiting for video_looper to stop
2024-06-03 15:44:36,496 INFO stopped: video_looper (exit status 0)
2024-06-03 15:44:36,514 INFO spawned: 'video_looper' with pid 7071
2024-06-03 15:44:42,419 INFO success: video_looper entered RUNNING state, process has stayed up for > than 5 seconds (startsecs)

==> video_looper-stderr---supervisor-RdMFA9.log <==

==> video_looper-stdout---supervisor-jott80.log <==
[2024-06-03 15:44:50.560425] Playing movie: video.mp4 (video) (endless loop)
[2024-06-03 15:44:50.964147] Playing movie: video.mp4 (video) (endless loop)
[2024-06-03 15:44:51.382080] Playing movie: video.mp4 (video) (endless loop)
[2024-06-03 15:44:51.795857] Playing movie: video.mp4 (video) (endless loop)
[2024-06-03 15:44:52.188375] Playing movie: video.mp4 (video) (endless loop)
githubarooski commented 2 months ago

tldr, I reformatted the drive with balena etcher which doesn't give you all the tweaking options that the raspberry pi imager provides and now it's working as expected. I think it was the extra settings portion of the raspberry pi imager which dorked everything up.

githubarooski commented 2 months ago

Ok, sorry for the many notifications, but I was wrong, this is still a problem, even with a fresh install, then upgrade to 1.0.18 with install.sh

Can you try testing on your end passing a custom resolution via extra_args?