[TRACE] 2019-08-12 09:03:23.744 [main] mediathek.Main - L&F property is empty, setting L&F
[INFO ] 2019-08-12 09:03:24.230 [main] mediathek.Main - Portable Mode: false
[DEBUG] 2019-08-12 09:03:24.358 [main] com.zaxxer.hikari.HikariConfig - HikariPool-1 - configuration:
[DEBUG] 2019-08-12 09:03:24.362 [main] com.zaxxer.hikari.HikariConfig - allowPoolSuspension.............false
[DEBUG] 2019-08-12 09:03:24.362 [main] com.zaxxer.hikari.HikariConfig - autoCommit......................true
[DEBUG] 2019-08-12 09:03:24.362 [main] com.zaxxer.hikari.HikariConfig - catalog.........................none
[DEBUG] 2019-08-12 09:03:24.362 [main] com.zaxxer.hikari.HikariConfig - connectionInitSql...............none
[DEBUG] 2019-08-12 09:03:24.362 [main] com.zaxxer.hikari.HikariConfig - connectionTestQuery.............none
[DEBUG] 2019-08-12 09:03:24.362 [main] com.zaxxer.hikari.HikariConfig - connectionTimeout...............30000
[DEBUG] 2019-08-12 09:03:24.363 [main] com.zaxxer.hikari.HikariConfig - dataSource......................none
[DEBUG] 2019-08-12 09:03:24.363 [main] com.zaxxer.hikari.HikariConfig - dataSourceClassName............."org.h2.jdbcx.JdbcDataSource"
[DEBUG] 2019-08-12 09:03:24.363 [main] com.zaxxer.hikari.HikariConfig - dataSourceJNDI..................none
[DEBUG] 2019-08-12 09:03:24.364 [main] com.zaxxer.hikari.HikariConfig - dataSourceProperties............{password=<masked>, URL=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview;DB_CLOSE_DELAY=-1;DB_CLOSE_ON_EXIT=FALSE;AUTO_RECONNECT=TRUE}
[DEBUG] 2019-08-12 09:03:24.364 [main] com.zaxxer.hikari.HikariConfig - driverClassName.................none
[DEBUG] 2019-08-12 09:03:24.364 [main] com.zaxxer.hikari.HikariConfig - healthCheckProperties...........{}
[DEBUG] 2019-08-12 09:03:24.364 [main] com.zaxxer.hikari.HikariConfig - healthCheckRegistry.............none
[DEBUG] 2019-08-12 09:03:24.364 [main] com.zaxxer.hikari.HikariConfig - idleTimeout.....................600000
[DEBUG] 2019-08-12 09:03:24.364 [main] com.zaxxer.hikari.HikariConfig - initializationFailTimeout.......1
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - isolateInternalQueries..........false
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - jdbcUrl.........................none
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - leakDetectionThreshold..........0
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - maxLifetime.....................1800000
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - maximumPoolSize.................9
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - metricRegistry..................none
[DEBUG] 2019-08-12 09:03:24.365 [main] com.zaxxer.hikari.HikariConfig - metricsTrackerFactory...........none
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - minimumIdle.....................9
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - password........................<masked>
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - poolName........................"HikariPool-1"
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - readOnly........................false
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - registerMbeans..................false
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - scheduledExecutor...............none
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - schema..........................none
[DEBUG] 2019-08-12 09:03:24.366 [main] com.zaxxer.hikari.HikariConfig - threadFactory...................internal
[DEBUG] 2019-08-12 09:03:24.367 [main] com.zaxxer.hikari.HikariConfig - transactionIsolation............default
[DEBUG] 2019-08-12 09:03:24.367 [main] com.zaxxer.hikari.HikariConfig - username........................none
[DEBUG] 2019-08-12 09:03:24.367 [main] com.zaxxer.hikari.HikariConfig - validationTimeout...............5000
[INFO ] 2019-08-12 09:03:24.368 [main] com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Starting...
[DEBUG] 2019-08-12 09:03:24.529 [main] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn0: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[INFO ] 2019-08-12 09:03:24.530 [main] com.zaxxer.hikari.HikariDataSource - HikariPool-1 - Start completed.
[INFO ] 2019-08-12 09:03:24.612 [AWT-EventQueue-0] mediathek.tool.Log - Programmstart: 12.08.2019 09:03:24
[DEBUG] 2019-08-12 09:03:24.613 [AWT-EventQueue-0] mediathek.tool.Log - totalMemory: 262 MB
[INFO ] 2019-08-12 09:03:24.613 [AWT-EventQueue-0] mediathek.tool.Log - maxMemory: 1073 MB
[INFO ] 2019-08-12 09:03:24.613 [AWT-EventQueue-0] mediathek.tool.Log - Version: MediathekView 13.3.0
[INFO ] 2019-08-12 09:03:24.613 [AWT-EventQueue-0] mediathek.tool.Log - Java:
[INFO ] 2019-08-12 09:03:24.615 [AWT-EventQueue-0] mediathek.tool.Log - Vendor: Ubuntu
[INFO ] 2019-08-12 09:03:24.615 [AWT-EventQueue-0] mediathek.tool.Log - VMname: OpenJDK 64-Bit Server VM
[INFO ] 2019-08-12 09:03:24.615 [AWT-EventQueue-0] mediathek.tool.Log - Version: 11.0.4
[INFO ] 2019-08-12 09:03:24.615 [AWT-EventQueue-0] mediathek.tool.Log - Runtimeversion: 11.0.4+11-post-Ubuntu-1ubuntu218.04.3
[INFO ] 2019-08-12 09:03:24.616 [AWT-EventQueue-0] mediathek.tool.MVFunctionSys - Programmpfad: /usr/share/mediathekview/
[INFO ] 2019-08-12 09:03:24.617 [AWT-EventQueue-0] mediathek.tool.MVFunctionSys - Verzeichnis Einstellungen: /home/harald/.mediathek3
[DEBUG] 2019-08-12 09:03:24.630 [HikariPool-1 housekeeper] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=1, active=0, idle=1, waiting=0)
[DEBUG] 2019-08-12 09:03:24.634 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn1: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.635 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn2: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.637 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn3: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.638 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn4: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.639 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn5: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.639 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn6: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.640 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn7: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.641 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Added connection conn8: url=jdbc:h2:file:/home/harald/.mediathek3/databasemediathekview user=
[DEBUG] 2019-08-12 09:03:24.642 [HikariPool-1 connection adder] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - After adding stats (total=9, active=0, idle=9, waiting=0)
[DEBUG] 2019-08-12 09:03:25.124 [AWT-EventQueue-0] mediathek.config.MVConfig - User-Agent: Mozilla
[INFO ] 2019-08-12 09:03:25.124 [AWT-EventQueue-0] mediathek.config.Daten - Konfig wurde gelesen!
[DEBUG] 2019-08-12 09:03:27.771 [AWT-EventQueue-0] mediathek.update.ProgramUpdateCheck - ProgramUpdateCheck Started.
[TRACE] 2019-08-12 09:03:28.071 [ForkJoinPool.commonPool-worker-3] mediathek.filmlisten.reader.FilmListReader - Liste Filme lesen von: /home/harald/.mediathek3/filme.json
[DEBUG] 2019-08-12 09:03:31.066 [ForkJoinPool.commonPool-worker-3] mediathek.filmlisten.reader.FilmListReader - Reading filmlist took 2.942 s
[INFO ] 2019-08-12 09:03:31.067 [ForkJoinPool.commonPool-worker-3] mediathek.filmlisten.reader.FilmListReader - Liste Filme gelesen am: 12.08.2019, 09:03
[INFO ] 2019-08-12 09:03:31.067 [ForkJoinPool.commonPool-worker-3] mediathek.filmlisten.reader.FilmListReader -   erstellt am: 12.08.2019, 08:00
[INFO ] 2019-08-12 09:03:31.067 [ForkJoinPool.commonPool-worker-3] mediathek.filmlisten.reader.FilmListReader -   Anzahl Filme: 294529
[INFO ] 2019-08-12 09:03:31.068 [ForkJoinPool.commonPool-worker-3] mediathek.daten.ListeFilme - Die Filmliste ist 62 Minuten alt
[TRACE] 2019-08-12 09:03:31.068 [ForkJoinPool.commonPool-worker-3] mediathek.javafx.FilmListFilterTask - FilmListFilterTask started
[DEBUG] 2019-08-12 09:03:32.060 [ForkJoinPool.commonPool-worker-3] mediathek.daten.ListeAbo - setAboFuerFilm: 945.7 ms
[DEBUG] 2019-08-12 09:03:32.253 [ForkJoinPool.commonPool-worker-3] mediathek.daten.ListeBlacklist - FILTERING and ADDING() took: 186.4 ms
[DEBUG] 2019-08-12 09:03:32.260 [ForkJoinPool.commonPool-worker-3] mediathek.daten.ListeBlacklist - filterListe(): 195.2 ms
[TRACE] 2019-08-12 09:03:32.264 [ForkJoinPool.commonPool-worker-3] mediathek.javafx.FilmListFilterTask - FilmListFilterTask finished
[INFO ] 2019-08-12 09:03:32.372 [AWT-EventQueue-0] mediathek.daten.ListeDownloads - Filme in Downloads eintragen
[INFO ] 2019-08-12 09:03:32.754 [AWT-EventQueue-0] mediathek.tool.MVHttpClient - MVHttpClient: Proxy not configured
[INFO ] 2019-08-12 09:03:35.794 [AWT-EventQueue-0] mediathek.config.Daten - -------------------------------------------------------
[INFO ] 2019-08-12 09:03:35.794 [AWT-EventQueue-0] mediathek.config.Daten - Einstellungen sichern
[INFO ] 2019-08-12 09:03:35.794 [AWT-EventQueue-0] mediathek.config.Daten - Einstellungen wurden heute schon gesichert
[INFO ] 2019-08-12 09:03:35.795 [AWT-EventQueue-0] mediathek.config.Daten - -------------------------------------------------------
[INFO ] 2019-08-12 09:03:35.797 [AWT-EventQueue-0] mediathek.controller.IoXmlSchreiben - Daten Schreiben nach: /home/harald/.mediathek3/mediathek.xml
[INFO ] 2019-08-12 09:03:35.797 [AWT-EventQueue-0] mediathek.controller.IoXmlSchreiben - Config Schreiben nach: /home/harald/.mediathek3/mediathek.xml startet
[INFO ] 2019-08-12 09:03:35.816 [AWT-EventQueue-0] mediathek.controller.IoXmlSchreiben - Config Schreiben beendet
[DEBUG] 2019-08-12 09:03:35.819 [AWT-EventQueue-0] mediathek.update.AutomaticFilmlistUpdate - AutomaticFilmlistUpdate Started.
[DEBUG] 2019-08-12 09:03:35.824 [Index Thread] mediathek.daten.ListeMediaDB - Mediensammlung erstellen
[DEBUG] 2019-08-12 09:03:54.632 [HikariPool-1 housekeeper] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=9, active=0, idle=9, waiting=0)
[INFO ] 2019-08-12 09:03:56.221 [Index Thread] mediathek.daten.ListeMediaDB - MediaDB schreiben (0) Dateien :
[INFO ] 2019-08-12 09:03:56.222 [Index Thread] mediathek.daten.ListeMediaDB -    --> Start Schreiben nach: /home/harald/.mediathek3/mediadb.txt
[INFO ] 2019-08-12 09:03:56.224 [Index Thread] mediathek.daten.ListeMediaDB -    --> geschrieben!
[DEBUG] 2019-08-12 09:03:56.225 [Index Thread] mediathek.daten.ListeMediaDB - Ende Mediensammlung erstellen
[INFO ] 2019-08-12 09:04:02.814 [PROGRAMM DL THREAD: zeit.geschichte: Hitlers Zirkel des Bösen - Das Ende (1945)] mediathek.controller.starter.StarterClass - [Download starten, Programmset: Linux Speichern, Ziel: /home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4, URL: https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8, Startzeit: 09:04:02, Programmaufruf: /usr/bin/ffmpeg -user_agent "Mozilla"  -i https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8 -c copy -bsf:a aac_adtstoasc /home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4, Programmaufruf[]: /usr/bin/ffmpeg<>-user_agent<>"Mozilla"<><>-i<>https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8<>-c<>copy<>-bsf:a<>aac_adtstoasc<>/home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4]
[INFO ] 2019-08-12 09:04:02.828 [PROGRAMM DL THREAD: zeit.geschichte: Hitlers Zirkel des Bösen - Das Ende (1945)] mediathek.controller.starter.RuntimeExec - =====================
[INFO ] 2019-08-12 09:04:02.828 [PROGRAMM DL THREAD: zeit.geschichte: Hitlers Zirkel des Bösen - Das Ende (1945)] mediathek.controller.starter.RuntimeExec - Starte Array: 
[INFO ] 2019-08-12 09:04:02.830 [PROGRAMM DL THREAD: zeit.geschichte: Hitlers Zirkel des Bösen - Das Ende (1945)] mediathek.controller.starter.RuntimeExec -  -> /usr/bin/ffmpeg<>-user_agent<>"Mozilla"<><>-i<>https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8<>-c<>copy<>-bsf:a<>aac_adtstoasc<>/home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4
[INFO ] 2019-08-12 09:04:02.831 [PROGRAMM DL THREAD: zeit.geschichte: Hitlers Zirkel des Bösen - Das Ende (1945)] mediathek.controller.starter.RuntimeExec - =====================
[INFO ] 2019-08-12 09:04:04.910 [PROGRAMM DL THREAD: zeit.geschichte: Hitlers Zirkel des Bösen - Das Ende (1945)] mediathek.controller.starter.StarterClass - [Download ist fertig und war fehlerhaft, Programmset: Linux Speichern, Ziel: /home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4, Startzeit: 09:04:02, Endzeit: 09:04:04, Restarts: 0, Dauer: 2 s, Dauer: <1 Min., URL: https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8, Programmaufruf: /usr/bin/ffmpeg -user_agent "Mozilla"  -i https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8 -c copy -bsf:a aac_adtstoasc /home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4, Programmaufruf[]: /usr/bin/ffmpeg<>-user_agent<>"Mozilla"<><>-i<>https://apasfiis.sf.apa.at/ipad/cms-austria/2019-08-10_2240_sd_06_zeit-geschichte_____14022506__o__1399411620__s14541141_1__ORF3HD_22420900P_23265316P_Q8C.mp4/playlist.m3u8<>-c<>copy<>-bsf:a<>aac_adtstoasc<>/home/harald/Downloads/Dokus/zeit.geschichte-zeit.geschichte_ Hitlers Zirkel des Bösen - Das Ende (1945)-1056467918.mp4]
[DEBUG] 2019-08-12 09:04:24.633 [HikariPool-1 housekeeper] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=9, active=0, idle=9, waiting=0)
[DEBUG] 2019-08-12 09:04:27.774 [ForkJoinPool.commonPool-worker-7] mediathek.update.ProgramUpdateCheck - performUpdateCheck started.
[DEBUG] 2019-08-12 09:04:28.324 [ForkJoinPool.commonPool-worker-7] mediathek.update.ProgramUpdateCheck - performUpdateCheck finished.
[DEBUG] 2019-08-12 09:04:54.634 [HikariPool-1 housekeeper] com.zaxxer.hikari.pool.HikariPool - HikariPool-1 - Pool stats (total=9, active=0, idle=9, waiting=0)