[Pfeifenraucher]

Moin!

Ich habe mir die log-Dateien mal angeschaut und stolpere über das Zusammenspiel von epgd und epg2vdr. Hier mal ein Auszug aus einem recht frisch gebooteten VM.

Spoiler (show / hide)

/var/log/messages

Code: [Select]

Oct 25 15:45:01 (MLD) user.err epgd: Set locale to 'de_DE.UTF-8'
Oct 25 15:45:01 (MLD) user.err epgd: detected UTF-8
Oct 25 15:45:01 (MLD) user.err epgd: Loading uuid from '/etc/epgd/uuid' succeeded [7C40D815-37F7-49A1-A276-F3FFAF0ACE7D]
Oct 25 15:45:01 (MLD) user.err epgd: Loading plugin: /lib/epgd/plugins/libepgd-epgdata.so
Oct 25 15:45:01 (MLD) user.err epgd: Loading plugin: /lib/epgd/plugins/libepgd-tvm.so
Oct 25 15:45:01 (MLD) user.err vdr: [2262] [live] INFO: validating server ip '192.168.2.240'
Oct 25 15:45:01 (MLD) user.err epgd: Read 25 option from /etc/epgd/epgd.conf
Oct 25 15:45:01 (MLD) user.err epgd: Info: Stylesheet '/etc/epgd/epgdata-utf-8.xsl' loaded
Oct 25 15:45:01 (MLD) user.err epgd: Info: Stylesheet '/etc/epgd/tvmovie-utf-8.xsl' loaded
Oct 25 15:45:01 (MLD) user.err epgd: SQL client character now 'utf8'
Oct 25 15:45:01 (MLD) user.err vdr: epg2vdr: Set locale to 'de_DE.UTF-8'
Oct 25 15:45:01 (MLD) user.err vdr: epg2vdr: detected UTF-8
Oct 25 15:45:01 (MLD) user.err vdr: [2271] ERROR: unexpected tag while reading EPG data:
Oct 25 15:45:01 (MLD) user.err vdr: [2271] ERROR: EPG data problem in line 13
Oct 25 15:45:01 (MLD) user.err vdr: [2271] ERROR: EPG data problem in file /data/tv/Der_Duft_der_Frauen/2011-04-25.23.38.50.99.rec/info.vdr
Oct 25 15:45:01 (MLD) user.err vdr: epg2vdr: SQL client character now 'utf8'
Oct 25 15:45:02 (MLD) user.err epgd: State now 'init'
Oct 25 15:45:09 (MLD) auth.info login[3055]: root login on 'tty2'
Oct 25 15:45:09 (MLD) auth.info login[3054]: root login on 'tty1'
Oct 25 15:45:09 (MLD) auth.info login[3056]: root login on 'tty3'
Oct 25 15:45:09 (MLD) auth.info login[3064]: root login on 'tty4'
Oct 25 15:45:10 (MLD) user.err vdr: epg2vdr: Info: Last update was at '15.10.25 14:59:46'
Oct 25 15:45:10 (MLD) user.err epgd: Loading '/etc/epgd/channelmap.conf'
Oct 25 15:45:10 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:45:10 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:45:10 (MLD) user.err vdr: epg2vdr: Update thread started (pid=2262)
Oct 25 15:45:10 (MLD) user.err vdr: [3082] [live] INFO: attempt to listen on ip = '192.168.2.240'
Oct 25 15:45:10 (MLD) user.err epgd: 188 channel mappings read.
Oct 25 15:45:10 (MLD) user.err epgd: No external events on database, force initial check!
Oct 25 15:45:10 (MLD) user.err epgd: Scheduled next update in 10 second(s)
Oct 25 15:45:10 (MLD) user.err epgd: State now 'standby'
Oct 25 15:45:11 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2278
Oct 25 15:45:20 (MLD) user.err vdr: epg2vdr: Updating timer
Oct 25 15:45:20 (MLD) user.err vdr: epg2vdr: Update timer for event 22839
Oct 25 15:45:20 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:45:20 (MLD) user.err epgd: State now 'busy (events)'
Oct 25 15:45:21 (MLD) user.err epgd: Starting cleanup of events
Oct 25 15:45:21 (MLD) user.err epgd: Delete fileref [substr(name,1,8) <= '20151024']
Oct 25 15:45:21 (MLD) user.err epgd: Delete events [starttime+duration < 1445762721]
Oct 25 15:45:24 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:45:29 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 76144 hashes (in 5 seconds)
Oct 25 15:45:29 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:45:29 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:45:29 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:45:31 (MLD) user.err epgd: Cleanup of events finished
Oct 25 15:45:31 (MLD) user.err epgd: EPG Update started
Oct 25 15:45:31 (MLD) user.err epgd: EPG Update finished, loaded 0 files (0.000 KB), 0 non-updates skipped, 0 rejected due to format error.
Oct 25 15:45:31 (MLD) user.err epgd: Starting episode download ...
Oct 25 15:45:31 (MLD) user.err epgd: SVDRPCL: connected to eplists.constabel.net:2006 'eplists.constabel.net EpisodesListsServer 0.0.5-beta6; Sun Oct 25 15:45:31 2015'
Oct 25 15:45:31 (MLD) user.err epgd: Got 'Setting encoding to utf8'
Oct 25 15:45:31 (MLD) user.err epgd: Requesting episode changes of last 141 minutes
Oct 25 15:45:31 (MLD) user.err epgd: Received 1 episode files
Oct 25 15:45:32 (MLD) user.err epgd: Starting episode lookup ...
Oct 25 15:45:34 (MLD) user.err epgd: Warning, size of 200 for 'comppartname' exeeded [DIETRUPPESTEIGTINSTSHIRTGESCHÄFTEINALSDIEPENNYTEESZUMVERKAUFSSCHLAGERWERDENREKRUTIERTSAMVIERTKLÄSSLERDIEBEIDERHERSTELLUNGDERSHIRTSHELFENSOLLENDOCHCARLYSAMUNDFREDDIESTREITENSICHWE
Oct 25 15:45:35 (MLD) user.err epgd: Warning, size of 200 for 'comppartname' exeeded [ZOEYVERSUCHTVERZWEIFELTEINGEBURTSTAGSGESCHENKFÜRCHASEZUFINDENALSSIEERFÄHRTDASSSEINEGROßMUTTERAMSELBENTAGGEBURTSTAGHATWIEERHATSIEEINEGENIALEIDEEAUßERDEMKLEMMTDUSTINSICHDENARMINE
Oct 25 15:45:35 (MLD) user.err epgd: Warning, size of 200 for 'comppartname' exeeded [ZOEYTRITTDERRINGERMANNSCHAFTBEINACHDEMSIEEINENSTREITGESCHLICHTETHATDOCHIHRTRAINERLÄSSTSIENICHTAUFDIEMATTEBEIDERREGIONALMEISTERSCHAFTBEKOMMTSIESCHLIEßLICHIHRECHANCEUNDDARFGEGENEIN
Oct 25 15:45:35 (MLD) user.err epgd: Warning, size of 200 for 'comppartname' exeeded [FÜREINESCHULAUFGABESCHREIBENZOEYUNDIHREFREUNDEIHREPEINLICHSTENGEHEIMNISSEAUFZETTELUNDBINDENSIEANLUFTBALLONSABERDIEEREIGNISSENEHMENEINEBÖSEWENDUNGALSJEMANDZOEYSGEHEIMNISHERAUSFIND
Oct 25 15:45:36 (MLD) user.err epgd: Lookup done for 1509 series, matched 1533 parts by compare and 126 parts by lv in 4 seconds; Updated 131
Oct 25 15:45:36 (MLD) user.err epgd: Calling 'mergeepg'
Oct 25 15:45:36 (MLD) user.err epgd: 'mergeepg' suceeded
Oct 25 15:45:36 (MLD) user.err epgd: State now 'busy (images)'
Oct 25 15:45:36 (MLD) user.err epgd: Starting cleanup of imagerefs
Oct 25 15:45:36 (MLD) user.err epgd: Starting cleanup of images
Oct 25 15:45:36 (MLD) user.err epgd: Image cleanup finished
Oct 25 15:45:36 (MLD) user.err epgd: Start download of new images
Oct 25 15:45:36 (MLD) user.err epgd: Loaded 0 images (0.000 KB), checked 0; 0 failed to load in 0 seconds
Oct 25 15:45:36 (MLD) user.err epgd: State now 'busy (scraping)'
Oct 25 15:45:47 (MLD) user.err epgd: Scheduled next update in 12 hour(s)
Oct 25 15:45:47 (MLD) user.err epgd: State now 'standby'
Oct 25 15:45:50 (MLD) user.err vdr: epg2vdr: --- EPG update started ---
Oct 25 15:45:50 (MLD) user.err vdr: epg2vdr: Exit image cleanup to avoid deleting of all images on nearly empty imagerefs table
Oct 25 15:45:50 (MLD) user.err vdr: epg2vdr: Update EPG, loading changes since 25.10.2015 14:59:46
Oct 25 15:45:57 (MLD) user.err vdr: epg2vdr: Updated changes since '25.10.2015 14:59:46'; 188 channels, 26245 events (4 deletions) in 6.586 seconds
Oct 25 15:45:58 (MLD) user.err vdr: epg2vdr: Load images from database
Oct 25 15:45:58 (MLD) user.err vdr: epg2vdr: Got 0 images from database in 0 seconds (0 updates, 0 new)
Oct 25 15:45:58 (MLD) user.err vdr: epg2vdr: --- EPG update finished ---
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2296
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75482 hashes (in 0 seconds)
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2290
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2293
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2287
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2284
Oct 25 15:46:04 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75482 hashes (in 1 seconds)
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2281
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Init handler instance for thread 2299
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75482 hashes (in 0 seconds)
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:46:05 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:46:08 (MLD) user.err vdr: epg2vdr: Updating timer
Oct 25 15:46:08 (MLD) user.err vdr: epg2vdr: Update timer for event 22839
Oct 25 15:46:25 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:46:25 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:46:25 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75601 hashes (in 0 seconds)
Oct 25 15:46:25 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:46:25 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:46:25 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75641 hashes (in 0 seconds)
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:46:46 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:46:47 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75641 hashes (in 1 seconds)
Oct 25 15:46:47 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:46:47 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:46:47 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:46:50 (MLD) user.err epgd: State now 'busy (match)'
Oct 25 15:47:01 (MLD) user.err epgd: 503 DVB pending, mergeepg done after 0.119 seconds
Oct 25 15:47:01 (MLD) user.err epgd: State now 'standby'
Oct 25 15:47:10 (MLD) user.err vdr: epg2vdr: Updated changes since '25.10.2015 15:45:58'; 188 channels, 446 events (0 deletions) in 1.429 seconds
Oct 25 15:47:28 (MLD) user.err vdr: epg2vdr: Trying to re-connect to database!
Oct 25 15:47:28 (MLD) user.err vdr: epg2vdr: Start reading hashes from db
Oct 25 15:47:29 (MLD) user.err vdr: epg2vdr: Finished reading hashes from db, got 75784 hashes (in 1 seconds)
Oct 25 15:47:29 (MLD) user.err vdr: epg2vdr: Start reading external ids from db
Oct 25 15:47:29 (MLD) user.err vdr: epg2vdr: Finished reading external id's from db, got 188 id's
Oct 25 15:47:29 (MLD) user.err vdr: epg2vdr: Connection established successfull!
Oct 25 15:47:52 (MLD) user.err epgd: State now 'busy (match)'
Oct 25 15:48:03 (MLD) user.err epgd: 704 DVB pending, mergeepg done after 0.163 seconds
Oct 25 15:48:03 (MLD) user.err epgd: State now 'standby'
Oct 25 15:48:12 (MLD) user.err vdr: epg2vdr: Updated changes since '25.10.2015 15:47:10'; 188 channels, 1103 events (0 deletions) in 1.113 seconds
Oct 25 15:48:23 (MLD) user.err epgd: State now 'busy (match)'
Oct 25 15:48:34 (MLD) user.err epgd: 554 DVB pending, mergeepg done after 0.292 seconds
Oct 25 15:48:35 (MLD) user.err epgd: State now 'standby'


Was muss ich den Systemen mitgeben, damit sich das System beruhigt und die Log-Dateien nicht vollgepammt werden?

Gruß,
Erik.

[skippy]

Hallo Erik,

nach meinem Kenntnisstand ist es normal, dass der epgd beim ersten Mal soviel ins Logfile schreibt. Das sollte sich beruhigen, wenn er einmal alle EPG-Daten geholt und in die DB geschrieben hat. Danach holt er nur noch neue Daten und Updates. Bei mir dauert das so ca. eine Stunde, bis die Daten drin sind, habe aber schon länger nicht mehr neu aufgesetzt.

Viele Grüße skippy

[Pfeifenraucher]

nach meinem Kenntnisstand ist es normal, dass der epgd beim ersten Mal soviel ins Logfile schreibt. Das sollte sich beruhigen, wenn er einmal alle EPG-Daten geholt und in die DB geschrieben hat. Danach holt er nur noch neue Daten und Updates. Bei mir dauert das so ca. eine Stunde, bis die Daten drin sind, habe aber schon länger nicht mehr neu aufgesetzt.

Zur Zeit scheint das tatsächlich der Fall zu sein, ich schau mir das morgen früh mal genauer an. Interessanterweise hat sich mit meiner heutigen scheinbar bereinigenden Aktion das System in die richtige Richtung entwickelt zu haben - der VDR hat jetzt die EPG-Daten und fügt diese auch den Aufnahmen bei.

Gruß,
Erik

[Pfeifenraucher]

Wie war das nur mit - Denkste.

Innerhalb von zwei Stunden ist meine /ver/log/messages voll.

Ich habe mal eine drangehängt, vielleicht hat jemand eine Idee, wie ich epgd & epg2vdr auf die eingestellten 6 bzw. 12h ruhiggestellt bekomme. Ich hätte auch nichts gegen die voreingestellten 2h des epgd, allerdings musste ich mir erst einmal sicher sein, dass die eingestellten Werte nichts mit meinem Problem zu tun haben.

Die DeBug-Logs sind auch oben: 0s5eNs

Gruß,
Erik.