+ (1) Background File Eraser + (8) graphics acceleration manager + (9) gLCDDC found OLED display! + (9) GFBDC 16384k video mem - double buffering available! 13144kB available for acceleration surfaces. resolution: 720 x 576 x 32 (stride: 2880) + (9) Font Render Class [FONT] initializing lib... [FONT] loading fonts... PYTHONPATH: /usr/lib/enigma2/python [FONT] Intializing font cache, using max. 4MB... + (10) gRC RC thread created successfully + (15) eWindowStyleManager + (20) DVB-CI UI + (20) misc options + (20) UHF Modulator couldnt open /dev/rfmod0!!!! + (20) AVSwitch Driver + (20) RC Input layer + (21) Console RC Driver + (21) input device driver Input device "dreambox remote control (native)" is not a keyboard. Input device "dreambox advanced remote control (native)" is not a keyboard. Input device "dreambox ir keyboard" is a keyboard. Input device "dreambox ir mouse" is not a keyboard. Input device "dreambox front panel" is not a keyboard. Found 5 input devices! + (30) eActionMap + (35) CI Slots scanning for common interfaces.. CI Slot 0 setSource(0) CI Slot 1 setSource(0) CI Slot 2 setSource(0) CI Slot 3 setSource(0) eDVBCIInterfaces->setInputSource(0, 0) eDVBCIInterfaces->setInputSource(1, 1) eDVBCIInterfaces->setInputSource(2, 2) eDVBCIInterfaces->setInputSource(3, 3) done, found 4 common interface slots + (40) eServiceCenter settings instance. + (41) eServiceFactoryFS + (41) eServiceFactoryDVB + (41) eServiceFactoryMP3 reached rl 70 ---- opening lame channel db reading services (version 4) loaded 264 services scanning for frontends.. opening frontend 0 detected satellite frontend close frontend 0 opening frontend 1 detected satellite frontend close frontend 1 opening frontend 2 detected cable frontend close frontend 2 opening frontend 3 detected cable frontend close frontend 3 found 1 adapter, 4 frontends(4 sim) and 5 demux, boxtype 3 Use valid Linux Time :) (RTC?) [EPGC] Initialized EPGCache [EPGC] time updated.. start EPG Mainloop before: 1 after: 1 Loading spinners... found 4 spinner! setIoPrio best-effort level 3 ok /proc/stb/fp/lnb_sense0 is 0 enumerating block devices... found block device 'hda': ok, removable=True, cdrom=True, partitions=[], device=hda couldn't read model: [Errno 2] No such file or directory: '/sys/devices/pci0000:01/0000:01:00.0/ide0/0.0/model' found block device 'mtdblock6': blacklisted found block device 'mtdblock5': blacklisted found block device 'mtdblock4': blacklisted found block device 'mtdblock3': blacklisted found block device 'mtdblock2': blacklisted found block device 'mtdblock1': blacklisted found block device 'mtdblock0': blacklisted found block device 'hdc': ok, removable=False, cdrom=False, partitions=['hdc1'], device=hdc couldn't read model: [Errno 2] No such file or directory: '/sys/devices/pci0000:01/0000:01:00.0/ide1/1.0/model' new Harddisk hdc -> /dev/ide/host0/bus1/target0/lun0 -> /dev/ide/host0/bus1/target0/lun0/disc [ePopen] command: ('hdparm', 'hdparm', '-S0', '/dev/ide/host0/bus1/target0/lun0/disc') couldn't read model: [Errno 2] No such file or directory: '/sys/devices/pci0000:01/0000:01:00.0/ide1/1.0/model' found block device 'loop7': blacklisted found block device 'loop6': blacklisted found block device 'loop5': blacklisted found block device 'loop4': blacklisted found block device 'loop3': blacklisted found block device 'loop2': blacklisted found block device 'loop1': blacklisted found block device 'loop0': blacklisted not loading user skin: [Errno 2] No such file or directory: '/etc/enigma2/skin_user.xml' main thread is non-idle! display spinner! Preferred tag editor set to None Reading satellites.xml Reading cables.xml couldn't open /etc/tuxbox/cables.xml!! sec config cleared setSlotInfo for dvb frontend 0 to slotid 0, descr BCM4506 (internal), need rotorworkaround No, enabled No, DVB-S2 Yes setSlotInfo for dvb frontend 1 to slotid 1, descr BCM4506 (internal), need rotorworkaround No, enabled No, DVB-S2 Yes setSlotInfo for dvb frontend 2 to slotid 2, descr Philips CU1216Mk3, need rotorworkaround No, enabled Yes, DVB-S2 No setSlotInfo for dvb frontend 3 to slotid 3, descr Philips CU1216Mk3, need rotorworkaround No, enabled Yes, DVB-S2 No slot: 0 configmode: nothing slot: 1 configmode: nothing sec config completed loading bouquet... /etc/enigma2/bouquets.tv loading bouquet... /etc/enigma2/userbouquet.favourites.tv 69 entries in Bouquet userbouquet.favourites.tv 1 entries in Bouquet bouquets.tv loading bouquet... /etc/enigma2/bouquets.radio loading bouquet... /etc/enigma2/userbouquet.favourites.radio 0 entries in Bouquet userbouquet.favourites.radio 1 entries in Bouquet bouquets.radio [eDVBLocalTimeHandler] enable sync local time with transponder time! main thread is non-idle! display spinner! add dreampackage scanner plugin added [FONT] adding font /usr/share/fonts/nmsbd.ttf...executing main OK (Regular) [FONT] adding font /usr/share/fonts/lcd.ttf...OK (LCD) [FONT] adding font /usr/share/fonts/ae_AlMateen.ttf...OK (Replacement) [FONT] adding font /usr/share/fonts/tuxtxt.ttf...OK (Console) --> setting scaler_sharpness to: 0000000D couldn't open /proc/stb/misc/12V_output [ePopen] command: ip -o addr [ePopen] command: ip -o addr setLCDBrightness 25 setLCDBrightness 127 Activating keymap: Keyboard English [ePopen] command: loadkmap < /usr/share/keymaps/eng.kmap Activating language English language set to en_EN [WebInterface] set language to en [WebInterface] set language to en [WebInterface] fallback to default translation for Webinterface [WebInterface] fallback to default translation for Configuration for the Webinterface [AudioSync] set language to en [AudioSync] set language to en getModeList for port DVI-PC getModeList for port YPbPr getModeList for port Scart getModeList for port DVI-PC getModeList for port DVI hotplug on dvi -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto setMode - port: DVI mode: 1080i rate: multi -> setting aspect, policy, policy2, wss 16:9 panscan letterbox auto [CleanupWizard] freeSpaceAvailable--> 12724 starting hotplug handler [CrashlogAutoSubmit] - autostart [CI_Assignment] activating ci configs: [CI_Activate] FOUND 4 CI Slots [CI_Activate_Config_CI0] no config file found [CI_Activate_Config_CI0] error parsing xml... [CI_Activate_Config_CI1] no config file found [CI_Activate_Config_CI1] error parsing xml... [CI_Activate_Config_CI2] no config file found [CI_Activate_Config_CI2] error parsing xml... [CI_Activate_Config_CI3] no config file found [CI_Activate_Config_CI3] error parsing xml... It's now Sat Dec 25 16:16:55 2010 [timer.py] next activation: 1293290315 (in 99577 ms) [TIMER] record time changed, start prepare is now: Sat Dec 25 21:39:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Sat Dec 25 21:40:00 2010 localbegin: Sat Dec 25 21:40:00 2010 localend: Sun Dec 26 00:00:00 2010 localnow: Sat Dec 25 16:16:56 2010 Day: 5 ProcessRepeated result Sat Dec 25 21:40:00 2010 Sun Dec 26 00:00:00 2010 It's now Sat Dec 25 16:16:55 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290315 (in 99542 ms) [TIMER] record time changed, start prepare is now: Sun Dec 26 07:19:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Sun Dec 26 07:20:00 2010 localbegin: Sun Dec 26 07:20:00 2010 localend: Sun Dec 26 08:45:00 2010 localnow: Sat Dec 25 16:16:56 2010 Day: 0 Day: 1 Day: 2 Day: 3 Day: 4 Day: 5 Day: 6 ProcessRepeated result Sun Dec 26 07:20:00 2010 Sun Dec 26 08:45:00 2010 It's now Sat Dec 25 16:16:55 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290315 (in 99461 ms) [TIMER] record time changed, start prepare is now: Sun Dec 26 19:19:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Sun Dec 26 19:20:00 2010 localbegin: Sun Dec 26 19:20:00 2010 localend: Sun Dec 26 20:00:00 2010 localnow: Sat Dec 25 16:16:56 2010 Day: 6 ProcessRepeated result Sun Dec 26 19:20:00 2010 Sun Dec 26 20:00:00 2010 It's now Sat Dec 25 16:16:55 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290315 (in 99373 ms) [TIMER] record time changed, start prepare is now: Sun Dec 26 20:14:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Sun Dec 26 20:15:00 2010 localbegin: Sun Dec 26 20:15:00 2010 localend: Sun Dec 26 22:15:00 2010 localnow: Sat Dec 25 16:16:56 2010 Day: 6 ProcessRepeated result Sun Dec 26 20:15:00 2010 Sun Dec 26 22:15:00 2010 It's now Sat Dec 25 16:16:55 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290315 (in 99276 ms) [TIMER] record time changed, start prepare is now: Sun Dec 26 22:59:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Sun Dec 26 23:00:00 2010 localbegin: Sun Dec 26 23:00:00 2010 localend: Mon Dec 27 00:15:00 2010 localnow: Sat Dec 25 16:16:56 2010 Day: 6 ProcessRepeated result Sun Dec 26 23:00:00 2010 Mon Dec 27 00:15:00 2010 It's now Sat Dec 25 16:16:55 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290315 (in 99170 ms) [TIMER] record time changed, start prepare is now: Wed Dec 29 03:04:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:208:492:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:208:492:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Wed Dec 29 03:05:00 2010 localbegin: Wed Dec 29 03:05:00 2010 localend: Wed Dec 29 03:50:00 2010 localnow: Sat Dec 25 16:16:56 2010 Day: 2 ProcessRepeated result Wed Dec 29 03:05:00 2010 Wed Dec 29 03:50:00 2010 It's now Sat Dec 25 16:16:55 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290315 (in 99044 ms) [TIMER] record time changed, start prepare is now: Thu Dec 30 21:29:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:460:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:208:492:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:460:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:456:7D0:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:1CC:44C:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:208:492:FE00:FFFF0000:0:0:0: record: 0 recording service: 1:0:1:18D8:4F6:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated localrepeatedbegindate: Thu Dec 30 21:30:00 2010 localbegin: Thu Dec 30 21:30:00 2010 localend: Thu Dec 30 23:00:00 2010 localnow: Sat Dec 25 16:16:57 2010 Day: 3 ProcessRepeated result Thu Dec 30 21:30:00 2010 Thu Dec 30 23:00:00 2010 It's now Sat Dec 25 16:16:56 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290316 (in 99922 ms) [TIMER] record time changed, start prepare is now: Fri Dec 17 19:59:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:938:442:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 [TIMER] record time changed, start prepare is now: Mon Dec 20 00:29:40 2010 sanitycheck check checkTimerlist recording service: 1:0:1:460:7D0:FE00:FFFF0000:0:0:0: record: 0 [Timer] Record ProcessRepeated already passed, skipping shouldSkip: True state == ended False waiting && disabled: 0 It's now Sat Dec 25 16:16:56 2010 [timer.py] next activation: 1293290316 (in 99795 ms) [CrashlogAutoSubmit] - autostart [CrashlogAutoSubmit] - getMailExchange not showing fine-tuning wizard, config variable doesn't exist showtestcard is false Looking for embedded skin setValue 100 cannot open /proc/stb/audio/j1_mute(No such file or directory) Setvolume: 100 100 (raw) Setvolume: 0 0 (-1db) Setvolume: 100 100 (raw) Setvolume: 0 0 (-1db) allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! RemovePopup, id = ZapError allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! reused converter! allocating new converter! allocating new converter! reused converter! reused converter! reused converter! allocating new converter! reused converter! allocating new converter! reused converter! allocating new converter! reused converter! reused converter! reused converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! playing 1:0:1:33E:442:FE00:FFFF0000:0:0:0: not pauseable. RemovePopup, id = ZapError allocate channel.. 0442:fe00 opening frontend 2 [eDVBCAService] new channel 0x108e3630! (2)tune tuning to 530000 khz, sr 6875000, fec 9, modulation 3, inversion 1 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:33E:442:FE00:FFFF0000:0:0:0: allocate demux [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 setting frontend 2 child has terminated pipes closed child has terminated pipes closed [ePopen] command: route -n | grep eth0 child has terminated pipes closed [ePopen] command: route -n | grep ath0 poll: unhandled POLLERR/HUP/NVAL for fd 32(16) child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 37(16) poll: unhandled POLLERR/HUP/NVAL for fd 38(16) poll: unhandled POLLERR/HUP/NVAL for fd 40(16) (2)fe event: status 0, inversion on, m_tuning 1 (2)fe event: status 1f, inversion on, m_tuning 2 OURSTATE: ok [eDVBCAService] channel 0x108e3630 running [eDVBLocalTimerHandler] channel 0x108e3630 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x108e3630 running [EPGC] next update in 2 sec stop release channel timer no version filtering 0012: 4e 03 3e 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (033e), and 1 audio stream(s) (033f), and the pcr pid is 033e, and the text pid is 0340 allocate demux disable teletext subtitles decoder state: play, vpid=830, apid=831 DMX_SET_PES_FILTER(0x33e) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x33f) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux0 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x33e) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x340) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok AUDIO_CHANNEL_SELECT(0) - ok not pauseable. child has terminated pipes closed child has terminated pipes closed 192.168 169.254 0.0.0.0 nameservers: [[192, 168, 235, 1]] read configured interface: {'lo': {'dhcp': False}, 'ath0': {'dhcp': True}, 'eth0': {'dhcp': True}} self.ifaces after loading: {'ath0': {'preup': '\tpre-up /usr/sbin/wpa_supplicant -iath0 -c/etc/wpa_supplicant.conf -B -dd -Dmadwifi\n', 'ip': [192, 168, 235, 200], 'up': True, 'netmask': [255, 255, 255, 0], 'dhcp': True, 'bcast': [192, 168, 235, 255], 'gateway': [192, 168, 235, 1], 'postdown': '\tpost-down wpa_cli terminate\n'}, 'eth0': {'preup': False, 'ip': [0, 0, 0, 0], 'up': False, 'mac': '00:09:34:1c:9f:9a', 'dhcp': True, 'netmask': [0, 0, 0, 0], 'gateway': [0, 0, 0, 0], 'postdown': False}} [Toplevel.importExternalModules] Could NOT import external module: AutoTimer [Toplevel.importExternalModules] Exception Caught No module named AutoTimer.AutoTimerResource [Toplevel.importExternalModules] Could NOT import external module: EPGRefresh [Toplevel.importExternalModules] Exception Caught No module named EPGRefresh.EPGRefreshResource [Toplevel.importExternalModules] Imported external module: Example [WebInterface] checking for SSL Certificates [Webinterface] started on 192.168.235.200:80 auth= False ssl= False [Webinterface] started on 192.168.235.200:443 auth= True ssl= True [Webinterface] started on 127.0.0.1:80 auth= False ssl= False poll: unhandled POLLERR/HUP/NVAL for fd 38(16) poll: unhandled POLLERR/HUP/NVAL for fd 39(16) [CrashlogAutoSubmit] gotMXServer: 82.149.226.184 [CrashlogAutoSubmit] - mxServerFound --> 82.149.226.184 [CrashlogAutoSubmit] - no crashlogs found. -+ 1/2 TID 4e + 1/1 TID 00 done! no version filtering 0347: 02 03 3e 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 17 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! eventNewProgramInfo 0 0 have 1 video stream(s) (033e), and 1 audio stream(s) (033f), and the pcr pid is 033e, and the text pid is 0340 decoder state: play, vpid=830, apid=831 [eDVBCAService] new service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: [eDVBCAService] add demux 0 to slot 0 service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: [eDVBCIInterfaces] gotPMT demux 0 mask 01 prevhash 00000000 [eDVBCAService] send 44 bytes 9f 80 32 28 03 03 3e 19 00 13 01 81 08 ff ff 00 00 04 42 fe 00 82 02 01 00 84 02 03 47 02 03 3e 00 00 03 03 3f 00 00 06 03 40 00 00 doing version filtering 0347: 02 03 3e 19 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! [EPGC] start caching events(1293290220) timeout 0012! [eDVBLocalTimerHandler] diff is 1 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] update RTC [eDVBLocalTimerHandler] time update to 16:17:02 [eDVBLocalTimerHandler] m_time_difference is 1 [eDVBLocalTimerHandler] set Linux Time [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 9389 bytes for cache used [EPGC] abort non avail viasat reading action -> InfobarMenuActions mainMenu loading mainmenu XML... Subtitles Subtitles TimerEdit TimerEditList PluginBrowser PluginBrowser allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! action -> OkCancelActions ok okbuttonClick action -> WizardActions ok node: node: config[titlesetmode].setValue(multi) config[name].setValue(Dreambox DVD record) config[menutemplate].setValue(/usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/Thumbs_menu_clouds.ddvdm.xml) config[authormode].setValue(menu_linked) config[dataformat].setValue(iso9660_4) config[vmgm].setValue(/usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/vmgmdream.mpg) config[output].setValue(dvd) config[isopath].setValue(/media/hdd/movie/) node: node: [xmlGetTitleNodeRecursive] -1 [] xmlGetTitleNodeRecursive subnode: node: node: node: config[offset_thumb].setValue((-1, 0)) config[fontface_title].setValue(/usr/share/fonts/nmsbd.ttf) config[next_page_text].setValue(»»») config[offset_headline].setValue((-1, 40)) config[titleformat].setValue($t) config[cols].setValue(2) config[fontface_headline].setValue(/usr/share/fonts/nmsbd.ttf) config[color_highlight].setValue([128, 0, 0]) config[rows].setValue(2) config[dimensions].setValue((720, 576)) config[space_rows].setValue(24) config[margin_right].setValue(48) config[subtitleformat].setValue($d) config[offset_title].setValue((-1, 144)) config[fontsize_subtitle].setValue(12) config[fontsize_title].setValue(18) config[thumb_size].setValue((180, 144)) config[menuaudio].setValue(/usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/silence.mp2) config[thumb_border].setValue(2) config[menubg].setValue(/usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/dreamdvd_clouds.jpg) config[fontsize_headline].setValue(46) config[space_cols].setValue(24) config[color_button].setValue([8, 0, 0]) config[prev_page_text].setValue(«««) config[margin_top].setValue(100) config[margin_left].setValue(48) config[color_headline].setValue([128, 0, 0]) config[margin_bottom].setValue(48) config[fontface_subtitle].setValue(/usr/share/fonts/nmsbd.ttf) config[offset_subtitle].setValue((-1, 164)) node: updateSize: 0 MAX_DL: 8050 MAX_SL: 4380 Looking for embedded skin allocating new converter! no ancillary data in audio stream... abort radiotext pes parser action -> DVDTitleList addTitle Looking for embedded skin allocating new converter! allocating new converter! allocating new converter! allocating new converter! allocating new converter! warning, skin is missing element key_blue in [EPGC] nownext finished(1293290245) action -> ColorActions green not pauseable. playing 1:0:0:0:0:0:0:0:0:0:/hdd/movie/20101225 0720 - Discov - Mythbusters.ts [eDVBCAService] free slot 0 demux 0 for service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: [eDVBCAService] free service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: decoder state: play, vpid=-1, apid=-1 DEMUX_STOP - pcr - ok DEMUX_STOP - video - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - audio - ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer alloc PVR [eDVBCAService] remove channel 0x108e3630 [eDVBLocalTimerHandler] remove channel 0x108e3630 [eEPGCache] remove channel 0x108e3630 [EPGC] abort caching events !! loading streaminfo for /hdd/movie/20101225 0720 - Discov - Mythbusters.ts loading cuts.. 3 entries before: 1 after: 1 setIoPrio best-effort level 0 ok FILEPUSH THREAD START allocate demux no version filtering 0012: 4e 00 00 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (0104), and 1 audio stream(s) (0105), and the pcr pid is 0104, and the text pid is 0106 disable teletext subtitles decoder state: play, vpid=260, apid=261 DMX_SET_PES_FILTER(0x105) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - getNextSourceSpan, current offset is 00000000, m_skipmode_m = 0! NO CUESHEET. (00000000, 10485700) ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux4 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x104) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x106) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok eDVBServicePlay::seekTo: jump 0 seek. stopping thread. FILEPUSH THREAD STOP thread joined 0 AUDIO_CLEAR_BUFFER - ok VIDEO_CLEAR_BUFFER - ok before: 1 after: 1 setIoPrio best-effort level 0 ok FILEPUSH THREAD START getNextSourceSpan, current offset is 0001ff20, m_skipmode_m = 0! getOffset for pts 0x0 ok, resolved skip (rel: 0, diff 0), now at 00000000 NO CUESHEET. (00000000, 10485700) new service started! trying to download cuts! cut lists disabled! cutlists were disabled Looking for embedded skin allocating new converter! eDVBTSTools::fixupPTS failed! fixup PTS failed set seek position: 0 set instance. + 1/1 TID 00 done! no version filtering 010d: 02 18 d8 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 03 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len ERROR reading PES (fd=39) - Value too large for defined data type data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len data_unit_length > len + 1/1 TID 02 done! eventNewProgramInfo 0 0 have 1 video stream(s) (0104), and 1 audio stream(s) (0105), and the pcr pid is 0104, and the text pid is 0106 decoder state: play, vpid=260, apid=261 [audiotrack] pid: 261 description: MP2 language: dan doing version filtering 010d: 02 18 d8 3d 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 playing 1:0:1:33E:442:FE00:FFFF0000:0:0:0: stopping thread. FILEPUSH THREAD STOP thread joined 0 decoder state: play, vpid=-1, apid=-1 DEMUX_STOP - video - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - audio - ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok not pauseable. RemovePopup, id = ZapError allocate channel.. 0442:fe00 [eDVBCAService] new channel 0x108e3630! (2)tune tuning to 530000 khz, sr 6875000, fec 9, modulation 3, inversion 1 OURSTATE: tuning allocate Channel: res 0 [eDVBCIInterfaces] addPMTHandler 1:0:1:33E:442:FE00:FFFF0000:0:0:0: allocate demux updateSize: 2131 MAX_DL: 8050 MAX_SL: 4380 [SEC] startTuneTimeout 5000 [SEC] setFrontend 1 setting frontend 2 (2)fe event: status 0, inversion on, m_tuning 1 (2)fe event: status 1f, inversion on, m_tuning 2 OURSTATE: ok [eDVBCAService] channel 0x108e3630 running [eDVBLocalTimerHandler] channel 0x108e3630 running no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [eEPGCache] channel 0x108e3630 running stop release channel timer no version filtering 0012: 4e 03 3e 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 ok ... now we start!! no version filtering 0000: 00 00 00 00 00 00 mask: ff 00 00 00 00 00 mode: 00 00 00 00 00 00 eventNewProgramInfo 0 0 have 1 video stream(s) (033e), and 1 audio stream(s) (033f), and the pcr pid is 033e, and the text pid is 0340 allocate demux disable teletext subtitles decoder state: play, vpid=830, apid=831 DMX_SET_PES_FILTER(0x33e) - pcr - ok DEMUX_START - pcr - ok DMX_SET_PES_FILTER(0x33f) - audio - ok DEMUX_START - audio - ok AUDIO_SET_BYPASS(1) - ok AUDIO_PAUSE - ok AUDIO_PLAY - [EPGC] next update in 2 sec ok Video Device: /dev/dvb/adapter0/video0 demux device: /dev/dvb/adapter0/demux0 VIDEO_SET_STREAMTYPE 0 - ok DMX_SET_PES_FILTER(0x33e) - video - ok DEMUX_START - video - ok VIDEO_FREEZE - ok VIDEO_PLAY - ok DMX_SET_PES_FILTER(0x340) - ttx - ok DEMUX_START - ttx - ok VIDEO_SLOWMOTION(0) - ok VIDEO_FAST_FORWARD(0) - ok VIDEO_CONTINUE - ok AUDIO_CONTINUE - ok not pauseable. + 1/1 TID 00 done! no version filtering 0347: 02 03 3e 00 00 00 mask: ff ff ff 00 00 00 mode: 00 00 00 00 00 00 doing version filtering 0000: 00 00 00 17 00 00 mask: ff 00 00 3f 00 00 mode: 00 00 00 3e 00 00 + 1/1 TID 02 done! eventNewProgramInfo 0 0 have 1 video stream(s) (033e), and 1 audio stream(s) (033f), and the pcr pid is 033e, and the text pid is 0340 decoder state: play, vpid=830, apid=831 [eDVBCAService] new service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: [eDVBCAService] add demux 0 to slot 0 service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: [eDVBCIInterfaces] gotPMT demux 0 mask 01 prevhash 00000000 [eDVBCAService] send 44 bytes 9f 80 32 28 03 03 3e 19 00 13 01 81 08 ff ff 00 00 04 42 fe 00 82 02 01 00 84 02 03 47 02 03 3e 00 00 03 03 3f 00 00 06 03 40 00 00 doing version filtering 0347: 02 03 3e 19 00 00 mask: ff ff ff 3f 00 00 mode: 00 00 00 3e 00 00 sdt update done! [EPGC] start caching events(1293290249) -+ 1/2 TID 4e [eDVBLocalTimerHandler] diff is 0 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] not changed timeout 0012! action -> DVDTitleList burnProject [EPGC] abort non avail viasat reading action -> MsgBoxActions ok allocating new converter! allocating new converter! reused converter! allocating new converter! reused converter! allocating new converter! reused converter! allocating new converter! main thread is non-idle! display spinner! execute: 0 png2yuv -n1 -Ip -f25 -j /hdd/movie/tmp/20101225161739/dvd_menubg1.png eConsoleAppContainer::dumpToFile open(/hdd/movie/tmp/20101225161739/dvdmenubg1.yuv, O_WRONLY|O_CREAT|O_TRUNC, 0644)=54 ERROR reading PES (fd=39) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 39(8) ERROR reading PES (fd=43) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 43(8) [png2yuvTask] INFO: [png2yuv] Parsing & checking input files. [png2yuvTask] INFO: [png2yuv] Image dimensions are 720x576 [png2yuvTask] INFO: [png2yuv] Movie frame rate is: 25.000000 frames/second [png2yuvTask] INFO: [png2yuv] Non-interlaced/progressive frames. [png2yuvTask] INFO: [png2yuv] Frame size: 720 x 576 [png2yuvTask] INFO: [png2yuv] Now generating YUV4MPEG stream. child has terminated pipes closed execute: 0 mpeg2enc -f8 -np -a2 -o /hdd/movie/tmp/20101225161739/dvdmenubg1.mv2 eConsoleAppContainer::readFromFile open(/hdd/movie/tmp/20101225161739/dvdmenubg1.yuv, O_RDONLY)=55, read: 32768 poll: unhandled POLLERR/HUP/NVAL for fd 57(16) [mpeg2encTask] INFO: [mpeg2enc] Selecting DVD with dummy navigation packets output profile [mpeg2encTask] INFO: [mpeg2enc] Progressive input - selecting progressive encoding. [mpeg2encTask] INFO: [mpeg2enc] Encoding MPEG-2 video to /hdd/movie/tmp/20101225161739/dvdmenubg1.mv2 [mpeg2encTask] INFO: [mpeg2enc] Horizontal size: 720 pel [mpeg2encTask] INFO: [mpeg2enc] Vertical size: 576 pel [mpeg2encTask] INFO: [mpeg2enc] Aspect ratio code: 2 = 4:3 display [mpeg2encTask] INFO: [mpeg2enc] Frame rate code: 3 = 25.0 (PAL/SECAM VIDEO / converted FILM) [mpeg2encTask] INFO: [mpeg2enc] Bitrate: 7500 KBit/s [mpeg2encTask] INFO: [mpeg2enc] Quality factor: 8 (Quantisation = 9) (1=best, 31=worst) [mpeg2encTask] INFO: [mpeg2enc] Field order for input: none/progressive [mpeg2encTask] INFO: [mpeg2enc] Sequence unlimited length [mpeg2encTask] INFO: [mpeg2enc] Search radius: 16 [mpeg2encTask] INFO: [mpeg2enc] DualPrime: no [mpeg2encTask] INFO: [mpeg2enc] Using one-pass rate controller [mpeg2encTask] INFO: [mpeg2enc] GOP SIZE RANGE 7 TO 15 [mpeg2encTask] INFO: [mpeg2enc] Setting colour/gamma parameters to "PAL B/G" [mpeg2encTask] INFO: [mpeg2enc] Progressive format frames = 1 [mpeg2encTask] INFO: [mpeg2enc] Using default unmodified quantization matrices [mpeg2encTask] INFO: [mpeg2enc] PAR = 0 [mpeg2encTask] [mpeg2encTask] INFO: [mpeg2enc] NEW GOP INIT length 15 readFromFile done - closing eConsoleAppContainer stdin pipe [mpeg2encTask] INFO: [mpeg2enc] Signaling last frame = 0 [mpeg2encTask] INFO: [mpeg2enc] Enc1 0 0( 0) I q=9.03 [100% Intra] [mpeg2encTask] INFO: [mpeg2enc] Sequence end reached [mpeg2encTask] INFO: [mpeg2enc] Pass2 0 0( 0) I q=9.03 RETAINED [mpeg2encTask] INFO: [mpeg2enc] Guesstimated final muxed size = 36690 [mpeg2encTask] child has terminated pipes closed execute: 0 mplex -f8 -o /hdd/movie/tmp/20101225161739/dvdmenubg1.mpg -v1 /hdd/movie/tmp/20101225161739/dvdmenubg1.mv2 /usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/silence.mp2 poll: unhandled POLLERR/HUP/NVAL for fd 58(16) [MplexTask] INFO: [mplex] mplex version 1.9.0 (2.2.7 $Date: 2006/02/01 22:23:01 $) [MplexTask] INFO: [mplex] File /hdd/movie/tmp/20101225161739/dvdmenubg1.mv2 looks like an MPEG Video stream. [MplexTask] INFO: [mplex] File /usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/silence.mp2 looks like an MPEG Audio stream. [MplexTask] INFO: [mplex] Video stream 0: profile 8 selected - ignoring non-standard options! [MplexTask] INFO: [mplex] Found 1 audio streams and 1 video streams [MplexTask] INFO: [mplex] Selecting dvdauthor DVD output profile [MplexTask] INFO: [mplex] Multiplexing video program stream! [MplexTask] INFO: [mplex] Scanning for header info: Video stream e0 (/hdd/movie/tmp/20101225161739/dvdmenubg1.mv2) [MplexTask] INFO: [mplex] VIDEO STREAM: e0 [MplexTask] INFO: [mplex] Frame width : 720 [MplexTask] INFO: [mplex] Frame height : 576 [MplexTask] INFO: [mplex] Aspect ratio : 4:3 display [MplexTask] INFO: [mplex] Picture rate : 25.000 frames/sec [MplexTask] INFO: [mplex] Bit rate : 7500000 bits/sec [MplexTask] INFO: [mplex] Vbv buffer size : 229376 bytes [MplexTask] INFO: [mplex] CSPF : 0 [MplexTask] INFO: [mplex] Scanning for header info: Audio stream c0 (/usr/lib/enigma2/python/Plugins/Extensions/DVDBurn/silence.mp2) [MplexTask] INFO: [mplex] MPEG AUDIO STREAM: c0 [MplexTask] INFO: [mplex] Audio version : 1.0 [MplexTask] INFO: [mplex] Layer : 2 [MplexTask] INFO: [mplex] CRC checksums : no [MplexTask] INFO: [mplex] Bit rate : 16384 bytes/sec (128 kbit/sec) [MplexTask] INFO: [mplex] Frequency : 44100 Hz [MplexTask] INFO: [mplex] Mode : 3 single channel [MplexTask] INFO: [mplex] Mode extension : 0 [MplexTask] INFO: [mplex] Copyright bit : 0 no copyright [MplexTask] INFO: [mplex] Original/Copy : 1 original [MplexTask] INFO: [mplex] Emphasis : 0 none [MplexTask] INFO: [mplex] SYSTEMS/PROGRAM stream: [MplexTask] INFO: [mplex] rough-guess multiplexed stream data rate : 7788744 [MplexTask] INFO: [mplex] target data-rate specified : 10080000 [MplexTask] INFO: [mplex] Setting specified specified data rate: 10080000 [MplexTask] INFO: [mplex] Scanned to end AU 0 [MplexTask] INFO: [mplex] Run-in delay = 7200 Video delay = 7200 Audio delay = 10800 [MplexTask] INFO: [mplex] New sequence commences... [MplexTask] INFO: [mplex] Video e0: buf= 0 frame=000000 sector=00000000 [MplexTask] INFO: [mplex] Audio c0: buf= 0 frame=000000 sector=00000000 [MplexTask] INFO: [mplex] STREAM e0 completed [MplexTask] INFO: [mplex] STREAM c0 completed [MplexTask] INFO: [mplex] Multiplex completion at SCR=43885. [MplexTask] INFO: [mplex] Video e0: completed [MplexTask] INFO: [mplex] Audio c0: completed [MplexTask] INFO: [mplex] VIDEO_STATISTICS: e0 [MplexTask] INFO: [mplex] Video Stream length: 36690 bytes [MplexTask] INFO: [mplex] Sequence headers: 1 [MplexTask] INFO: [mplex] Sequence ends : 1 [MplexTask] INFO: [mplex] No. Pictures : 1 [MplexTask] INFO: [mplex] No. Groups : 1 [MplexTask] INFO: [mplex] No. I Frames : 1 avg. size 36690 bytes [MplexTask] INFO: [mplex] No. P Frames : 0 avg. size 0 bytes [MplexTask] INFO: [mplex] No. B Frames : 0 avg. size 0 bytes [MplexTask] INFO: [mplex] Average bit-rate : 7338000 bits/sec [MplexTask] INFO: [mplex] Peak bit-rate : 0 bits/sec [MplexTask] INFO: [mplex] BUFFERING stream too short for useful statistics [MplexTask] INFO: [mplex] AUDIO_STATISTICS: c0 [MplexTask] INFO: [mplex] Audio stream length 8340 bytes. [MplexTask] INFO: [mplex] Syncwords : 20 [MplexTask] INFO: [mplex] Frames : 20 padded [MplexTask] INFO: [mplex] Frames : 0 unpadded [MplexTask] INFO: [mplex] BUFFERING stream too short for useful statistics [MplexTask] INFO: [mplex] MUX STATUS: no under-runs detected. child has terminated pipes closed execute: 0 spumux /hdd/movie/tmp/20101225161739/spumux1.xml eConsoleAppContainer::dumpToFile open(/hdd/movie/tmp/20101225161739/dvdmenu1.mpg, O_WRONLY|O_CREAT|O_TRUNC, 0644)=55 eConsoleAppContainer::readFromFile open(/hdd/movie/tmp/20101225161739/dvdmenubg1.mpg, O_RDONLY)=56, read: 32768 poll: unhandled POLLERR/HUP/NVAL for fd 58(16) readFromFile done - closing eConsoleAppContainer stdin pipe [spumuxTask] DVDAuthor::spumux, version 0.6.14. Build options: gnugetopt iconv freetype fribidi Send bugs to [spumuxTask] INFO: Locale=C [spumuxTask] INFO: Converting filenames to ANSI_X3.4-1968 [spumuxTask] STAT: [spumuxTask] 0:00:00.000 [spumuxTask] INFO: PNG had 2 colors [spumuxTask] INFO: Constructing blank img [spumuxTask] INFO: Constructing blank sel [spumuxTask] INFO: Pickbuttongroups, success with 1 groups, useimg=1 [spumuxTask] INFO: Found EOF in .sub file. [spumuxTask] INFO: Max_sub_size=888 [spumuxTask] INFO: 1 subtitles added, 0 subtitles skipped, stream: 32, offset: 0.12 [spumuxTask] Statistics: [spumuxTask] - Processed 0 subtitles. [spumuxTask] - The longest display line had -1 characters. [spumuxTask] - The maximum number of displayed lines was 0. [spumuxTask] - The normal display height of the font arial.ttf was 0. [spumuxTask] - The bottom display height of the font arial.ttf was 0. [spumuxTask] - The biggest subtitle box had 888 bytes. child has terminated pipes closed execute: 0 mknod /hdd/movie/tmp/20101225161739/dvd_title_1.mpg p poll: unhandled POLLERR/HUP/NVAL for fd 58(16) child has terminated pipes closed execute: 0 dvdauthor -x /hdd/movie/tmp/20101225161739/dvdauthor.xml poll: unhandled POLLERR/HUP/NVAL for fd 59(16) [DVDAuthorTask] DVDAuthor::dvdauthor, version 0.6.14. [DVDAuthorTask] Build options: gnugetopt iconv freetype fribidi [DVDAuthorTask] Send bugs to [DVDAuthorTask] [DVDAuthorTask] INFO: Locale=C [DVDAuthorTask] INFO: Converting filenames to ANSI_X3.4-1968 [DVDAuthorTask] INFO: dvdauthor creating VTS [DVDAuthorTask] WARN: First cell is not marked as a chapter in PGC 0, setting chapter flag [DVDAuthorTask] STAT: Picking VTS 01 [DVDAuthorTask] [DVDAuthorTask] STAT: Processing /hdd/movie/tmp/20101225161739/dvd_title_1.mpg... task going resident: execute: 0 ln -s /hdd/movie/20101225 0720 - Discov - Mythbusters.ts /hdd/movie/tmp/20101225161739/source_title_1.ts child has terminated pipes closed execute: 0 projectx /hdd/movie/tmp/20101225161739/source_title_1.ts -demux -out /hdd/movie/tmp/20101225161739 poll: unhandled POLLERR/HUP/NVAL for fd 62(16) no ancillary data in audio stream... abort radiotext pes parser nr_read 58977 nr_write 19644 sum 78621 prev_sum 0 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] nownext finished(1293290273) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 234927 bytes for cache used It's now Sat Dec 25 16:18:37 2010 [timer.py] next activation: 1293290417 (in 99971 ms) It's now Sat Dec 25 16:18:37 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290417 (in 99966 ms) nr_read 59641 nr_write 19790 sum 79431 prev_sum 78621 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 374662 bytes for cache used [EPGC] schedule other finished(1293290346) [EPGC] schedule finished(1293290347) [EPGC] stop caching events(1293290347) [EPGC] next update in 60 min nr_read 60316 nr_write 19947 sum 80263 prev_sum 79431 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 379208 bytes for cache used It's now Sat Dec 25 16:20:17 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290517 (in 99996 ms) It's now Sat Dec 25 16:20:17 2010 [timer.py] next activation: 1293290517 (in 99993 ms) nr_read 60996 nr_write 20129 sum 81125 prev_sum 80263 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 379208 bytes for cache used nr_read 61683 nr_write 20289 sum 81972 prev_sum 81125 hdd was accessed since previous check! [IDLE] 0 600 False It's now Sat Dec 25 16:21:57 2010 [timer.py] next activation: 1293290617 (in 99997 ms) It's now Sat Dec 25 16:21:57 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290617 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 379208 bytes for cache used nr_read 62372 nr_write 20464 sum 82836 prev_sum 81972 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 379208 bytes for cache used It's now Sat Dec 25 16:23:37 2010 [timer.py] next activation: 1293290717 (in 99980 ms) It's now Sat Dec 25 16:23:37 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290717 (in 99977 ms) nr_read 63047 nr_write 20613 sum 83660 prev_sum 82836 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 379208 bytes for cache used nr_read 63724 nr_write 20796 sum 84520 prev_sum 83660 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 377539 bytes for cache used It's now Sat Dec 25 16:25:17 2010 [timer.py] next activation: 1293290817 (in 99997 ms) It's now Sat Dec 25 16:25:17 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290817 (in 99991 ms) nr_read 64408 nr_write 20990 sum 85398 prev_sum 84520 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 377539 bytes for cache used nr_read 65089 nr_write 21171 sum 86260 prev_sum 85398 hdd was accessed since previous check! [IDLE] 0 600 False It's now Sat Dec 25 16:26:57 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293290917 (in 99994 ms) It's now Sat Dec 25 16:26:57 2010 [timer.py] next activation: 1293290917 (in 99991 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 377539 bytes for cache used nr_read 65788 nr_write 21347 sum 87135 prev_sum 86260 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 377539 bytes for cache used It's now Sat Dec 25 16:28:37 2010 [timer.py] next activation: 1293291017 (in 99960 ms) It's now Sat Dec 25 16:28:37 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291017 (in 99956 ms) nr_read 66478 nr_write 21532 sum 88010 prev_sum 87135 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 377539 bytes for cache used nr_read 67159 nr_write 21706 sum 88865 prev_sum 88010 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 375808 bytes for cache used It's now Sat Dec 25 16:30:17 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291117 (in 99996 ms) It's now Sat Dec 25 16:30:17 2010 [timer.py] next activation: 1293291117 (in 99992 ms) nr_read 67825 nr_write 21841 sum 89666 prev_sum 88865 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 375808 bytes for cache used nr_read 68531 nr_write 22012 sum 90543 prev_sum 89666 hdd was accessed since previous check! [IDLE] 0 600 False It's now Sat Dec 25 16:31:57 2010 [timer.py] next activation: 1293291217 (in 99997 ms) It's now Sat Dec 25 16:31:57 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291217 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 375808 bytes for cache used nr_read 69226 nr_write 22181 sum 91407 prev_sum 90543 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 375808 bytes for cache used It's now Sat Dec 25 16:33:37 2010 [timer.py] next activation: 1293291317 (in 99990 ms) It's now Sat Dec 25 16:33:37 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291317 (in 99985 ms) nr_read 69891 nr_write 22337 sum 92228 prev_sum 91407 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 375808 bytes for cache used nr_read 70560 nr_write 22501 sum 93061 prev_sum 92228 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 374260 bytes for cache used It's now Sat Dec 25 16:35:17 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291417 (in 99995 ms) It's now Sat Dec 25 16:35:17 2010 [timer.py] next activation: 1293291417 (in 99993 ms) nr_read 71242 nr_write 22663 sum 93905 prev_sum 93061 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 374260 bytes for cache used nr_read 71923 nr_write 22825 sum 94748 prev_sum 93905 hdd was accessed since previous check! [IDLE] 0 600 False It's now Sat Dec 25 16:36:57 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291517 (in 99996 ms) It's now Sat Dec 25 16:36:57 2010 [timer.py] next activation: 1293291517 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 374260 bytes for cache used nr_read 72588 nr_write 22983 sum 95571 prev_sum 94748 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 374260 bytes for cache used It's now Sat Dec 25 16:38:37 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291617 (in 99964 ms) It's now Sat Dec 25 16:38:37 2010 [timer.py] next activation: 1293291617 (in 99953 ms) nr_read 73251 nr_write 23133 sum 96384 prev_sum 95571 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 374260 bytes for cache used nr_read 73952 nr_write 23306 sum 97258 prev_sum 96384 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 373271 bytes for cache used It's now Sat Dec 25 16:40:17 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291717 (in 99996 ms) It's now Sat Dec 25 16:40:17 2010 [timer.py] next activation: 1293291717 (in 99993 ms) nr_read 74647 nr_write 23474 sum 98121 prev_sum 97258 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 373271 bytes for cache used nr_read 75331 nr_write 23609 sum 98940 prev_sum 98121 hdd was accessed since previous check! [IDLE] 0 600 False It's now Sat Dec 25 16:41:57 2010 [timer.py] next activation: 1293291817 (in 99998 ms) It's now Sat Dec 25 16:41:57 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291817 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 373271 bytes for cache used nr_read 76021 nr_write 23777 sum 99798 prev_sum 98940 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 373271 bytes for cache used It's now Sat Dec 25 16:43:37 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293291917 (in 99956 ms) It's now Sat Dec 25 16:43:37 2010 [timer.py] next activation: 1293291917 (in 99953 ms) nr_read 76682 nr_write 23940 sum 100622 prev_sum 99798 hdd was accessed since previous check! [IDLE] 0 600 False [DemuxTask] produced file: /hdd/movie/tmp/20101225161739/source_title_1.m2v None [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 373271 bytes for cache used nr_read 77412 nr_write 24175 sum 101587 prev_sum 100622 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 372694 bytes for cache used It's now Sat Dec 25 16:45:17 2010 [timer.py] next activation: 1293292017 (in 99997 ms) It's now Sat Dec 25 16:45:17 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292017 (in 99994 ms) nr_read 78085 nr_write 24354 sum 102439 prev_sum 101587 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 372694 bytes for cache used [DemuxTask] produced file: /hdd/movie/tmp/20101225161739/source_title_1.mp2 261 child has terminated pipes closed execute: 0 mplex -f8 -o /hdd/movie/tmp/20101225161739/dvd_title_1.mpg -v1 /hdd/movie/tmp/20101225161739/source_title_1.m2v /hdd/movie/tmp/20101225161739/source_title_1.mp2 poll: unhandled POLLERR/HUP/NVAL for fd 62(16) [MplexTask] INFO: [mplex] mplex version 1.9.0 (2.2.7 $Date: 2006/02/01 22:23:01 $) [MplexTask] INFO: [mplex] File /hdd/movie/tmp/20101225161739/source_title_1.m2v looks like an MPEG Video stream. [MplexTask] INFO: [mplex] File /hdd/movie/tmp/20101225161739/source_title_1.mp2 looks like an MPEG Audio stream. [MplexTask] INFO: [mplex] Video stream 0: profile 8 selected - ignoring non-standard options! [MplexTask] INFO: [mplex] Found 1 audio streams and 1 video streams [MplexTask] INFO: [mplex] Selecting dvdauthor DVD output profile [MplexTask] INFO: [mplex] Multiplexing video program stream! [MplexTask] INFO: [mplex] Scanning for header info: Video stream e0 (/hdd/movie/tmp/20101225161739/source_title_1.m2v) [MplexTask] INFO: [mplex] VIDEO STREAM: e0 [MplexTask] INFO: [mplex] Frame width : 720 [MplexTask] INFO: [mplex] Frame height : 576 [MplexTask] INFO: [mplex] Aspect ratio : 16:9 display [MplexTask] INFO: [mplex] Picture rate : 25.000 frames/sec [MplexTask] INFO: [mplex] Bit rate : 6378000 bits/sec [MplexTask] INFO: [mplex] Vbv buffer size : 229376 bytes [MplexTask] INFO: [mplex] CSPF : 0 [MplexTask] INFO: [mplex] Scanning for header info: Audio stream c0 (/hdd/movie/tmp/20101225161739/source_title_1.mp2) [MplexTask] INFO: [mplex] MPEG AUDIO STREAM: c0 [MplexTask] INFO: [mplex] Audio version : 1.0 [MplexTask] INFO: [mplex] Layer : 2 [MplexTask] INFO: [mplex] CRC checksums : no [MplexTask] INFO: [mplex] Bit rate : 32768 bytes/sec (256 kbit/sec) [MplexTask] INFO: [mplex] Frequency : 48000 Hz [MplexTask] INFO: [mplex] Mode : 0 stereo [MplexTask] INFO: [mplex] Mode extension : 0 [MplexTask] INFO: [mplex] Copyright bit : 0 no copyright [MplexTask] INFO: [mplex] Original/Copy : 0 copy [MplexTask] INFO: [mplex] Emphasis : 0 none [MplexTask] INFO: [mplex] SYSTEMS/PROGRAM stream: [MplexTask] INFO: [mplex] rough-guess multiplexed stream data rate : 6777496 [MplexTask] INFO: [mplex] target data-rate specified : 10080000 [MplexTask] INFO: [mplex] Setting specified specified data rate: 10080000 [MplexTask] INFO: [mplex] Run-in delay = 7200 Video delay = 7200 Audio delay = 10800 [MplexTask] INFO: [mplex] New sequence commences... [MplexTask] INFO: [mplex] Video e0: buf= 0 frame=000000 sector=00000000 [MplexTask] INFO: [mplex] Audio c0: buf= 0 frame=000000 sector=00000000 [DVDAuthorTask] STAT: VOBU 16 at 3MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 3 of 2131 [DVDAuthorTask] STAT: VOBU 32 at 6MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 6 of 2131 [DVDAuthorTask] STAT: VOBU 48 at 8MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 8 of 2131 [DVDAuthorTask] STAT: VOBU 64 at 10MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 10 of 2131 [DVDAuthorTask] STAT: VOBU 80 at 13MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 13 of 2131 [DVDAuthorTask] STAT: VOBU 96 at 16MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 16 of 2131 [DVDAuthorTask] STAT: VOBU 112 at 18MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 18 of 2131 [DVDAuthorTask] STAT: VOBU 128 at 21MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 21 of 2131 [DVDAuthorTask] STAT: VOBU 144 at 23MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 23 of 2131 [DVDAuthorTask] STAT: VOBU 160 at 26MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 26 of 2131 [DVDAuthorTask] STAT: VOBU 176 at 29MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 29 of 2131 [DVDAuthorTask] STAT: VOBU 192 at 32MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 32 of 2131 [DVDAuthorTask] STAT: VOBU 208 at 35MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 35 of 2131 [DVDAuthorTask] STAT: VOBU 224 at 38MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 38 of 2131 [DVDAuthorTask] STAT: VOBU 240 at 40MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 40 of 2131 [DVDAuthorTask] STAT: VOBU 256 at 43MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 43 of 2131 [DVDAuthorTask] STAT: VOBU 272 at 45MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 45 of 2131 [DVDAuthorTask] STAT: VOBU 288 at 48MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 48 of 2131 [DVDAuthorTask] STAT: VOBU 304 at 51MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 51 of 2131 [DVDAuthorTask] STAT: VOBU 320 at 54MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 54 of 2131 [DVDAuthorTask] STAT: VOBU 336 at 57MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 57 of 2131 [DVDAuthorTask] STAT: VOBU 352 at 59MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 59 of 2131 [DVDAuthorTask] STAT: VOBU 368 at 61MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 61 of 2131 [DVDAuthorTask] STAT: VOBU 384 at 65MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 65 of 2131 [DVDAuthorTask] STAT: VOBU 400 at 67MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 67 of 2131 [DVDAuthorTask] STAT: VOBU 416 at 70MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 70 of 2131 [DVDAuthorTask] STAT: VOBU 432 at 74MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 74 of 2131 [DVDAuthorTask] STAT: VOBU 448 at 77MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 77 of 2131 [DVDAuthorTask] STAT: VOBU 464 at 80MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 80 of 2131 [DVDAuthorTask] STAT: VOBU 480 at 83MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 83 of 2131 [DVDAuthorTask] STAT: VOBU 496 at 86MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 86 of 2131 nr_read 79032 nr_write 24613 sum 103645 prev_sum 102439 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 512 at 89MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 89 of 2131 [DVDAuthorTask] STAT: VOBU 528 at 92MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 92 of 2131 [DVDAuthorTask] STAT: VOBU 544 at 96MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 96 of 2131 It's now Sat Dec 25 16:46:57 2010 [timer.py] next activation: 1293292117 (in 99997 ms) It's now Sat Dec 25 16:46:57 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292117 (in 99994 ms) [DVDAuthorTask] STAT: VOBU 560 at 99MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 99 of 2131 [DVDAuthorTask] STAT: VOBU 576 at 102MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 102 of 2131 [DVDAuthorTask] STAT: VOBU 592 at 107MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 107 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 372694 bytes for cache used [DVDAuthorTask] STAT: VOBU 608 at 110MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 110 of 2131 [DVDAuthorTask] STAT: VOBU 624 at 113MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 113 of 2131 [DVDAuthorTask] STAT: VOBU 640 at 116MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 116 of 2131 [DVDAuthorTask] STAT: VOBU 656 at 119MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 119 of 2131 [DVDAuthorTask] STAT: VOBU 672 at 122MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 122 of 2131 [DVDAuthorTask] STAT: VOBU 688 at 125MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 125 of 2131 [DVDAuthorTask] STAT: VOBU 704 at 129MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 129 of 2131 [DVDAuthorTask] STAT: VOBU 720 at 132MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 132 of 2131 [DVDAuthorTask] STAT: VOBU 736 at 134MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 134 of 2131 [DVDAuthorTask] STAT: VOBU 752 at 137MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 137 of 2131 [DVDAuthorTask] STAT: VOBU 768 at 139MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 139 of 2131 [DVDAuthorTask] STAT: VOBU 784 at 142MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 142 of 2131 [DVDAuthorTask] STAT: VOBU 800 at 145MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 145 of 2131 [DVDAuthorTask] STAT: VOBU 816 at 148MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 148 of 2131 [DVDAuthorTask] STAT: VOBU 832 at 150MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 150 of 2131 [DVDAuthorTask] STAT: VOBU 848 at 152MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 152 of 2131 [DVDAuthorTask] STAT: VOBU 864 at 155MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 155 of 2131 [DVDAuthorTask] STAT: VOBU 880 at 158MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 158 of 2131 [DVDAuthorTask] STAT: VOBU 896 at 160MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 160 of 2131 [DVDAuthorTask] STAT: VOBU 912 at 162MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 162 of 2131 [DVDAuthorTask] STAT: VOBU 928 at 165MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 165 of 2131 no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [DVDAuthorTask] STAT: VOBU 944 at 167MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 167 of 2131 [DVDAuthorTask] STAT: VOBU 960 at 171MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 171 of 2131 [eDVBLocalTimerHandler] diff is 1 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] difference between linux time and RTC time is < 60 sec... so the transponder time looks ok [eDVBLocalTimerHandler] time update to 16:47:36 [eDVBLocalTimerHandler] m_time_difference is 1 [eDVBLocalTimerHandler] set Linux Time [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 372694 bytes for cache used [DVDAuthorTask] STAT: VOBU 976 at 174MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 174 of 2131 [DVDAuthorTask] STAT: VOBU 992 at 177MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 177 of 2131 [DVDAuthorTask] STAT: VOBU 1008 at 180MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 180 of 2131 [DVDAuthorTask] STAT: VOBU 1024 at 182MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 182 of 2131 [DVDAuthorTask] STAT: VOBU 1040 at 185MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 185 of 2131 [DVDAuthorTask] STAT: VOBU 1056 at 189MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 189 of 2131 [DVDAuthorTask] STAT: VOBU 1072 at 192MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 192 of 2131 [DVDAuthorTask] STAT: VOBU 1088 at 194MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 194 of 2131 [DVDAuthorTask] STAT: VOBU 1104 at 197MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 197 of 2131 [DVDAuthorTask] STAT: VOBU 1120 at 201MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 201 of 2131 [DVDAuthorTask] STAT: VOBU 1136 at 203MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 203 of 2131 nr_read 79992 nr_write 24897 sum 104889 prev_sum 103645 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 1152 at 206MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 206 of 2131 [DVDAuthorTask] STAT: VOBU 1168 at 209MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 209 of 2131 [DVDAuthorTask] STAT: VOBU 1184 at 213MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 213 of 2131 [DVDAuthorTask] STAT: VOBU 1200 at 216MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 216 of 2131 [DVDAuthorTask] STAT: VOBU 1216 at 218MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 218 of 2131 [DVDAuthorTask] STAT: VOBU 1232 at 221MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 221 of 2131 [DVDAuthorTask] STAT: VOBU 1248 at 223MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 223 of 2131 [DVDAuthorTask] STAT: VOBU 1264 at 226MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 226 of 2131 [DVDAuthorTask] STAT: VOBU 1280 at 230MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 230 of 2131 [DVDAuthorTask] STAT: VOBU 1296 at 232MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 232 of 2131 [DVDAuthorTask] STAT: VOBU 1312 at 234MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 234 of 2131 [DVDAuthorTask] STAT: VOBU 1328 at 237MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 237 of 2131 [DVDAuthorTask] STAT: VOBU 1344 at 239MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 239 of 2131 [DVDAuthorTask] STAT: VOBU 1360 at 241MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 241 of 2131 [DVDAuthorTask] STAT: VOBU 1376 at 244MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 244 of 2131 [DVDAuthorTask] STAT: VOBU 1392 at 246MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 246 of 2131 [DVDAuthorTask] STAT: VOBU 1408 at 248MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 248 of 2131 [DVDAuthorTask] STAT: VOBU 1424 at 251MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 251 of 2131 [DVDAuthorTask] STAT: VOBU 1440 at 254MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 254 of 2131 [DVDAuthorTask] STAT: VOBU 1456 at 257MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 257 of 2131 [DVDAuthorTask] STAT: VOBU 1472 at 259MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 259 of 2131 [DVDAuthorTask] STAT: VOBU 1488 at 260MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 260 of 2131 [DVDAuthorTask] STAT: VOBU 1504 at 262MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 262 of 2131 [DVDAuthorTask] STAT: VOBU 1520 at 265MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 265 of 2131 [DVDAuthorTask] STAT: VOBU 1536 at 268MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 268 of 2131 [DVDAuthorTask] STAT: VOBU 1552 at 271MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 271 of 2131 [DVDAuthorTask] STAT: VOBU 1568 at 273MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 273 of 2131 [DVDAuthorTask] STAT: VOBU 1584 at 277MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 277 of 2131 [DVDAuthorTask] STAT: VOBU 1600 at 281MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 281 of 2131 [DVDAuthorTask] STAT: VOBU 1616 at 285MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 285 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 372676 bytes for cache used It's now Sat Dec 25 16:48:38 2010 [timer.py] next activation: 1293292218 (in 99988 ms) It's now Sat Dec 25 16:48:38 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292218 (in 99983 ms) [DVDAuthorTask] STAT: VOBU 1632 at 289MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 289 of 2131 [DVDAuthorTask] STAT: VOBU 1648 at 292MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 292 of 2131 [DVDAuthorTask] STAT: VOBU 1664 at 295MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 295 of 2131 [DVDAuthorTask] STAT: VOBU 1680 at 298MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 298 of 2131 [DVDAuthorTask] STAT: VOBU 1696 at 301MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 301 of 2131 [DVDAuthorTask] STAT: VOBU 1712 at 303MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 303 of 2131 [DVDAuthorTask] STAT: VOBU 1728 at 307MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 307 of 2131 [DVDAuthorTask] STAT: VOBU 1744 at 310MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 310 of 2131 [DVDAuthorTask] STAT: VOBU 1760 at 313MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 313 of 2131 [DVDAuthorTask] STAT: VOBU 1776 at 316MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 316 of 2131 [DVDAuthorTask] STAT: VOBU 1792 at 318MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 318 of 2131 nr_read 80931 nr_write 25091 sum 106022 prev_sum 104889 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 1808 at 321MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 321 of 2131 [DVDAuthorTask] STAT: VOBU 1824 at 324MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 324 of 2131 [DVDAuthorTask] STAT: VOBU 1840 at 326MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 326 of 2131 [DVDAuthorTask] STAT: VOBU 1856 at 329MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 329 of 2131 [DVDAuthorTask] STAT: VOBU 1872 at 332MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 332 of 2131 [DVDAuthorTask] STAT: VOBU 1888 at 335MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 335 of 2131 [DVDAuthorTask] STAT: VOBU 1904 at 337MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 337 of 2131 [DVDAuthorTask] STAT: VOBU 1920 at 340MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 340 of 2131 [DVDAuthorTask] STAT: VOBU 1936 at 342MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 342 of 2131 [DVDAuthorTask] STAT: VOBU 1952 at 345MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 345 of 2131 [DVDAuthorTask] STAT: VOBU 1968 at 347MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 347 of 2131 [DVDAuthorTask] STAT: VOBU 1984 at 349MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 349 of 2131 [DVDAuthorTask] STAT: VOBU 2000 at 351MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 351 of 2131 [DVDAuthorTask] STAT: VOBU 2016 at 354MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 354 of 2131 [DVDAuthorTask] STAT: VOBU 2032 at 356MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 356 of 2131 [DVDAuthorTask] STAT: VOBU 2048 at 358MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 358 of 2131 [DVDAuthorTask] STAT: VOBU 2064 at 360MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 360 of 2131 [DVDAuthorTask] STAT: VOBU 2080 at 363MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 363 of 2131 [DVDAuthorTask] STAT: VOBU 2096 at 366MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 366 of 2131 [DVDAuthorTask] STAT: VOBU 2112 at 369MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 369 of 2131 [DVDAuthorTask] STAT: VOBU 2128 at 372MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 372 of 2131 [DVDAuthorTask] STAT: VOBU 2144 at 375MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 375 of 2131 [DVDAuthorTask] STAT: VOBU 2160 at 378MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 378 of 2131 [DVDAuthorTask] STAT: VOBU 2176 at 381MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 381 of 2131 [DVDAuthorTask] STAT: VOBU 2192 at 384MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 384 of 2131 [DVDAuthorTask] STAT: VOBU 2208 at 387MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 387 of 2131 [DVDAuthorTask] STAT: VOBU 2224 at 390MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 390 of 2131 [DVDAuthorTask] STAT: VOBU 2240 at 393MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 393 of 2131 [DVDAuthorTask] STAT: VOBU 2256 at 396MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 396 of 2131 [DVDAuthorTask] STAT: VOBU 2272 at 399MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 399 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 372676 bytes for cache used [DVDAuthorTask] STAT: VOBU 2288 at 402MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 402 of 2131 [DVDAuthorTask] STAT: VOBU 2304 at 405MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 405 of 2131 [DVDAuthorTask] STAT: VOBU 2320 at 407MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 407 of 2131 [DVDAuthorTask] STAT: VOBU 2336 at 410MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 410 of 2131 [DVDAuthorTask] STAT: VOBU 2352 at 413MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 413 of 2131 [DVDAuthorTask] STAT: VOBU 2368 at 416MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 416 of 2131 [DVDAuthorTask] STAT: VOBU 2384 at 419MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 419 of 2131 [DVDAuthorTask] STAT: VOBU 2400 at 422MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 422 of 2131 [DVDAuthorTask] STAT: VOBU 2416 at 426MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 426 of 2131 [DVDAuthorTask] STAT: VOBU 2432 at 429MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 429 of 2131 nr_read 81854 nr_write 25274 sum 107128 prev_sum 106022 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 2448 at 432MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 432 of 2131 [DVDAuthorTask] STAT: VOBU 2464 at 435MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 435 of 2131 [DVDAuthorTask] STAT: VOBU 2480 at 437MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 437 of 2131 [DVDAuthorTask] STAT: VOBU 2496 at 440MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 440 of 2131 [DVDAuthorTask] STAT: VOBU 2512 at 442MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 442 of 2131 [DVDAuthorTask] STAT: VOBU 2528 at 444MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 444 of 2131 [DVDAuthorTask] STAT: VOBU 2544 at 447MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 447 of 2131 [DVDAuthorTask] STAT: VOBU 2560 at 450MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 450 of 2131 [DVDAuthorTask] STAT: VOBU 2576 at 453MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 453 of 2131 [DVDAuthorTask] STAT: VOBU 2592 at 457MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 457 of 2131 [DVDAuthorTask] STAT: VOBU 2608 at 460MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 460 of 2131 [DVDAuthorTask] STAT: VOBU 2624 at 464MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 464 of 2131 [DVDAuthorTask] STAT: VOBU 2640 at 467MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 467 of 2131 [DVDAuthorTask] STAT: VOBU 2656 at 470MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 470 of 2131 [DVDAuthorTask] STAT: VOBU 2672 at 473MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 473 of 2131 [DVDAuthorTask] STAT: VOBU 2688 at 477MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 477 of 2131 It's now Sat Dec 25 16:50:18 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292318 (in 99996 ms) It's now Sat Dec 25 16:50:18 2010 [timer.py] next activation: 1293292318 (in 99994 ms) [DVDAuthorTask] STAT: VOBU 2704 at 480MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 480 of 2131 [DVDAuthorTask] STAT: VOBU 2720 at 482MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 482 of 2131 [DVDAuthorTask] STAT: VOBU 2736 at 484MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 484 of 2131 [DVDAuthorTask] STAT: VOBU 2752 at 487MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 487 of 2131 [DVDAuthorTask] STAT: VOBU 2768 at 488MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 488 of 2131 [DVDAuthorTask] STAT: VOBU 2784 at 491MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 491 of 2131 [DVDAuthorTask] STAT: VOBU 2800 at 494MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 494 of 2131 [DVDAuthorTask] STAT: VOBU 2816 at 497MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 497 of 2131 [DVDAuthorTask] STAT: VOBU 2832 at 502MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 502 of 2131 [DVDAuthorTask] STAT: VOBU 2848 at 504MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 504 of 2131 [DVDAuthorTask] STAT: VOBU 2864 at 508MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 508 of 2131 [DVDAuthorTask] STAT: VOBU 2880 at 510MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 510 of 2131 [DVDAuthorTask] STAT: VOBU 2896 at 513MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 513 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370713 bytes for cache used [DVDAuthorTask] STAT: VOBU 2912 at 516MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 516 of 2131 [DVDAuthorTask] STAT: VOBU 2928 at 520MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 520 of 2131 [DVDAuthorTask] STAT: VOBU 2944 at 523MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 523 of 2131 [DVDAuthorTask] STAT: VOBU 2960 at 526MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 526 of 2131 [DVDAuthorTask] STAT: VOBU 2976 at 529MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 529 of 2131 [DVDAuthorTask] STAT: VOBU 2992 at 531MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 531 of 2131 [DVDAuthorTask] STAT: VOBU 3008 at 533MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 533 of 2131 [DVDAuthorTask] STAT: VOBU 3024 at 537MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 537 of 2131 [DVDAuthorTask] STAT: VOBU 3040 at 540MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 540 of 2131 [DVDAuthorTask] STAT: VOBU 3056 at 543MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 543 of 2131 [DVDAuthorTask] STAT: VOBU 3072 at 546MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 546 of 2131 nr_read 82806 nr_write 25467 sum 108273 prev_sum 107128 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 3088 at 548MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 548 of 2131 [DVDAuthorTask] STAT: VOBU 3104 at 550MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 550 of 2131 [DVDAuthorTask] STAT: VOBU 3120 at 552MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 552 of 2131 [DVDAuthorTask] STAT: VOBU 3136 at 555MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 555 of 2131 [DVDAuthorTask] STAT: VOBU 3152 at 557MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 557 of 2131 [DVDAuthorTask] STAT: VOBU 3168 at 559MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 559 of 2131 [DVDAuthorTask] STAT: VOBU 3184 at 562MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 562 of 2131 [DVDAuthorTask] STAT: VOBU 3200 at 566MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 566 of 2131 [DVDAuthorTask] STAT: VOBU 3216 at 569MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 569 of 2131 [DVDAuthorTask] STAT: VOBU 3232 at 571MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 571 of 2131 [DVDAuthorTask] STAT: VOBU 3248 at 573MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 573 of 2131 [DVDAuthorTask] STAT: VOBU 3264 at 575MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 575 of 2131 [DVDAuthorTask] STAT: VOBU 3280 at 577MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 577 of 2131 [DVDAuthorTask] STAT: VOBU 3296 at 580MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 580 of 2131 [DVDAuthorTask] STAT: VOBU 3312 at 583MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 583 of 2131 [DVDAuthorTask] STAT: VOBU 3328 at 587MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 587 of 2131 [DVDAuthorTask] STAT: VOBU 3344 at 590MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 590 of 2131 [DVDAuthorTask] STAT: VOBU 3360 at 594MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 594 of 2131 [DVDAuthorTask] STAT: VOBU 3376 at 598MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 598 of 2131 [DVDAuthorTask] STAT: VOBU 3392 at 601MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 601 of 2131 [DVDAuthorTask] STAT: VOBU 3408 at 605MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 605 of 2131 [DVDAuthorTask] STAT: VOBU 3424 at 608MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 608 of 2131 [DVDAuthorTask] STAT: VOBU 3440 at 611MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 611 of 2131 [DVDAuthorTask] STAT: VOBU 3456 at 615MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 615 of 2131 [DVDAuthorTask] STAT: VOBU 3472 at 619MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 619 of 2131 [DVDAuthorTask] STAT: VOBU 3488 at 622MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 622 of 2131 [DVDAuthorTask] STAT: VOBU 3504 at 625MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 625 of 2131 [DVDAuthorTask] STAT: VOBU 3520 at 629MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 629 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370596 bytes for cache used [DVDAuthorTask] STAT: VOBU 3536 at 632MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 632 of 2131 [DVDAuthorTask] STAT: VOBU 3552 at 635MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 635 of 2131 [DVDAuthorTask] STAT: VOBU 3568 at 638MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 638 of 2131 [DVDAuthorTask] STAT: VOBU 3584 at 642MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 642 of 2131 [DVDAuthorTask] STAT: VOBU 3600 at 645MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 645 of 2131 [DVDAuthorTask] STAT: VOBU 3616 at 649MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 649 of 2131 [DVDAuthorTask] STAT: VOBU 3632 at 652MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 652 of 2131 [DVDAuthorTask] STAT: VOBU 3648 at 655MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 655 of 2131 [DVDAuthorTask] STAT: VOBU 3664 at 658MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 658 of 2131 [DVDAuthorTask] STAT: VOBU 3680 at 661MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 661 of 2131 [DVDAuthorTask] STAT: VOBU 3696 at 664MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 664 of 2131 nr_read 83806 nr_write 25659 sum 109465 prev_sum 108273 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 3712 at 666MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 666 of 2131 [DVDAuthorTask] STAT: VOBU 3728 at 669MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 669 of 2131 [DVDAuthorTask] STAT: VOBU 3744 at 671MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 671 of 2131 It's now Sat Dec 25 16:51:58 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292418 (in 99996 ms) It's now Sat Dec 25 16:51:58 2010 [timer.py] next activation: 1293292418 (in 99992 ms) [DVDAuthorTask] STAT: VOBU 3760 at 674MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 674 of 2131 [DVDAuthorTask] STAT: VOBU 3776 at 677MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 677 of 2131 [DVDAuthorTask] STAT: VOBU 3792 at 679MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 679 of 2131 [DVDAuthorTask] STAT: VOBU 3808 at 682MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 682 of 2131 [DVDAuthorTask] STAT: VOBU 3824 at 684MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 684 of 2131 [DVDAuthorTask] STAT: VOBU 3840 at 687MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 687 of 2131 [DVDAuthorTask] STAT: VOBU 3856 at 690MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 690 of 2131 [DVDAuthorTask] STAT: VOBU 3872 at 692MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 692 of 2131 [DVDAuthorTask] STAT: VOBU 3888 at 694MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 694 of 2131 [DVDAuthorTask] STAT: VOBU 3904 at 697MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 697 of 2131 [DVDAuthorTask] STAT: VOBU 3920 at 699MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 699 of 2131 [DVDAuthorTask] STAT: VOBU 3936 at 701MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 701 of 2131 [DVDAuthorTask] STAT: VOBU 3952 at 703MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 703 of 2131 [DVDAuthorTask] STAT: VOBU 3968 at 706MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 706 of 2131 [DVDAuthorTask] STAT: VOBU 3984 at 708MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 708 of 2131 [DVDAuthorTask] STAT: VOBU 4000 at 710MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 710 of 2131 [DVDAuthorTask] STAT: VOBU 4016 at 713MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 713 of 2131 [DVDAuthorTask] STAT: VOBU 4032 at 715MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 715 of 2131 [DVDAuthorTask] STAT: VOBU 4048 at 718MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 718 of 2131 [DVDAuthorTask] STAT: VOBU 4064 at 721MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 721 of 2131 [DVDAuthorTask] STAT: VOBU 4080 at 724MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 724 of 2131 [DVDAuthorTask] STAT: VOBU 4096 at 726MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 726 of 2131 [DVDAuthorTask] STAT: VOBU 4112 at 728MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 728 of 2131 [DVDAuthorTask] STAT: VOBU 4128 at 732MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 732 of 2131 [DVDAuthorTask] STAT: VOBU 4144 at 734MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 734 of 2131 [DVDAuthorTask] STAT: VOBU 4160 at 737MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 737 of 2131 [DVDAuthorTask] STAT: VOBU 4176 at 739MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 739 of 2131 [DVDAuthorTask] STAT: VOBU 4192 at 743MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 743 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370596 bytes for cache used [DVDAuthorTask] STAT: VOBU 4208 at 745MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 745 of 2131 [DVDAuthorTask] STAT: VOBU 4224 at 747MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 747 of 2131 [DVDAuthorTask] STAT: VOBU 4240 at 750MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 750 of 2131 [DVDAuthorTask] STAT: VOBU 4256 at 752MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 752 of 2131 [DVDAuthorTask] STAT: VOBU 4272 at 754MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 754 of 2131 [DVDAuthorTask] STAT: VOBU 4288 at 757MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 757 of 2131 [DVDAuthorTask] STAT: VOBU 4304 at 759MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 759 of 2131 [DVDAuthorTask] STAT: VOBU 4320 at 760MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 760 of 2131 [DVDAuthorTask] STAT: VOBU 4336 at 762MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 762 of 2131 [DVDAuthorTask] STAT: VOBU 4352 at 764MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 764 of 2131 [DVDAuthorTask] STAT: VOBU 4368 at 766MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 766 of 2131 [DVDAuthorTask] STAT: VOBU 4384 at 769MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 769 of 2131 [DVDAuthorTask] STAT: VOBU 4400 at 772MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 772 of 2131 [DVDAuthorTask] STAT: VOBU 4416 at 775MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 775 of 2131 nr_read 84743 nr_write 25847 sum 110590 prev_sum 109465 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 4432 at 777MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 777 of 2131 [DVDAuthorTask] STAT: VOBU 4448 at 779MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 779 of 2131 [DVDAuthorTask] STAT: VOBU 4464 at 781MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 781 of 2131 [DVDAuthorTask] STAT: VOBU 4480 at 783MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 783 of 2131 [DVDAuthorTask] STAT: VOBU 4496 at 785MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 785 of 2131 [DVDAuthorTask] STAT: VOBU 4512 at 788MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 788 of 2131 [DVDAuthorTask] STAT: VOBU 4528 at 790MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 790 of 2131 [DVDAuthorTask] STAT: VOBU 4544 at 792MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 792 of 2131 [DVDAuthorTask] STAT: VOBU 4560 at 795MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 795 of 2131 [DVDAuthorTask] STAT: VOBU 4576 at 797MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 797 of 2131 [DVDAuthorTask] STAT: VOBU 4592 at 800MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 800 of 2131 [DVDAuthorTask] STAT: VOBU 4608 at 802MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 802 of 2131 [DVDAuthorTask] STAT: VOBU 4624 at 804MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 804 of 2131 [DVDAuthorTask] STAT: VOBU 4640 at 806MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 806 of 2131 [DVDAuthorTask] STAT: VOBU 4656 at 809MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 809 of 2131 [DVDAuthorTask] STAT: VOBU 4672 at 812MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 812 of 2131 [DVDAuthorTask] STAT: VOBU 4688 at 815MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 815 of 2131 [DVDAuthorTask] STAT: VOBU 4704 at 817MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 817 of 2131 [DVDAuthorTask] STAT: VOBU 4720 at 821MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 821 of 2131 [DVDAuthorTask] STAT: VOBU 4736 at 824MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 824 of 2131 [DVDAuthorTask] STAT: VOBU 4752 at 826MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 826 of 2131 [DVDAuthorTask] STAT: VOBU 4768 at 829MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 829 of 2131 [DVDAuthorTask] STAT: VOBU 4784 at 833MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 833 of 2131 [DVDAuthorTask] STAT: VOBU 4800 at 836MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 836 of 2131 [DVDAuthorTask] STAT: VOBU 4816 at 838MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 838 of 2131 [DVDAuthorTask] STAT: VOBU 4832 at 841MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 841 of 2131 [DVDAuthorTask] STAT: VOBU 4848 at 844MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 844 of 2131 [DVDAuthorTask] STAT: VOBU 4864 at 846MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 846 of 2131 [DVDAuthorTask] STAT: VOBU 4880 at 848MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 848 of 2131 [DVDAuthorTask] STAT: VOBU 4896 at 851MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 851 of 2131 [DVDAuthorTask] STAT: VOBU 4912 at 854MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 854 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370596 bytes for cache used [DVDAuthorTask] STAT: VOBU 4928 at 857MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 857 of 2131 It's now Sat Dec 25 16:53:38 2010 [timer.py] next activation: 1293292518 (in 99997 ms) It's now Sat Dec 25 16:53:38 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292518 (in 99992 ms) [DVDAuthorTask] STAT: VOBU 4944 at 859MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 859 of 2131 [DVDAuthorTask] STAT: VOBU 4960 at 862MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 862 of 2131 [DVDAuthorTask] STAT: VOBU 4976 at 866MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 866 of 2131 [DVDAuthorTask] STAT: VOBU 4992 at 869MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 869 of 2131 [DVDAuthorTask] STAT: VOBU 5008 at 872MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 872 of 2131 [DVDAuthorTask] STAT: VOBU 5024 at 875MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 875 of 2131 [DVDAuthorTask] STAT: VOBU 5040 at 877MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 877 of 2131 [DVDAuthorTask] STAT: VOBU 5056 at 879MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 879 of 2131 [DVDAuthorTask] STAT: VOBU 5072 at 881MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 881 of 2131 [DVDAuthorTask] STAT: VOBU 5088 at 884MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 884 of 2131 [DVDAuthorTask] STAT: VOBU 5104 at 886MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 886 of 2131 nr_read 85666 nr_write 26034 sum 111700 prev_sum 110590 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 5120 at 889MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 889 of 2131 [DVDAuthorTask] STAT: VOBU 5136 at 891MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 891 of 2131 [DVDAuthorTask] STAT: VOBU 5152 at 893MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 893 of 2131 [DVDAuthorTask] STAT: VOBU 5168 at 896MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 896 of 2131 [DVDAuthorTask] STAT: VOBU 5184 at 898MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 898 of 2131 [DVDAuthorTask] STAT: VOBU 5200 at 902MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 902 of 2131 [DVDAuthorTask] STAT: VOBU 5216 at 905MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 905 of 2131 [DVDAuthorTask] STAT: VOBU 5232 at 910MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 910 of 2131 [DVDAuthorTask] STAT: VOBU 5248 at 913MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 913 of 2131 [DVDAuthorTask] STAT: VOBU 5264 at 916MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 916 of 2131 [DVDAuthorTask] STAT: VOBU 5280 at 920MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 920 of 2131 [DVDAuthorTask] STAT: VOBU 5296 at 923MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 923 of 2131 [DVDAuthorTask] STAT: VOBU 5312 at 925MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 925 of 2131 [DVDAuthorTask] STAT: VOBU 5328 at 927MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 927 of 2131 [DVDAuthorTask] STAT: VOBU 5344 at 931MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 931 of 2131 [DVDAuthorTask] STAT: VOBU 5360 at 933MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 933 of 2131 [DVDAuthorTask] STAT: VOBU 5376 at 936MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 936 of 2131 [DVDAuthorTask] STAT: VOBU 5392 at 939MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 939 of 2131 [DVDAuthorTask] STAT: VOBU 5408 at 943MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 943 of 2131 [DVDAuthorTask] STAT: VOBU 5424 at 948MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 948 of 2131 [DVDAuthorTask] STAT: VOBU 5440 at 950MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 950 of 2131 [DVDAuthorTask] STAT: VOBU 5456 at 954MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 954 of 2131 [DVDAuthorTask] STAT: VOBU 5472 at 957MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 957 of 2131 [DVDAuthorTask] STAT: VOBU 5488 at 962MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 962 of 2131 [DVDAuthorTask] STAT: VOBU 5504 at 966MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 966 of 2131 [DVDAuthorTask] STAT: VOBU 5520 at 970MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 970 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370596 bytes for cache used [DVDAuthorTask] STAT: VOBU 5536 at 975MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 975 of 2131 [DVDAuthorTask] STAT: VOBU 5552 at 979MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 979 of 2131 [DVDAuthorTask] STAT: VOBU 5568 at 982MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 982 of 2131 [DVDAuthorTask] STAT: VOBU 5584 at 986MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 986 of 2131 [DVDAuthorTask] STAT: VOBU 5600 at 988MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 988 of 2131 [DVDAuthorTask] STAT: VOBU 5616 at 992MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 992 of 2131 [DVDAuthorTask] STAT: VOBU 5632 at 995MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 995 of 2131 [DVDAuthorTask] STAT: VOBU 5648 at 999MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 999 of 2131 [DVDAuthorTask] STAT: VOBU 5664 at 1002MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1002 of 2131 [DVDAuthorTask] STAT: VOBU 5680 at 1006MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1006 of 2131 nr_read 86643 nr_write 26230 sum 112873 prev_sum 111700 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 5696 at 1009MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1009 of 2131 [DVDAuthorTask] STAT: VOBU 5712 at 1012MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1012 of 2131 [DVDAuthorTask] STAT: VOBU 5728 at 1014MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1014 of 2131 [DVDAuthorTask] STAT: VOBU 5744 at 1016MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1016 of 2131 [DVDAuthorTask] STAT: VOBU 5760 at 1019MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1019 of 2131 [DVDAuthorTask] STAT: VOBU 5776 at 1021MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1021 of 2131 [DVDAuthorTask] STAT: VOBU 5792 at 1023MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1023 of 2131 [DVDAuthorTask] STAT: VOBU 5808 at 1025MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1025 of 2131 [DVDAuthorTask] STAT: VOBU 5824 at 1027MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1027 of 2131 [DVDAuthorTask] STAT: VOBU 5840 at 1029MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1029 of 2131 [DVDAuthorTask] STAT: VOBU 5856 at 1032MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1032 of 2131 [DVDAuthorTask] STAT: VOBU 5872 at 1034MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1034 of 2131 [DVDAuthorTask] STAT: VOBU 5888 at 1037MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1037 of 2131 [DVDAuthorTask] STAT: VOBU 5904 at 1039MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1039 of 2131 [DVDAuthorTask] STAT: VOBU 5920 at 1041MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1041 of 2131 [DVDAuthorTask] STAT: VOBU 5936 at 1044MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1044 of 2131 [DVDAuthorTask] STAT: VOBU 5952 at 1046MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1046 of 2131 [DVDAuthorTask] STAT: VOBU 5968 at 1048MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1048 of 2131 [DVDAuthorTask] STAT: VOBU 5984 at 1050MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1050 of 2131 [DVDAuthorTask] STAT: VOBU 6000 at 1052MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1052 of 2131 It's now Sat Dec 25 16:55:18 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292618 (in 99996 ms) It's now Sat Dec 25 16:55:18 2010 [timer.py] next activation: 1293292618 (in 99992 ms) [DVDAuthorTask] STAT: VOBU 6016 at 1054MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1054 of 2131 [DVDAuthorTask] STAT: VOBU 6032 at 1056MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1056 of 2131 [DVDAuthorTask] STAT: VOBU 6048 at 1058MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1058 of 2131 [DVDAuthorTask] STAT: VOBU 6064 at 1061MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1061 of 2131 [DVDAuthorTask] STAT: VOBU 6080 at 1063MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1063 of 2131 [DVDAuthorTask] STAT: VOBU 6096 at 1065MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1065 of 2131 [DVDAuthorTask] STAT: VOBU 6112 at 1066MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1066 of 2131 [DVDAuthorTask] STAT: VOBU 6128 at 1069MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1069 of 2131 [DVDAuthorTask] STAT: VOBU 6144 at 1071MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1071 of 2131 [DVDAuthorTask] STAT: VOBU 6160 at 1074MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1074 of 2131 [DVDAuthorTask] STAT: VOBU 6176 at 1076MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1076 of 2131 [DVDAuthorTask] STAT: VOBU 6192 at 1079MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1079 of 2131 [DVDAuthorTask] STAT: VOBU 6208 at 1082MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1082 of 2131 [DVDAuthorTask] STAT: VOBU 6224 at 1084MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1084 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370532 bytes for cache used [DVDAuthorTask] STAT: VOBU 6240 at 1087MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1087 of 2131 [DVDAuthorTask] STAT: VOBU 6256 at 1089MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1089 of 2131 [DVDAuthorTask] STAT: VOBU 6272 at 1092MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1092 of 2131 [DVDAuthorTask] STAT: VOBU 6288 at 1095MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1095 of 2131 [DVDAuthorTask] STAT: VOBU 6304 at 1097MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1097 of 2131 [DVDAuthorTask] STAT: VOBU 6320 at 1100MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1100 of 2131 [DVDAuthorTask] STAT: VOBU 6336 at 1102MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1102 of 2131 [DVDAuthorTask] STAT: VOBU 6352 at 1105MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1105 of 2131 [DVDAuthorTask] STAT: VOBU 6368 at 1107MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1107 of 2131 [DVDAuthorTask] STAT: VOBU 6384 at 1110MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1110 of 2131 [DVDAuthorTask] STAT: VOBU 6400 at 1112MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1112 of 2131 [DVDAuthorTask] STAT: VOBU 6416 at 1114MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1114 of 2131 [DVDAuthorTask] STAT: VOBU 6432 at 1116MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1116 of 2131 nr_read 87566 nr_write 26597 sum 114163 prev_sum 112873 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 6448 at 1119MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1119 of 2131 [DVDAuthorTask] STAT: VOBU 6464 at 1122MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1122 of 2131 [DVDAuthorTask] STAT: VOBU 6480 at 1125MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1125 of 2131 [DVDAuthorTask] STAT: VOBU 6496 at 1127MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1127 of 2131 [DVDAuthorTask] STAT: VOBU 6512 at 1130MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1130 of 2131 [DVDAuthorTask] STAT: VOBU 6528 at 1132MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1132 of 2131 [DVDAuthorTask] STAT: VOBU 6544 at 1134MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1134 of 2131 [DVDAuthorTask] STAT: VOBU 6560 at 1136MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1136 of 2131 [DVDAuthorTask] STAT: VOBU 6576 at 1139MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1139 of 2131 [DVDAuthorTask] STAT: VOBU 6592 at 1142MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1142 of 2131 [DVDAuthorTask] STAT: VOBU 6608 at 1145MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1145 of 2131 [DVDAuthorTask] STAT: VOBU 6624 at 1148MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1148 of 2131 [DVDAuthorTask] STAT: VOBU 6640 at 1150MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1150 of 2131 [DVDAuthorTask] STAT: VOBU 6656 at 1153MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1153 of 2131 [DVDAuthorTask] STAT: VOBU 6672 at 1156MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1156 of 2131 [DVDAuthorTask] STAT: VOBU 6688 at 1159MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1159 of 2131 [DVDAuthorTask] STAT: VOBU 6704 at 1162MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1162 of 2131 [DVDAuthorTask] STAT: VOBU 6720 at 1166MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1166 of 2131 [DVDAuthorTask] STAT: VOBU 6736 at 1169MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1169 of 2131 [DVDAuthorTask] STAT: VOBU 6752 at 1172MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1172 of 2131 [DVDAuthorTask] STAT: VOBU 6768 at 1174MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1174 of 2131 [DVDAuthorTask] STAT: VOBU 6784 at 1177MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1177 of 2131 [DVDAuthorTask] STAT: VOBU 6800 at 1180MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1180 of 2131 [DVDAuthorTask] STAT: VOBU 6816 at 1184MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1184 of 2131 [DVDAuthorTask] STAT: VOBU 6832 at 1187MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1187 of 2131 [DVDAuthorTask] STAT: VOBU 6848 at 1191MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1191 of 2131 [DVDAuthorTask] STAT: VOBU 6864 at 1195MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1195 of 2131 [DVDAuthorTask] STAT: VOBU 6880 at 1199MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1199 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370532 bytes for cache used [DVDAuthorTask] STAT: VOBU 6896 at 1202MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1202 of 2131 [DVDAuthorTask] STAT: VOBU 6912 at 1206MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1206 of 2131 [DVDAuthorTask] STAT: VOBU 6928 at 1209MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1209 of 2131 [DVDAuthorTask] STAT: VOBU 6944 at 1213MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1213 of 2131 [DVDAuthorTask] STAT: VOBU 6960 at 1217MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1217 of 2131 [DVDAuthorTask] STAT: VOBU 6976 at 1221MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1221 of 2131 [DVDAuthorTask] STAT: VOBU 6992 at 1225MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1225 of 2131 [DVDAuthorTask] STAT: VOBU 7008 at 1229MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1229 of 2131 [DVDAuthorTask] STAT: VOBU 7024 at 1232MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1232 of 2131 nr_read 88523 nr_write 26789 sum 115312 prev_sum 114163 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 7040 at 1236MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1236 of 2131 [DVDAuthorTask] STAT: VOBU 7056 at 1239MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1239 of 2131 [DVDAuthorTask] STAT: VOBU 7072 at 1242MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1242 of 2131 It's now Sat Dec 25 16:56:58 2010 [timer.py] next activation: 1293292718 (in 99995 ms) It's now Sat Dec 25 16:56:58 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292718 (in 99991 ms) [DVDAuthorTask] STAT: VOBU 7088 at 1246MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1246 of 2131 [DVDAuthorTask] STAT: VOBU 7104 at 1249MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1249 of 2131 [DVDAuthorTask] STAT: VOBU 7120 at 1254MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1254 of 2131 [DVDAuthorTask] STAT: VOBU 7136 at 1257MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1257 of 2131 [DVDAuthorTask] STAT: VOBU 7152 at 1261MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1261 of 2131 [DVDAuthorTask] STAT: VOBU 7168 at 1264MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1264 of 2131 [DVDAuthorTask] STAT: VOBU 7184 at 1268MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1268 of 2131 [DVDAuthorTask] STAT: VOBU 7200 at 1272MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1272 of 2131 [DVDAuthorTask] STAT: VOBU 7216 at 1275MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1275 of 2131 [DVDAuthorTask] STAT: VOBU 7232 at 1279MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1279 of 2131 [DVDAuthorTask] STAT: VOBU 7248 at 1282MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1282 of 2131 [DVDAuthorTask] STAT: VOBU 7264 at 1287MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1287 of 2131 [DVDAuthorTask] STAT: VOBU 7280 at 1291MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1291 of 2131 [DVDAuthorTask] STAT: VOBU 7296 at 1294MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1294 of 2131 [DVDAuthorTask] STAT: VOBU 7312 at 1298MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1298 of 2131 [DVDAuthorTask] STAT: VOBU 7328 at 1302MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1302 of 2131 [DVDAuthorTask] STAT: VOBU 7344 at 1305MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1305 of 2131 [DVDAuthorTask] STAT: VOBU 7360 at 1308MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1308 of 2131 [DVDAuthorTask] STAT: VOBU 7376 at 1311MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1311 of 2131 [DVDAuthorTask] STAT: VOBU 7392 at 1313MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1313 of 2131 [DVDAuthorTask] STAT: VOBU 7408 at 1315MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1315 of 2131 [DVDAuthorTask] STAT: VOBU 7424 at 1318MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1318 of 2131 [DVDAuthorTask] STAT: VOBU 7440 at 1321MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1321 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370532 bytes for cache used [DVDAuthorTask] STAT: VOBU 7456 at 1324MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1324 of 2131 [DVDAuthorTask] STAT: VOBU 7472 at 1326MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1326 of 2131 [DVDAuthorTask] STAT: VOBU 7488 at 1328MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1328 of 2131 [DVDAuthorTask] STAT: VOBU 7504 at 1332MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1332 of 2131 [DVDAuthorTask] STAT: VOBU 7520 at 1336MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1336 of 2131 [DVDAuthorTask] STAT: VOBU 7536 at 1339MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1339 of 2131 [DVDAuthorTask] STAT: VOBU 7552 at 1343MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1343 of 2131 [DVDAuthorTask] STAT: VOBU 7568 at 1347MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1347 of 2131 [DVDAuthorTask] STAT: VOBU 7584 at 1350MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1350 of 2131 [DVDAuthorTask] STAT: VOBU 7600 at 1354MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1354 of 2131 nr_read 89495 nr_write 26985 sum 116480 prev_sum 115312 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 7616 at 1358MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1358 of 2131 [DVDAuthorTask] STAT: VOBU 7632 at 1361MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1361 of 2131 [DVDAuthorTask] STAT: VOBU 7648 at 1364MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1364 of 2131 [DVDAuthorTask] STAT: VOBU 7664 at 1366MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1366 of 2131 [DVDAuthorTask] STAT: VOBU 7680 at 1369MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1369 of 2131 [DVDAuthorTask] STAT: VOBU 7696 at 1372MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1372 of 2131 [DVDAuthorTask] STAT: VOBU 7712 at 1375MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1375 of 2131 [DVDAuthorTask] STAT: VOBU 7728 at 1378MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1378 of 2131 [DVDAuthorTask] STAT: VOBU 7744 at 1381MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1381 of 2131 [DVDAuthorTask] STAT: VOBU 7760 at 1383MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1383 of 2131 [DVDAuthorTask] STAT: VOBU 7776 at 1386MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1386 of 2131 [DVDAuthorTask] STAT: VOBU 7792 at 1389MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1389 of 2131 [DVDAuthorTask] STAT: VOBU 7808 at 1391MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1391 of 2131 [DVDAuthorTask] STAT: VOBU 7824 at 1394MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1394 of 2131 [DVDAuthorTask] STAT: VOBU 7840 at 1395MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1395 of 2131 [DVDAuthorTask] STAT: VOBU 7856 at 1397MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1397 of 2131 [DVDAuthorTask] STAT: VOBU 7872 at 1399MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1399 of 2131 [DVDAuthorTask] STAT: VOBU 7888 at 1400MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1400 of 2131 [DVDAuthorTask] STAT: VOBU 7904 at 1402MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1402 of 2131 [DVDAuthorTask] STAT: VOBU 7920 at 1406MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1406 of 2131 [DVDAuthorTask] STAT: VOBU 7936 at 1410MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1410 of 2131 [DVDAuthorTask] STAT: VOBU 7952 at 1413MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1413 of 2131 [DVDAuthorTask] STAT: VOBU 7968 at 1417MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1417 of 2131 [DVDAuthorTask] STAT: VOBU 7984 at 1421MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1421 of 2131 [DVDAuthorTask] STAT: VOBU 8000 at 1425MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1425 of 2131 [DVDAuthorTask] STAT: VOBU 8016 at 1430MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1430 of 2131 [DVDAuthorTask] STAT: VOBU 8032 at 1434MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1434 of 2131 [DVDAuthorTask] STAT: VOBU 8048 at 1437MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1437 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370532 bytes for cache used [DVDAuthorTask] STAT: VOBU 8064 at 1439MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1439 of 2131 [DVDAuthorTask] STAT: VOBU 8080 at 1441MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1441 of 2131 It's now Sat Dec 25 16:58:38 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292818 (in 99996 ms) It's now Sat Dec 25 16:58:38 2010 [timer.py] next activation: 1293292818 (in 99993 ms) [DVDAuthorTask] STAT: VOBU 8096 at 1443MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1443 of 2131 [DVDAuthorTask] STAT: VOBU 8112 at 1445MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1445 of 2131 [DVDAuthorTask] STAT: VOBU 8128 at 1448MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1448 of 2131 [DVDAuthorTask] STAT: VOBU 8144 at 1451MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1451 of 2131 [DVDAuthorTask] STAT: VOBU 8160 at 1454MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1454 of 2131 [DVDAuthorTask] STAT: VOBU 8176 at 1457MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1457 of 2131 [DVDAuthorTask] STAT: VOBU 8192 at 1460MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1460 of 2131 [DVDAuthorTask] STAT: VOBU 8208 at 1463MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1463 of 2131 [DVDAuthorTask] STAT: VOBU 8224 at 1466MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1466 of 2131 [DVDAuthorTask] STAT: VOBU 8240 at 1469MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1469 of 2131 nr_read 90429 nr_write 27165 sum 117594 prev_sum 116480 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 8256 at 1473MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1473 of 2131 [DVDAuthorTask] STAT: VOBU 8272 at 1477MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1477 of 2131 [DVDAuthorTask] STAT: VOBU 8288 at 1481MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1481 of 2131 [DVDAuthorTask] STAT: VOBU 8304 at 1484MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1484 of 2131 [DVDAuthorTask] STAT: VOBU 8320 at 1487MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1487 of 2131 [DVDAuthorTask] STAT: VOBU 8336 at 1491MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1491 of 2131 [DVDAuthorTask] STAT: VOBU 8352 at 1495MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1495 of 2131 [DVDAuthorTask] STAT: VOBU 8368 at 1499MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1499 of 2131 [DVDAuthorTask] STAT: VOBU 8384 at 1504MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1504 of 2131 [DVDAuthorTask] STAT: VOBU 8400 at 1508MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1508 of 2131 [DVDAuthorTask] STAT: VOBU 8416 at 1513MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1513 of 2131 [DVDAuthorTask] STAT: VOBU 8432 at 1516MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1516 of 2131 [DVDAuthorTask] STAT: VOBU 8448 at 1520MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1520 of 2131 [DVDAuthorTask] STAT: VOBU 8464 at 1523MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1523 of 2131 [DVDAuthorTask] STAT: VOBU 8480 at 1527MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1527 of 2131 [DVDAuthorTask] STAT: VOBU 8496 at 1532MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1532 of 2131 [DVDAuthorTask] STAT: VOBU 8512 at 1535MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1535 of 2131 [DVDAuthorTask] STAT: VOBU 8528 at 1538MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1538 of 2131 [DVDAuthorTask] STAT: VOBU 8544 at 1542MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1542 of 2131 [DVDAuthorTask] STAT: VOBU 8560 at 1545MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1545 of 2131 [DVDAuthorTask] STAT: VOBU 8576 at 1549MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1549 of 2131 [DVDAuthorTask] STAT: VOBU 8592 at 1553MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1553 of 2131 [DVDAuthorTask] STAT: VOBU 8608 at 1557MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1557 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 370500 bytes for cache used [DVDAuthorTask] STAT: VOBU 8624 at 1560MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1560 of 2131 [DVDAuthorTask] STAT: VOBU 8640 at 1564MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1564 of 2131 [DVDAuthorTask] STAT: VOBU 8656 at 1568MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1568 of 2131 [DVDAuthorTask] STAT: VOBU 8672 at 1571MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1571 of 2131 [DVDAuthorTask] STAT: VOBU 8688 at 1576MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1576 of 2131 [DVDAuthorTask] STAT: VOBU 8704 at 1580MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1580 of 2131 [DVDAuthorTask] STAT: VOBU 8720 at 1583MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1583 of 2131 [DVDAuthorTask] STAT: VOBU 8736 at 1587MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1587 of 2131 [DVDAuthorTask] STAT: VOBU 8752 at 1591MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1591 of 2131 nr_read 91427 nr_write 27367 sum 118794 prev_sum 117594 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 8768 at 1594MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1594 of 2131 [DVDAuthorTask] STAT: VOBU 8784 at 1598MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1598 of 2131 [DVDAuthorTask] STAT: VOBU 8800 at 1601MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1601 of 2131 [DVDAuthorTask] STAT: VOBU 8816 at 1605MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1605 of 2131 [DVDAuthorTask] STAT: VOBU 8832 at 1609MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1609 of 2131 [DVDAuthorTask] STAT: VOBU 8848 at 1612MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1612 of 2131 [DVDAuthorTask] STAT: VOBU 8864 at 1616MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1616 of 2131 [DVDAuthorTask] STAT: VOBU 8880 at 1619MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1619 of 2131 [DVDAuthorTask] STAT: VOBU 8896 at 1622MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1622 of 2131 [DVDAuthorTask] STAT: VOBU 8912 at 1625MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1625 of 2131 [DVDAuthorTask] STAT: VOBU 8928 at 1628MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1628 of 2131 [DVDAuthorTask] STAT: VOBU 8944 at 1631MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1631 of 2131 [DVDAuthorTask] STAT: VOBU 8960 at 1633MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1633 of 2131 [DVDAuthorTask] STAT: VOBU 8976 at 1635MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1635 of 2131 [DVDAuthorTask] STAT: VOBU 8992 at 1637MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1637 of 2131 [DVDAuthorTask] STAT: VOBU 9008 at 1641MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1641 of 2131 It's now Sat Dec 25 17:00:18 2010 [timer.py] next activation: 1293292918 (in 99997 ms) It's now Sat Dec 25 17:00:18 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293292918 (in 99992 ms) [DVDAuthorTask] STAT: VOBU 9024 at 1645MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1645 of 2131 [DVDAuthorTask] STAT: VOBU 9040 at 1648MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1648 of 2131 [DVDAuthorTask] STAT: VOBU 9056 at 1651MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1651 of 2131 [DVDAuthorTask] STAT: VOBU 9072 at 1655MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1655 of 2131 [DVDAuthorTask] STAT: VOBU 9088 at 1659MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1659 of 2131 [DVDAuthorTask] STAT: VOBU 9104 at 1663MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1663 of 2131 [DVDAuthorTask] STAT: VOBU 9120 at 1668MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1668 of 2131 [DVDAuthorTask] STAT: VOBU 9136 at 1671MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1671 of 2131 [DVDAuthorTask] STAT: VOBU 9152 at 1675MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1675 of 2131 [DVDAuthorTask] STAT: VOBU 9168 at 1678MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1678 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 365469 bytes for cache used [DVDAuthorTask] STAT: VOBU 9184 at 1682MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1682 of 2131 [DVDAuthorTask] STAT: VOBU 9200 at 1685MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1685 of 2131 [DVDAuthorTask] STAT: VOBU 9216 at 1688MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1688 of 2131 [DVDAuthorTask] STAT: VOBU 9232 at 1690MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1690 of 2131 [DVDAuthorTask] STAT: VOBU 9248 at 1693MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1693 of 2131 [DVDAuthorTask] STAT: VOBU 9264 at 1696MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1696 of 2131 [DVDAuthorTask] STAT: VOBU 9280 at 1700MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1700 of 2131 [DVDAuthorTask] STAT: VOBU 9296 at 1702MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1702 of 2131 [DVDAuthorTask] STAT: VOBU 9312 at 1705MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1705 of 2131 [DVDAuthorTask] STAT: VOBU 9328 at 1709MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1709 of 2131 [DVDAuthorTask] STAT: VOBU 9344 at 1712MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1712 of 2131 nr_read 92385 nr_write 27550 sum 119935 prev_sum 118794 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 9360 at 1715MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1715 of 2131 [DVDAuthorTask] STAT: VOBU 9376 at 1718MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1718 of 2131 [DVDAuthorTask] STAT: VOBU 9392 at 1722MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1722 of 2131 [DVDAuthorTask] STAT: VOBU 9408 at 1726MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1726 of 2131 [DVDAuthorTask] STAT: VOBU 9424 at 1729MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1729 of 2131 [DVDAuthorTask] STAT: VOBU 9440 at 1732MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1732 of 2131 [DVDAuthorTask] STAT: VOBU 9456 at 1735MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1735 of 2131 [DVDAuthorTask] STAT: VOBU 9472 at 1739MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1739 of 2131 [DVDAuthorTask] STAT: VOBU 9488 at 1743MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1743 of 2131 [DVDAuthorTask] STAT: VOBU 9504 at 1747MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1747 of 2131 [DVDAuthorTask] STAT: VOBU 9520 at 1750MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1750 of 2131 [DVDAuthorTask] STAT: VOBU 9536 at 1753MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1753 of 2131 [DVDAuthorTask] STAT: VOBU 9552 at 1757MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1757 of 2131 [DVDAuthorTask] STAT: VOBU 9568 at 1761MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1761 of 2131 [DVDAuthorTask] STAT: VOBU 9584 at 1765MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1765 of 2131 [DVDAuthorTask] STAT: VOBU 9600 at 1769MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1769 of 2131 [DVDAuthorTask] STAT: VOBU 9616 at 1773MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1773 of 2131 [DVDAuthorTask] STAT: VOBU 9632 at 1777MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1777 of 2131 [DVDAuthorTask] STAT: VOBU 9648 at 1781MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1781 of 2131 [DVDAuthorTask] STAT: VOBU 9664 at 1785MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1785 of 2131 [DVDAuthorTask] STAT: VOBU 9680 at 1788MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1788 of 2131 [DVDAuthorTask] STAT: VOBU 9696 at 1791MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1791 of 2131 [DVDAuthorTask] STAT: VOBU 9712 at 1794MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1794 of 2131 [DVDAuthorTask] STAT: VOBU 9728 at 1798MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1798 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 365469 bytes for cache used [DVDAuthorTask] STAT: VOBU 9744 at 1803MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1803 of 2131 [DVDAuthorTask] STAT: VOBU 9760 at 1806MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1806 of 2131 [DVDAuthorTask] STAT: VOBU 9776 at 1809MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1809 of 2131 [DVDAuthorTask] STAT: VOBU 9792 at 1814MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1814 of 2131 [DVDAuthorTask] STAT: VOBU 9808 at 1818MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1818 of 2131 [DVDAuthorTask] STAT: VOBU 9824 at 1823MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1823 of 2131 [DVDAuthorTask] STAT: VOBU 9840 at 1828MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1828 of 2131 [DVDAuthorTask] STAT: VOBU 9856 at 1832MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1832 of 2131 nr_read 93376 nr_write 27760 sum 121136 prev_sum 119935 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 9872 at 1836MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1836 of 2131 [DVDAuthorTask] STAT: VOBU 9888 at 1839MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1839 of 2131 [DVDAuthorTask] STAT: VOBU 9904 at 1842MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1842 of 2131 It's now Sat Dec 25 17:01:58 2010 [timer.py] next activation: 1293293018 (in 99997 ms) It's now Sat Dec 25 17:01:58 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293018 (in 99992 ms) [DVDAuthorTask] STAT: VOBU 9920 at 1846MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1846 of 2131 [DVDAuthorTask] STAT: VOBU 9936 at 1849MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1849 of 2131 [DVDAuthorTask] STAT: VOBU 9952 at 1853MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1853 of 2131 [DVDAuthorTask] STAT: VOBU 9968 at 1857MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1857 of 2131 [DVDAuthorTask] STAT: VOBU 9984 at 1861MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1861 of 2131 [DVDAuthorTask] STAT: VOBU 10000 at 1864MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1864 of 2131 [DVDAuthorTask] STAT: VOBU 10016 at 1867MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1867 of 2131 [DVDAuthorTask] STAT: VOBU 10032 at 1871MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1871 of 2131 [DVDAuthorTask] STAT: VOBU 10048 at 1875MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1875 of 2131 [DVDAuthorTask] STAT: VOBU 10064 at 1879MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1879 of 2131 [DVDAuthorTask] STAT: VOBU 10080 at 1882MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1882 of 2131 [DVDAuthorTask] STAT: VOBU 10096 at 1885MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1885 of 2131 [DVDAuthorTask] STAT: VOBU 10112 at 1888MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1888 of 2131 [DVDAuthorTask] STAT: VOBU 10128 at 1891MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1891 of 2131 [DVDAuthorTask] STAT: VOBU 10144 at 1895MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1895 of 2131 [DVDAuthorTask] STAT: VOBU 10160 at 1897MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1897 of 2131 [DVDAuthorTask] STAT: VOBU 10176 at 1900MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1900 of 2131 [DVDAuthorTask] STAT: VOBU 10192 at 1902MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1902 of 2131 [DVDAuthorTask] STAT: VOBU 10208 at 1904MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1904 of 2131 [DVDAuthorTask] STAT: VOBU 10224 at 1906MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1906 of 2131 [DVDAuthorTask] STAT: VOBU 10240 at 1909MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1909 of 2131 [DVDAuthorTask] STAT: VOBU 10256 at 1913MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1913 of 2131 [DVDAuthorTask] STAT: VOBU 10272 at 1917MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1917 of 2131 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 365469 bytes for cache used [DVDAuthorTask] STAT: VOBU 10288 at 1921MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1921 of 2131 [DVDAuthorTask] STAT: VOBU 10304 at 1924MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1924 of 2131 [DVDAuthorTask] STAT: VOBU 10320 at 1927MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1927 of 2131 [DVDAuthorTask] STAT: VOBU 10336 at 1930MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1930 of 2131 [DVDAuthorTask] STAT: VOBU 10352 at 1933MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1933 of 2131 [DVDAuthorTask] STAT: VOBU 10368 at 1938MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1938 of 2131 [DVDAuthorTask] STAT: VOBU 10384 at 1942MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1942 of 2131 [DVDAuthorTask] STAT: VOBU 10400 at 1947MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1947 of 2131 [DVDAuthorTask] STAT: VOBU 10416 at 1951MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1951 of 2131 nr_read 94351 nr_write 27958 sum 122309 prev_sum 121136 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: VOBU 10432 at 1956MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1956 of 2131 [DVDAuthorTask] STAT: VOBU 10448 at 1960MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1960 of 2131 [DVDAuthorTask] STAT: VOBU 10464 at 1965MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1965 of 2131 [DVDAuthorTask] STAT: VOBU 10480 at 1968MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1968 of 2131 [DVDAuthorTask] STAT: VOBU 10496 at 1972MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1972 of 2131 [DVDAuthorTask] STAT: VOBU 10512 at 1976MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1976 of 2131 [DVDAuthorTask] STAT: VOBU 10528 at 1979MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1979 of 2131 [DVDAuthorTask] STAT: VOBU 10544 at 1984MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1984 of 2131 [DVDAuthorTask] STAT: VOBU 10560 at 1988MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1988 of 2131 [DVDAuthorTask] STAT: VOBU 10576 at 1991MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1991 of 2131 [DVDAuthorTask] STAT: VOBU 10592 at 1996MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 1996 of 2131 [DVDAuthorTask] STAT: VOBU 10608 at 2000MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 2000 of 2131 [DVDAuthorTask] STAT: VOBU 10624 at 2004MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 2004 of 2131 [DVDAuthorTask] STAT: VOBU 10640 at 2008MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 2008 of 2131 [DVDAuthorTask] STAT: VOBU 10656 at 2012MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 2012 of 2131 [MplexTask] INFO: [mplex] Scanned to end AU 128009 [MplexTask] INFO: [mplex] STREAM e0 completed [MplexTask] INFO: [mplex] STREAM c0 completed [DVDAuthorTask] [DVDAuthorTask] INFO: Video pts = 0.120 .. 5120.520 [DVDAuthorTask] INFO: Audio[8] pts = 0.120 .. 5120.520 [DVDAuthorTask] STAT: VOBU 10668 at 2015MB, 1 PGCS [DVDAuthorTask] update mplextask progress: 2015 of 2131 [DVDAuthorTask] [DVDAuthorTask] INFO: Generating VTS with the following video attributes: [DVDAuthorTask] INFO: MPEG version: mpeg2 [DVDAuthorTask] INFO: TV standard: pal [DVDAuthorTask] INFO: Aspect ratio: 16:9 [DVDAuthorTask] INFO: Resolution: 720x576 [DVDAuthorTask] INFO: Audio ch 0 format: mp2/2ch, 48khz 20bps, 'da' [DVDAuthorTask] [MplexTask] INFO: [mplex] Multiplex completion at SCR=460838326. [MplexTask] INFO: [mplex] Video e0: completed [MplexTask] INFO: [mplex] Audio c0: completed [MplexTask] INFO: [mplex] VIDEO_STATISTICS: e0 [MplexTask] INFO: [mplex] Video Stream length: 1893073728 bytes [MplexTask] INFO: [mplex] Sequence headers: 10668 [MplexTask] INFO: [mplex] Sequence ends : 1 [MplexTask] INFO: [mplex] No. Pictures : 128010 [MplexTask] INFO: [mplex] No. Groups : 10668 [MplexTask] INFO: [mplex] No. I Frames : 10668 avg. size 46588 bytes [MplexTask] INFO: [mplex] No. P Frames : 32004 avg. size 19850 bytes [MplexTask] INFO: [mplex] No. B Frames : 85338 avg. size 8914 bytes [MplexTask] INFO: [mplex] Average bit-rate : 2957600 bits/sec [MplexTask] INFO: [mplex] Peak bit-rate : 6378400 bits/sec [MplexTask] INFO: [mplex] BUFFERING min 15 Buf max 209371 [MplexTask] INFO: [mplex] AUDIO_STATISTICS: c0 [MplexTask] INFO: [mplex] Audio stream length 163852800 bytes. [MplexTask] INFO: [mplex] Syncwords : 213350 [MplexTask] INFO: [mplex] Frames : 213350 padded [MplexTask] INFO: [mplex] Frames : 0 unpadded [MplexTask] INFO: [mplex] BUFFERING min 15 Buf max 779 [MplexTask] INFO: [mplex] MUX STATUS: no under-runs detected. child has terminated pipes closed execute: 0 rm -f /hdd/movie/tmp/20101225161739/source_title_1.m2v /hdd/movie/tmp/20101225161739/source_title_1.mp2 /hdd/movie/tmp/20101225161739/cut_1.Xcl poll: unhandled POLLERR/HUP/NVAL for fd 62(16) child has terminated pipes closed waiting for 1 resident task(s) [8] to finish... poll: unhandled POLLERR/HUP/NVAL for fd 62(16) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 365469 bytes for cache used It's now Sat Dec 25 17:03:38 2010 [timer.py] next activation: 1293293118 (in 99998 ms) It's now Sat Dec 25 17:03:38 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293118 (in 99994 ms) nr_read 98885 nr_write 30597 sum 129482 prev_sum 122309 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 365469 bytes for cache used nr_read 106277 nr_write 37525 sum 143802 prev_sum 129482 hdd was accessed since previous check! [IDLE] 0 600 False [DVDAuthorTask] STAT: fixing VOBU at 3MB (17/10668, 0%) STAT: fixing VOBU at 6MB (33/10668, 0%) STAT: fixing VOBU at 8MB (49/10668, 0%) STAT: fixing VOBU at 10MB (65/10668, 0%) STAT: fixing VOBU at 13MB (81/10668, 0%) STAT: fixing VOBU at 16MB (97/10668, 0%) STAT: fixing VOBU at 18MB (113/10668, 1%) STAT: fixing VOBU at 21MB (129/10668, 1%) STAT: fixing VOBU at 23MB (145/10668, 1%) STAT: fixing VOBU at 26MB (161/10668, 1%) STAT: fixing VOBU at 29MB (177/10668, 1%) STAT: fixing VOBU at 32MB (193/10668, 1%) STAT: fixing VOBU at 35MB (209/10668, 1%) STAT: fixing VOBU at 38MB (225/10668, 2%) STAT: fixing VOBU at 40MB (241/10668, 2%) STAT: fixing VOBU at 43MB (257/10668, 2%) STAT: fixing VOBU at 45MB (273/10668, 2%) STAT: fixing VOBU at 48MB (289/10668, 2%) STAT: fixing VOBU at 51MB (305/10668, 2%) STAT: fixing VOBU at 54MB (321/10668, 2%) STAT: fixing VOBU at 57MB (337/10668, 3%) STAT: fixing VOBU at 59MB (353/10668, 3%) STAT: fixing VOBU at 61MB (369/10668, 3%) STAT: fixing VOBU at 65MB (385/10668, 3%) STAT: fixing VOBU at 67MB (401/10668, 3%) STAT: fixing VOBU at 70MB (417/10668, 3%) STAT: fixing VOBU at 74MB (433/10668, 4%) STAT: fixing VOBU at 77MB (449/10668, 4%) STAT: fixing VOBU at 80MB (465/10668, 4%) STAT: fixing VOBU at 83MB (481/10668, 4%) STAT: fixing VOBU at 86MB (497/10668, 4%) STAT: fixing VOBU at 89MB (513/10668, 4%) STAT: fixing VOBU at 92MB (529/10668, 4%) STAT: fixing VOBU at 96MB (545/10668, 5%) STAT: fixing VOBU at 99MB (561/10668, 5%) STAT: fixing VOBU at 102MB (577/10668, 5%) STAT: fixing VOBU at 107MB (593/10668, 5%) STAT: fixing VOBU at 110MB (609/10668, 5%) STAT: fixing VOBU at 113MB (625/10668, 5%) STAT: fixing VOBU at 116MB (641/10668, 5%) STAT: fixing VOBU at 119MB (657/10668, 6%) STAT: fixing VOBU at 122MB (673/10668, 6%) STAT: fixing VOBU at 125MB (689/10668, 6%) STAT: fixing VOBU at 129MB (705/10668, 6%) STAT: fixing VOBU at 132MB (721/10668, 6%) STAT: fixing VOBU at 134MB (737/10668, 6%) STAT: fixing VOBU at 137MB (753/10668, 7%) STAT: fixing VOBU at 139MB (769/10668, 7%) STAT: fixing VOBU at 142MB (785/10668, 7%) STAT: fixing VOBU at 145MB (801/10668, 7%) STAT: fixing VOBU at 148MB (817/10668, 7%) STAT: fixing VOBU at 150MB (833/10668, 7%) STAT: fixing VOBU at 152MB (849/10668, 7%) STAT: fixing VOBU at 155MB (865/10668, 8%) STAT: fixing VOBU at 158MB (881/10668, 8%) STAT: fixing VOBU at 160MB (897/10668, 8%) STAT: fixing VOBU at 162MB (913/10668, 8%) STAT: fixing VOBU at 165MB (929/10668, 8%) STAT: fixing VOBU at 167MB (945/10668, 8%) STAT: fixing VOBU at 171MB (961/10668, 8%) STAT: fixing VOBU at 174MB (977/10668, 9%) STAT: fixing VOBU at 177MB (993/10668, 9%) STAT: fixing VOBU at 180MB (1009/10668, 9%) STAT: fixing VOBU at 182MB (1025/10668, 9%) STAT: fixing VOBU at 185MB (1041/10668, 9%) STAT: fixing VOBU at 189MB (1057/10668, 9%) STAT: fixing VOBU at 192MB (1073/10668, 10%) STAT: fixing VOBU at 194MB (1089/10668, 10%) STAT: fixing VOBU at 197MB (1105/10668, 10%) STAT: fixing VOBU at 201MB (1121/10668, 10%) STAT: fixing VOBU at 203MB (1137/10668, 10%) STAT: fixing VOBU at 206MB (1153/10668, 10%) STAT: fixing VOBU at 209MB (1169/10668, 10%) STAT: fixing VOBU at 213MB (1185/10668, 11%) STAT: fixing VOBU at 216MB (1201/10668, 11%) STAT: fixing VOBU at 218MB (1217/10668, 11%) STAT: fixing VOBU at 221MB (1233/10668, 11%) STAT: fixing VOBU at 223MB (1249/10668, 11%) STAT: fixing VOBU at 226MB (1265/10668, 11%) STAT: fixing VOBU at 230MB (1281/10668, 11%) STAT: fixing VOBU at 232MB (1297/10668, 12%) STAT: fixing VOBU at 234MB (1313/10668, 12%) STAT: fixing VOBU at 237MB (1329/10668, 12%) STAT: fixing VOBU at 239MB (1345/10668, 12%) STAT: fixing VOBU at 241MB (1361/10668, 12%) STAT: fixing VOBU at 244MB (1377/10668, 12%) STAT: fixing VOBU at 246MB (1393/10668, 13%) STAT: fixing VOBU at 248MB (1409/10668, 13%) STAT: fixing VOBU at 251MB (1425/10668, 13%) STAT: fixing VOBU at 254MB (1441/10668, 13%) STAT: fixing VOBU at 257MB (1457/10668, 13%) STAT: fixing VOBU at 259MB (1473/10668, 13%) STAT: fixing VOBU at 260MB (1489/10668, 13%) STAT: fixing VOBU at 262MB (1505/10668, 14%) STAT: fixing VOBU at 265MB (1521/10668, 14%) STAT: fixing VOBU at 268MB (1537/10668, 14%) STAT: fixing VOBU at 271MB (1553/10668, 14%) STAT: fixing VOBU at 273MB (1569/10668, 14%) STAT: fixing VOBU at 277MB (1585/10668, 14%) STAT: fixing VOBU at 281MB (1601/10668, 14%) STAT: fixing VOBU at 285MB (1617/10668, 15%) STAT: fixing VOBU at 289MB (1633/10668, 15%) STAT: fixing VOBU at 292MB (1649/10668, 15%) STAT: fixing VOBU at 295MB (1665/10668, 15%) STAT: fixing VOBU at 298MB (1681/10668, 15%) STAT: fixing VOBU at 301MB (1697/10668, 15%) STAT: fixing VOBU at 303MB (1713/10668, 16%) STAT: fixing VOBU at 307MB (1729/10668, 16%) STAT: fixing VOBU at 310MB (1745/10668, 16%) STAT: fixing VOBU at 313MB (1761/10668, 16%) STAT: fixing VOBU at 316MB (1777/10668, 16%) STAT: fixing VOBU at 318MB (1793/10668, 16%) STAT: fixing VOBU at 321MB (1809/10668, 16%) STAT: fixing VOBU at 324MB (1825/10668, 17%) STAT: fixing VOBU at 326MB (1841/10668, 17%) STAT: fixing VOBU at 329MB (1857/10668, 17%) STAT: fixing VOBU at 332MB (1873/10668, 17%) STAT: fixing VOBU at 335MB (1889/10668, 17%) STAT: fixing VOBU at 337MB (1905/10668, 17%) STAT: fixing VOBU at 340MB (1921/10668, 17%) STAT: fixing VOBU at 342MB (1937/10668, 18%) STAT: fixing VOBU at 345MB (1953/10668, 18%) STAT: fixing VOBU at 347MB (1969/10668, 18%) STAT: fixing VOBU at 349MB (1985/10668, 18%) STAT: fixing VOBU at 351MB (2001/10668, 18%) STAT: fixing VOBU at 354MB (2017/10668, 18%) STAT: fixing VOBU at 356MB (2033/10668, 19%) STAT: fixing VOBU at 358MB (2049/10668, 19%) STAT: fixing VOBU at 360MB (2065/10668, 19%) STAT: fixing VOBU at 363MB (2081/10668, 19%) STAT: fixing VOBU at 366MB (2097/10668, 19%) STAT: fixing VOBU at 369MB (2113/10668, 19%) STAT: fixing VOBU at 372MB (2129/10668, 19%) STAT: fixing VOBU at 375MB (2145/10668, 20%) STAT: fixing VOBU at 378MB (2161/10668, 20%) STAT: fixing VOBU at 381MB (2177/10668, 20%) STAT: fixing VOBU at 384MB (2193/10668, 20%) STAT: fixing VOBU at 387MB (2209/10668, 20%) STAT: fixing VOBU at 390MB (2225/10668, 20%) STAT: fixing VOBU at 393MB (2241/10668, 20%) STAT: fixing VOBU at 396MB (2257/10668, 21%) STAT: fixing VOBU at 399MB (2273/10668, 21%) STAT: fixing VOBU at 402MB (2289/10668, 21%) STAT: fixing VOBU at 405MB (2305/10668, 21%) STAT: fixing VOBU at 407MB (2321/10668, 21%) STAT: fixing VOBU at 410MB (2337/10668, 21%) STAT: fixing VOBU at 413MB (2353/10668, 22%) STAT: fixing VOBU at 416MB (2369/10668, 22%) STAT: fixing VOBU at 419MB (2385/10668, 22%) STAT: fixing VOBU at 422MB (2401/10668, 22%) STAT: fixing VOBU at 426MB (2417/10668, 22%) STAT: fixing VOBU at 429MB (2433/10668, 22%) STAT: fixing VOBU at 432MB (2449/10668, 22%) STAT: fixing VOBU at 435MB (2465/10668, 23%) STAT: fixing VOBU at 437MB (2481/10668, 23%) STAT: fixing VOBU at 440MB (2497/10668, 23%) STAT: fixing VOBU at 442MB (2513/10668, 23%) STAT: fixing VOBU at 444MB (2529/10668, 23%) STAT: fixing VOBU at 447MB (2545/10668, 23%) STAT: fixing VOBU at 450MB (2561/10668, 23%) STAT: fixing VOBU at 453MB (2577/10668, 24%) STAT: fixing VOBU at 457MB (2593/10668, 24%) STAT: fixing VOBU at 460MB (2609/10668, 24%) STAT: fixing VOBU at 464MB (2625/10668, 24%) STAT: fixing VOBU at 467MB (2641/10668, 24%) STAT: fixing VOBU at 470MB (2657/10668, 24%) STAT: fixing VOBU at 473MB (2673/10668, 25%) STAT: fixing VOBU at 477MB (2689/10668, 25%) STAT: fixing VOBU at 480MB (2705/10668, 25%) STAT: fixing VOBU at 482MB (2721/10668, 25%) STAT: fixing VOBU at 484MB (2737/10668, 25%) STAT: fixing VOBU at 487MB (2753/10668, 25%) STAT: fixing VOBU at 488MB (2769/10668, 25%) STAT: fixing VOBU at 491MB (2785/10668, 26%) STAT: fixing VOBU at 494MB (2801/10668, 26%) STAT: fixing VOBU at 497MB (2817/10668, 26%) STAT: fixing VOBU at 502MB (2833/10668, 26%) STAT: fixing VOBU at 504MB (2849/10668, 26%) STAT: fixing VOBU at 508MB (2865/10668, 26%) STAT: fixing VOBU at 510MB (2881/10668, 26%) STAT: fixing VOBU at 513MB (2897/10668, 27%) STAT: fixing VOBU at 516MB (2913/10668, 27%) STAT: fixing VOBU at 520MB (2929/10668, 27%) STAT: fixing VOBU at 523MB (2945/10668, 27%) STAT: fixing VOBU at 526MB (2961/10668, 27%) STAT: fixing VOBU at 529MB (2977/10668, 27%) STAT: fixing VOBU at 531MB (2993/10668, 28%) STAT: fixing VOBU at 533MB (3009/10668, 28%) STAT: fixing VOBU at 537MB (3025/10668, 28%) STAT: fixing VOBU at 540MB (3041/10668, 28%) STAT: fixing VOBU at 543MB (3057/10668, 28%) STAT: fixing VOBU at 546MB (3073/10668, 28%) STAT: fixing VOBU at 548MB (3089/10668, 28%) STAT: fixing VOBU at 550MB (3105/10668, 29%) STAT: fixing VOBU at 552MB (3121/10668, 29%) STAT: fixing VOBU at 555MB (3137/10668, 29%) STAT: fixing VOBU at 557MB (3153/10668, 29%) STAT: fixing VOBU at 559MB (3169/10668, 29%) STAT: fixing VOBU at 562MB (3185/10668, 29%) STAT: fixing VOBU at 566MB (3201/10668, 29%) STAT: fixing VOBU at 569MB (3217/10668, 30%) STAT: fixing VOBU at 571MB (3233/10668, 30%) STAT: fixing VOBU at 573MB (3249/10668, 30%) STAT: fixing VOBU at 575MB (3265/10668, 30%) STAT: fixing VOBU at 577MB (3281/10668, 30%) STAT: fixing VOBU at 580MB (3297/10668, 30%) STAT: fixing VOBU at 583MB (3313/10668, 31%) STAT: fixing VOBU at 587MB (3329/10668, 31%) STAT: fixing VOBU at 590MB (3345/10668, 31%) STAT: fixing VOBU at 594MB (3361/10668, 31%) STAT: fixing VOBU at 598MB (3377/10668, 31%) STAT: fixing VOBU at 601MB (3393/10668, 31%) STAT: fixing VOBU at 605MB (3409/10668, 31%) STAT: fixing VOBU at 608MB (3425/10668, 32%) STAT: fixing VOBU at 611MB (3441/10668, 32%) STAT: fixing VOBU at 615MB (3457/10668, 32%) STAT: fixing VOBU at 619MB (3473/10668, 32%) STAT: fixing VOBU at 622MB (3489/10668, 32%) STAT: fixing VOBU at 625MB (3505/10668, 32%) STAT: fixing VOBU at 629MB (3521/10668, 32%) STAT: fixing VOBU at 632MB (3537/10668, 33%) STAT: fixing VOBU at 635MB (3553/10668, 33%) STAT: fixing VOBU at 638MB (3569/10668, 33%) STAT: fixing VOBU at 642MB (3585/10668, 33%) STAT: fixing VOBU at 645MB (3601/10668, 33%) STAT: fixing VOBU at 649MB (3617/10668, 33%) STAT: fixing VOBU at 652MB (3633/10668, 34%) STAT: fixing VOBU at 655MB (3649/10668, 34%) STAT: fixing VOBU at 658MB (3665/10668, 34%) STAT: fixing VOBU at 661MB (3681/10668, 34%) STAT: fixing VOBU at 664MB (3697/10668, 34%) STAT: fixing VOBU at 666MB (3713/10668, 34%) STAT: fixing VOBU at 669MB (3729/10668, 34%) STAT: fixing VOBU at 671MB (3745/10668, 35%) STAT: fixing VOBU at 674MB (3761/10668, 35%) STAT: fixing VOBU at 677MB (3777/10668, 35%) STAT: fixing VOBU at 679MB (3793/10668, 35%) STAT: fixing VOBU at 682MB (3809/10668, 35%) STAT: fixing VOBU at 684MB (3825/10668, 35%) STAT: fixing VOBU at 687MB (3841/10668, 35%) STAT: fixing VOBU at 690MB (3857/10668, 36%) STAT: fixing VOBU at 692MB (3873/10668, 36%) STAT: fixing VOBU at 694MB (3889/10668, 36%) STAT: fixing VOBU at 697MB (3905/10668, 36%) STAT: fixing VOBU at 699MB (3921/10668, 36%) STAT: fixing VOBU at 701MB (3937/10668, 36%) STAT: fixing VOBU at 703MB (3953/10668, 37%) STAT: fixing VOBU at 706MB (3969/10668, 37%) STAT: fixing VOBU at 708MB (3985/10668, 37%) STAT: fixing VOBU at 710MB (4001/10668, 37%) STAT: fixing VOBU at 713MB (4017/10668, 37%) STAT: fixing VOBU at 715MB (4033/10668, 37%) STAT: fixing VOBU at 718MB (4049/10668, 37%) STAT: fixing VOBU at 721MB (4065/10668, 38%) STAT: fixing VOBU at 724MB (4081/10668, 38%) STAT: fixing VOBU at 726MB (4097/10668, 38%) STAT: fixing VOBU at 728MB (4113/10668, 38%) STAT: fixing VOBU at 732MB (4129/10668, 38%) STAT: fixing VOBU at 734MB (4145/10668, 38%) STAT: fixing VOBU at 737MB (4161/10668, 38%) STAT: fixing VOBU at 739MB (4177/10668, 39%) STAT: fixing VOBU at 743MB (4193/10668, 39%) STAT: fixing VOBU at 745MB (4209/10668, 39%) STAT: fixing VOBU at 747MB (4225/10668, 39%) STAT: fixing VOBU at 750MB (4241/10668, 39%) STAT: fixing VOBU at 752MB (4257/10668, 39%) STAT: fixing VOBU at 754MB (4273/10668, 40%) STAT: fixing VOBU at 757MB (4289/10668, 40%) STAT: fixing VOBU at 759MB (4305/10668, 40%) STAT: fixing VOBU at 760MB (4321/10668, 40%) STAT: fixing VOBU at 762MB (4337/10668, 40%) STAT: fixing VOBU at 764MB (4353/10668, 40%) STAT: fixing VOBU at 766MB (4369/10668, 40%) STAT: fixing VOBU at 769MB (4385/10668, 41%) STAT: fixing VOBU at 772MB (4401/10668, 41%) STAT: fixing VOBU at 775MB (4417/10668, 41%) STAT: fixing VOBU at 777MB (4433/10668, 41%) STAT: fixing VOBU at 779MB (4449/10668, 41%) STAT: fixing VOBU at 781MB (4465/10668, 41%) STAT: fixing VOBU at 783MB (4481/10668, 41%) STAT: fixing VOBU at 785MB (4497/10668, 42%) STAT: fixing VOBU at 788MB (4513/10668, 42%) STAT: fixing VOBU at 790MB (4529/10668, 42%) STAT: fixing VOBU at 792MB (4545/10668, 42%) STAT: fixing VOBU at 795MB (4561/10668, 42%) STAT: fixing VOBU at 797MB (4577/10668, 42%) STAT: fixing VOBU at 800MB (4593/10668, 43%) STAT: fixing VOBU at 802MB (4609/10668, 43%) STAT: fixing VOBU at 804MB (4625/10668, 43%) STAT: fixing VOBU at 806MB (4641/10668, 43%) STAT: fixing VOBU at 809MB (4657/10668, 43%) STAT: fixing VOBU at 812MB (4673/10668, 43%) STAT: fixing VOBU at 815MB (4689/10668, 43%) STAT: fixing VOBU at 817MB (4705/10668, 44%) STAT: fixing VOBU at 821MB (4721/10668, 44%) STAT: fixing VOBU at 824MB (4737/10668, 44%) STAT: fixing VOBU at 826MB (4753/10668, 44%) STAT: fixing VOBU at 829MB (4769/10668, 44%) STAT: fixing VOBU at 833MB (4785/10668, 44%) STAT: fixing VOBU at 836MB (4801/10668, 44%) STAT: fixing VOBU at 838MB (4817/10668, 45%) STAT: fixing VOBU at 841MB (4833/10668, 45%) STAT: fixing VOBU at 844MB (4849/10668, 45%) STAT: fixing VOBU at 846MB (4865/10668, 45%) STAT: fixing VOBU at 848MB (4881/10668, 45%) STAT: fixing VOBU at 851MB (4897/10668, 45%) STAT: fixing VOBU at 854MB (4913/10668, 46%) STAT: fixing VOBU at 857MB (4929/10668, 46%) STAT: fixing VOBU at 859MB (4945/10668, 46%) STAT: fixing VOBU at 862MB (4961/10668, 46%) STAT: fixing VOBU at 866MB (4977/10668, 46%) STAT: fixing VOBU at 869MB (4993/10668, 46%) STAT: fixing VOBU at 872MB (5009/10668, 46%) STAT: fixing VOBU at 875MB (5025/10668, 47%) STAT: fixing VOBU at 877MB (5041/10668, 47%) STAT: fixing VOBU at 879MB (5057/10668, 47%) STAT: fixing VOBU at 881MB (5073/10668, 47%) STAT: fixing VOBU at 884MB (5089/10668, 47%) STAT: fixing VOBU at 886MB (5105/10668, 47%) STAT: fixing VOBU at 889MB (5121/10668, 47%) STAT: fixing VOBU at 891MB (5137/10668, 48%) STAT: fixing VOBU at 893MB (5153/10668, 48%) STAT: fixing VOBU at 896MB (5169/10668, 48%) STAT: fixing VOBU at 898MB (5185/10668, 48%) STAT: fixing VOBU at 902MB (5201/10668, 48%) STAT: fixing VOBU at 905MB (5217/10668, 48%) STAT: fixing VOBU at 910MB (5233/10668, 49%) STAT: fixing VOBU at 913MB (5249/10668, 49%) STAT: fixing VOBU at 916MB (5265/10668, 49%) STAT: fixing VOBU at 920MB (5281/10668, 49%) STAT: fixing VOBU at 923MB (5297/10668, 49%) STAT: fixing VOBU at 925MB (5313/10668, 49%) STAT: fixing VOBU at 927MB (5329/10668, 49%) STAT: fixing VOBU at 931MB (5345/10668, 50%) STAT: fixing VOBU at 933MB (5361/10668, 50%) STAT: fixing VOBU at 936MB (5377/10668, 50%) STAT: fixing VOBU at 939MB (5393/10668, 50%) STAT: fixing VOBU at 943MB (5409/10668, 50%) STAT: fixing VOBU at 948MB (5425/10668, 50%) STAT: fixing VOBU at 950MB (5441/10668, 50%) STAT: fixing VOBU at 954MB (5457/10668, 51%) STAT: fixing VOBU at 957MB (5473/10668, 51%) STAT: fixing VOBU at 962MB (5489/10668, 51%) STAT: fixing VOBU at 966MB (5505/10668, 51%) STAT: fixing VOBU at 970MB (5521/10668, 51%) STAT: fixing VOBU at 975MB (5537/10668, 51%) STAT: fixing VOBU at 979MB (5553/10668, 52%) STAT: fixing VOBU at 982MB (5569/10668, 52%) STAT: fixing VOBU at 986MB (5585/10668, 52%) STAT: fixing VOBU at 988MB (5601/10668, 52%) STAT: fixing VOBU at 992MB (5617/10668, 52%) STAT: fixing VOBU at 995MB (5633/10668, 52%) STAT: fixing VOBU at 999MB (5649/10668, 52%) STAT: fixing VOBU at 1002MB (5665/10668, 53%) STAT: fixing VOBU at 1006MB (5681/10668, 53%) STAT: fixing VOBU at 1009MB (5697/10668, 53%) STAT: fixing VOBU at 1012MB (5713/10668, 53%) STAT: fixing VOBU at 1014MB (5729/10668, 53%) STAT: fixing VOBU at 1016MB (5745/10668, 53%) STAT: fixing VOBU at 1019MB (5761/10668, 53%) STAT: fixing VOBU at 1021MB (5777/10668, 54%) STAT: fixing VOBU at 1023MB (5793/10668, 54%) STAT: fixing VOBU at 1025MB (5809/10668, 54%) STAT: fixing VOBU at 1027MB (5825/10668, 54%) STAT: fixing VOBU at 1029MB (5841/10668, 54%) STAT: fixing VOBU at 1032MB (5857/10668, 54%) STAT: fixing VOBU at 1034MB (5873/10668, 55%) STAT: fixing VOBU at 1037MB (5889/10668, 55%) STAT: fixing VOBU at 1039MB (5905/10668, 55%) STAT: fixing VOBU at 1041MB (5921/10668, 55%) STAT: fixing VOBU at 1044MB (5937/10668, 55%) STAT: fixing VOBU at 1046MB (5953/10668, 55%) STAT: fixing VOBU at 1048MB (5969/10668, 55%) STAT: fixing VOBU at 1050MB (5985/10668, 56%) STAT: fixing VOBU at 1052MB (6001/10668, 56%) STAT: fixing VOBU at 1054MB (6017/10668, 56%) STAT: fixing VOBU at 1056MB (6033/10668, 56%) STAT: fixing VOBU at 1058MB (6049/10668, 56%) STAT: fixing VOBU at 1061MB (6065/10668, 56%) STAT: fixing VOBU at 1063MB (6081/10668, 56%) STAT: fixing VOBU at 1065MB (6097/10668, 57%) STAT: fixing VOBU at 1066MB (6113/10668, 57%) STAT: fixing VOBU at 1069MB (6129/10668, 57%) STAT: fixing VOBU at 1071MB (6145/10668, 57%) STAT: fixing VOBU at 1074MB (6161/10668, 57%) STAT: fixing VOBU at 1076MB (6177/10668, 57%) STAT: fixing VOBU at 1079MB (6193/10668, 58%) STAT: fixing VOBU at 1082MB (6209/10668, 58%) STAT: fixing VOBU at 1084MB (6225/10668, 58%) STAT: fixing VOBU at 1087MB (6241/10668, 58%) STAT: fixing VOBU at 1089MB (6257/10668, 58%) STAT: fixing VOBU at 1092MB (6273/10668, 58%) STAT: fixing VOBU at 1095MB (6289/10668, 58%) STAT: fixing VOBU at 1097MB (6305/10668, 59%) STAT: fixing VOBU at 1100MB (6321/10668, 59%) STAT: fixing VOBU at 1102MB (6337/10668, 59%) STAT: fixing VOBU at 1105MB (6353/10668, 59%) STAT: fixing VOBU at 1107MB (6369/10668, 59%) STAT: fixing VOBU at 1110MB (6385/10668, 59%) STAT: fixing VOBU at 1112MB (6401/10668, 59%) STAT: fixing VOBU at 1114MB (6417/10668, 60%) STAT: fixing VOBU at 1116MB (6433/10668, 60%) STAT: fixing VOBU at 1119MB (6449/10668, 60%) STAT: fixing VOBU at 1122MB (6465/10668, 60%) STAT: fixing VOBU at 1125MB (6481/10668, 60%) STAT: fixing VOBU at 1127MB (6497/10668, 60%) STAT: fixing VOBU at 1130MB (6513/10668, 61%) STAT: fixing VOBU at 1132MB (6529/10668, 61%) STAT: fixing VOBU at 1134MB (6545/10668, 61%) STAT: fixing VOBU at 1136MB (6561/10668, 61%) STAT: fixing VOBU at 1139MB (6577/10668, 61%) STAT: fixing VOBU at 1142MB (6593/10668, 61%) STAT: fixing VOBU at 1145MB (6609/10668, 61%) STAT: fixing VOBU at 1148MB (6625/10668, 62%) STAT: fixing VOBU at 1150MB (6641/10668, 62%) STAT: fixing VOBU at 1153MB (6657/10668, 62%) STAT: fixing VOBU at 1156MB (6673/10668, 62%) STAT: fixing VOBU at 1159MB (6689/10668, 62%) STAT: fixing VOBU at 1162MB (6705/10668, 62%) STAT: fixing VOBU at 1166MB (6721/10668, 62%) STAT: fixing VOBU at 1169MB (6737/10668, 63%) STAT: fixing VOBU at 1172MB (6753/10668, 63%) STAT: fixing VOBU at 1174MB (6769/10668, 63%) STAT: fixing VOBU at 1177MB (6785/10668, 63%) STAT: fixing VOBU at 1180MB (6801/10668, 63%) STAT: fixing VOBU at 1184MB (6817/10668, 63%) STAT: fixing VOBU at 1187MB (6833/10668, 64%) STAT: fixing VOBU at 1191MB (6849/10668, 64%) STAT: fixing VOBU at 1195MB (6865/10668, 64%) STAT: fixing VOBU at 1199MB (6881/10668, 64%) STAT: fixing VOBU at 1202MB (6897/10668, 64%) STAT: fixing VOBU at 1206MB (6913/10668, 64%) STAT: fixing VOBU at 1209MB (6929/10668, 64%) STAT: fixing VOBU at 1213MB (6945/10668, 65%) STAT: fixing VOBU at 1217MB (6961/10668, 65%) STAT: fixing VOBU at 1221MB (6977/10668, 65%) STAT: fixing VOBU at 1225MB (6993/10668, 65%) STAT: fixing VOBU at 1229MB (7009/10668, 65%) STAT: fixing VOBU at 1232MB (7025/10668, 65%) STAT: fixing VOBU at 1236MB (7041/10668, 65%) STAT: fixing VOBU at 1239MB (7057/10668, 66%) STAT: fixing VOBU at 1242MB (7073/10668, 66%) STAT: fixing VOBU at 1246MB (7089/10668, 66%) STAT: fixing VOBU at 1249MB (7105/10668, 66%) STAT: fixing VOBU at 1254MB (7121/10668, 66%) STAT: fixing VOBU at 1257MB (7137/10668, 66%) STAT: fixing VOBU at 1261MB (7153/10668, 67%) STAT: fixing VOBU at 1264MB (7169/10668, 67%) STAT: fixing VOBU at 1268MB (7185/10668, 67%) STAT: fixing VOBU at 1272MB (7201/10668, 67%) STAT: fixing VOBU at 1275MB (7217/10668, 67%) STAT: fixing VOBU at 1279MB (7233/10668, 67%) STAT: fixing VOBU at 1282MB (7249/10668, 67%) STAT: fixing VOBU at 1287MB (7265/10668, 68%) STAT: fixing VOBU at 1291MB (7281/10668, 68%) STAT: fixing VOBU at 1294MB (7297/10668, 68%) STAT: fixing VOBU at 1298MB (7313/10668, 68%) STAT: fixing VOBU at 1302MB (7329/10668, 68%) STAT: fixing VOBU at 1305MB (7345/10668, 68%) STAT: fixing VOBU at 1308MB (7361/10668, 68%) STAT: fixing VOBU at 1311MB (7377/10668, 69%) STAT: fixing VOBU at 1313MB (7393/10668, 69%) STAT: fixing VOBU at 1315MB (7409/10668, 69%) STAT: fixing VOBU at 1318MB (7425/10668, 69%) STAT: fixing VOBU at 1321MB (7441/10668, 69%) STAT: fixing VOBU at 1324MB (7457/10668, 69%) STAT: fixing VOBU at 1326MB (7473/10668, 70%) STAT: fixing VOBU at 1328MB (7489/10668, 70%) STAT: fixing VOBU at 1332MB (7505/10668, 70%) STAT: fixing VOBU at 1336MB (7521/10668, 70%) STAT: fixing VOBU at 1339MB (7537/10668, 70%) STAT: fixing VOBU at 1343MB (7553/10668, 70%) STAT: fixing VOBU at 1347MB (7569/10668, 70%) STAT: fixing VOBU at 1350MB (7585/10668, 71%) STAT: fixing VOBU at 1354MB (7601/10668, 71%) STAT: fixing VOBU at 1358MB (7617/10668, 71%) STAT: fixing VOBU at 1361MB (7633/10668, 71%) STAT: fixing VOBU at 1364MB (7649/10668, 71%) STAT: fixing VOBU at 1366MB (7665/10668, 71%) STAT: fixing VOBU at 1369MB (7681/10668, 71%) STAT: fixing VOBU at 1372MB (7697/10668, 72%) STAT: fixing VOBU at 1375MB (7713/10668, 72%) STAT: fixing VOBU at 1378MB (7729/10668, 72%) STAT: fixing VOBU at 1381MB (7745/10668, 72%) STAT: fixing VOBU at 1383MB (7761/10668, 72%) STAT: fixing VOBU at 1386MB (7777/10668, 72%) STAT: fixing VOBU at 1389MB (7793/10668, 73%) STAT: fixing VOBU at 1391MB (7809/10668, 73%) STAT: fixing VOBU at 1394MB (7825/10668, 73%) STAT: fixing VOBU at 1395MB (7841/10668, 73%) STAT: fixing VOBU at 1397MB (7857/10668, 73%) STAT: fixing VOBU at 1399MB (7873/10668, 73%) STAT: fixing VOBU at 1400MB (7889/10668, 73%) STAT: fixing VOBU at 1402MB (7905/10668, 74%) STAT: fixing VOBU at 1406MB (7921/10668, 74%) STAT: fixing VOBU at 1410MB (7937/10668, 74%) STAT: fixing VOBU at 1413MB (7953/10668, 74%) STAT: fixing VOBU at 1417MB (7969/10668, 74%) STAT: fixing VOBU at 1421MB (7985/10668, 74%) STAT: fixing VOBU at 1425MB (8001/10668, 74%) STAT: fixing VOBU at 1430MB (8017/10668, 75%) STAT: fixing VOBU at 1434MB (8033/10668, 75%) STAT: fixing VOBU at 1437MB (8049/10668, 75%) STAT: fixing VOBU at 1439MB (8065/10668, 75%) STAT: fixing VOBU at 1441MB (8081/10668, 75%) STAT: fixing VOBU at 1443MB (8097/10668, 75%) STAT: fixing VOBU at 1445MB (8113/10668, 76%) STAT: fixing VOBU at 1448MB (8129/10668, 76%) STAT: fixing VOBU at 1451MB (8145/10668, 76%) STAT: fixing VOBU at 1454MB (8161/10668, 76%) STAT: fixing VOBU at 1457MB (8177/10668, 76%) STAT: fixing VOBU at 1460MB (8193/10668, 76%) STAT: fixing VOBU at 1463MB (8209/10668, 76%) STAT: fixing VOBU at 1466MB (8225/10668, 77%) STAT: fixing VOBU at 1469MB (8241/10668, 77%) STAT: fixing VOBU at 1473MB (8257/10668, 77%) STAT: fixing VOBU at 1477MB (8273/10668, 77%) STAT: fixing VOBU at 1481MB (8289/10668, 77%) STAT: fixing VOBU at 1484MB (8305/10668, 77%) STAT: fixing VOBU at 1487MB (8321/10668, 77%) STAT: fixing VOBU at 1491MB (8337/10668, 78%) STAT: fixing VOBU at 1495MB (8353/10668, 78%) STAT: fixing VOBU at 1499MB (8369/10668, 78%) STAT: fixing VOBU at 1504MB (8385/10668, 78%) STAT: fixing VOBU at 1508MB (8401/10668, 78%) STAT: fixing VOBU at 1513MB (8417/10668, 78%) STAT: fixing VOBU at 1516MB (8433/10668, 79%) STAT: fixing VOBU at 1520MB (8449/10668, 79%) STAT: fixing VOBU at 1523MB (8465/10668, 79%) STAT: fixing VOBU at 1527MB (8481/10668, 79%) STAT: fixing VOBU at 1532MB (8497/10668, 79%) STAT: fixing VOBU at 1535MB (8513/10668, 79%) STAT: fixing VOBU at 1538MB (8529/10668, 79%) STAT: fixing VOBU at 1542MB (8545/10668, 80%) STAT: fixing VOBU at 1545MB (8561/10668, 80%) STAT: fixing VOBU at 1549MB (8577/10668, 80%) STAT: fixing VOBU at 1553MB (8593/10668, 80%) STAT: fixing VOBU at 1557MB (8609/10668, 80%) STAT: fixing VOBU at 1560MB (8625/10668, 80%) STAT: fixing VOBU at 1564MB (8641/10668, 80%) STAT: fixing VOBU at 1568MB (8657/10668, 81%) STAT: fixing VOBU at 1571MB (8673/10668, 81%) STAT: fixing VOBU at 1576MB (8689/10668, 81%) STAT: fixing VOBU at 1580MB (8705/10668, 81%) STAT: fixing VOBU at 1583MB (8721/10668, 81%) STAT: fixing VOBU at 1587MB (8737/10668, 81%) STAT: fixing VOBU at 1591MB (8753/10668, 82%) STAT: fixing VOBU at 1594MB (8769/10668, 82%) STAT: fixing VOBU at 1598MB (8785/10668, 82%) STAT: fixing VOBU at 1601MB (8801/10668, 82%) STAT: fixing VOBU at 1605MB (8817/10668, 82%) STAT: fixing VOBU at 1609MB (8833/10668, 82%) STAT: fixing VOBU at 1612MB (8849/10668, 82%) STAT: fixing VOBU at 1616MB (8865/10668, 83%) STAT: fixing VOBU at 1619MB (8881/10668, 83%) STAT: fixing VOBU at 1622MB (8897/10668, 83%) STAT: fixing VOBU at 1625MB (8913/10668, 83%) STAT: fixing VOBU at 1628MB (8929/10668, 83%) STAT: fixing VOBU at 1631MB (8945/10668, 83%) STAT: fixing VOBU at 1633MB (8961/10668, 83%) STAT: fixing VOBU at 1635MB (8977/10668, 84%) STAT: fixing VOBU at 1637MB (8993/10668, 84%) STAT: fixing VOBU at 1641MB (9009/10668, 84%) STAT: fixing VOBU at 1645MB (9025/10668, 84%) STAT: fixing VOBU at 1648MB (9041/10668, 84%) STAT: fixing VOBU at 1651MB (9057/10668, 84%) STAT: fixing VOBU at 1655MB (9073/10668, 85%) STAT: fixing VOBU at 1659MB (9089/10668, 85%) STAT: fixing VOBU at 1663MB (9105/10668, 85%) STAT: fixing VOBU at 1668MB (9121/10668, 85%) STAT: fixing VOBU at 1671MB (9137/10668, 85%) STAT: fixing VOBU at 1675MB (9153/10668, 85%) STAT: fixing VOBU at 1678MB (9169/10668, 85%) STAT: fixing VOBU at 1682MB (9185/10668, 86%) STAT: fixing VOBU at 1685MB (9201/10668, 86%) STAT: fixing VOBU at 1688MB (9217/10668, 86%) STAT: fixing VOBU at 1690MB (9233/10668, 86%) STAT: fixing VOBU at 1693MB (9249/10668, 86%) STAT: fixing VOBU at 1696MB (9265/10668, 86%) STAT: fixing VOBU at 1700MB (9281/10668, 86%) STAT: fixing VOBU at 1702MB (9297/10668, 87%) STAT: fixing VOBU at 1705MB (9313/10668, 87%) STAT: fixing VOBU at 1709MB (9329/10668, 87%) STAT: fixing VOBU at 1712MB (9345/10668, 87%) STAT: fixing VOBU at 1715MB (9361/10668, 87%) STAT: fixing VOBU at 1718MB (9377/10668, 87%) STAT: fixing VOBU at 1722MB (9393/10668, 88%) STAT: fixing VOBU at 1726MB (9409/10668, 88%) STAT: fixing VOBU at 1729MB (9425/10668, 88%) STAT: fixing VOBU at 1732MB (9441/10668, 88%) STAT: fixing VOBU at 1735MB (9457/10668, 88%) STAT: fixing VOBU at 1739MB (9473/10668, 88%) STAT: fixing VOBU at 1743MB (9489/10668, 88%) STAT: fixing VOBU at 1747MB (9505/10668, 89%) STAT: fixing VOBU at 1750MB (9521/10668, 89%) STAT: fixing VOBU at 1753MB (9537/10668, 89%) STAT: fixing VOBU at 1757MB (9553/10668, 89%) STAT: fixing VOBU at 1761MB (9569/10668, 89%) STAT: fixing VOBU at 1765MB (9585/10668, 89%) STAT: fixing VOBU at 1769MB (9601/10668, 89%) STAT: fixing VOBU at 1773MB (9617/10668, 90%) STAT: fixing VOBU at 1777MB (9633/10668, 90%) STAT: fixing VOBU at 1781MB (9649/10668, 90%) STAT: fixing VOBU at 1785MB (9665/10668, 90%) STAT: fixing VOBU at 1788MB (9681/10668, 90%) STAT: fixing VOBU at 1791MB (9697/10668, 90%) STAT: fixing VOBU at 1794MB (9713/10668, 91%) STAT: fixing VOBU at 1798MB (9729/10668, 91%) STAT: fixing VOBU at 1803MB (9745/10668, 91%) STAT: fixing VOBU at 1806MB (9761/10668, 91%) STAT: fixing VOBU at 1809MB (9777/10668, 91%) STAT: fixing VOBU at 1814MB (9793/10668, 91%) STAT: fixing VOBU at 1818MB (9809/10668, 91%) STAT: fixing VOBU at 1823MB (9825/10668, 92%) STAT: fixing VOBU at 1828MB (9841/10668, 92%) STAT: fixing VOBU at 1832MB (9857/10668, 92%) STAT: fixing VOBU at 1836MB (9873/10668, 92%) STAT: fixing VOBU at 1839MB (9889/10668, 92%) STAT: fixing VOBU at 1842MB (9905/10668, 92%) STAT: fixing VOBU at 1846MB (9921/10668, 92%) STAT: fixing VOBU at 1849MB (9937/10668, 93%) STAT: fixing VOBU at 1853MB (9953/10668, 93%) STAT: fixing VOBU at 1857MB (9969/10668, 93%) STAT: fixing VOBU at 1861MB (9985/10668, 93%) STAT: fixing VOBU at 1864MB (10001/10668, 93%) STAT: fixing VOBU at 1867MB (10017/10668, 93%) STAT: fixing VOBU at 1871MB (10033/10668, 94%) STAT: fixing VOBU at 1875MB (10049/10668, 94%) STAT: fixing VOBU at 1879MB (10065/10668, 94%) STAT: fixing VOBU at 1882MB (10081/10668, 94%) STAT: fixing VOBU at 1885MB (10097/10668, 94%) STAT: fixing VOBU at 1888MB (10113/10668, 94%) STAT: fixing VOBU at 1891MB (10129/10668, 94%) STAT: fixing VOBU at 1895MB (10145/10668, 95%) STAT: fixing VOBU at 1897MB (10161/10668, 95%) STAT: fixing VOBU at 1900MB (10177/10668, 95%) STAT: fixing VOBU at 1902MB (10193/10668, 95%) STAT: fixing VOBU at 1904MB (10209/10668, 95%) STAT: fixing VOBU at 1906MB (10225/10668, 95%) STAT: fixing VOBU at 1909MB (10241/10668, 95%) STAT: fixing VOBU at 1913MB (10257/10668, 96%) STAT: fixing VOBU at 1917MB (10273/10668, 96%) STAT: fixing VOBU at 1921MB (10289/10668, 96%) STAT: fixing VOBU at 1924MB (10305/10668, 96%) STAT: fixing VOBU at 1927MB (10321/10668, 96%) STAT: fixing VOBU at 1930MB (10337/10668, 96%) STAT: fixing VOBU at 1933MB (10353/10668, 97%) STAT: fixing VOBU at 1938MB (10369/10668, 97%) STAT: fixing VOBU at 1942MB (10385/10668, 97%) STAT: fixing VOBU at 1947MB (10401/10668, 97%) STAT: fixing VOBU at 1951MB (10417/10668, 97%) STAT: fixing VOBU at 1956MB (10433/10668, 97%) STAT: fixing VOBU at 1960MB (10449/10668, 97%) STAT: fixing VOBU at 1965MB (10465/10668, 98%) STAT: fixing VOBU at 1968MB (10481/10668, 98%) STAT: fixing VOBU at 1972MB (10497/10668, 98%) STAT: fixing VOBU at 1976MB (10513/10668, 98%) STAT: fixing VOBU at 1979MB (10529/10668, 98%) STAT: fixing VOBU at 1984MB (10545/10668, 98%) STAT: fixing VOBU at 1988MB (10561/10668, 98%) STAT: fixing VOBU at 1991MB (10577/10668, 99%) STAT: fixing VOBU at 1996MB (10593/10668, 99%) STAT: fixing VOBU at 2000MB (10609/10668, 99%) STAT: fixing VOBU at 2004MB (10625/10668, 99%) STAT: fixing VOBU at 2008MB (10641/10668, 99%) STAT: fixing VOBU at 2012MB (10657/10668, 99%) STAT: fixed 10668 VOBUS [DVDAuthorTask] INFO: dvdauthor creating table of contents [DVDAuthorTask] INFO: Scanning /hdd/movie/tmp/20101225161739/dvd/VIDEO_TS/VTS_01_0.IFO [DVDAuthorTask] INFO: Creating menu for TOC [DVDAuthorTask] [DVDAuthorTask] STAT: Processing /hdd/movie/tmp/20101225161739/dvdmenu1.mpg... task keeps staying resident: [DVDAuthorTask] WARN: Discontinuity of 11755 in audio channel 8; please remultiplex input. [DVDAuthorTask] WARN: Previous sector: 0.120 - 0.120 [DVDAuthorTask] WARN: Current sector: 0.250 - 0.250 [DVDAuthorTask] WARN: Discontinuity of 11755 in audio channel 8; please remultiplex input. [DVDAuthorTask] WARN: Previous sector: 0.250 - 0.250 [DVDAuthorTask] WARN: Current sector: 0.381 - 0.381 [DVDAuthorTask] WARN: Discontinuity of 11755 in audio channel 8; please remultiplex input. [DVDAuthorTask] WARN: Previous sector: 0.381 - 0.381 [DVDAuthorTask] WARN: Current sector: 0.511 - 0.511 [DVDAuthorTask] [DVDAuthorTask] INFO: Video pts = 0.120 .. 0.160 [DVDAuthorTask] INFO: Audio[8] pts = 0.120 .. 0.511 [DVDAuthorTask] INFO: Audio[32] pts = 0.120 .. 0.120 [DVDAuthorTask] STAT: VOBU 1 at 0MB, 1 PGCS [DVDAuthorTask] [DVDAuthorTask] INFO: Generating VMGM with the following video attributes: [DVDAuthorTask] INFO: MPEG version: mpeg2 [DVDAuthorTask] INFO: TV standard: pal [DVDAuthorTask] INFO: Aspect ratio: 4:3 [DVDAuthorTask] INFO: Resolution: 720x576 [DVDAuthorTask] INFO: Audio ch 0 format: mp2/2ch, 48khz 20bps [DVDAuthorTask] [DVDAuthorTask] STAT: fixed 1 VOBUS child has terminated pipes closed resident task finished: poll: unhandled POLLERR/HUP/NVAL for fd 59(16) It's now Sat Dec 25 17:05:18 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293218 (in 99996 ms) It's now Sat Dec 25 17:05:18 2010 [timer.py] next activation: 1293293218 (in 99992 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 364471 bytes for cache used nr_read 106372 nr_write 38814 sum 145186 prev_sum 143802 hdd was accessed since previous check! [IDLE] 0 600 False Timeout! execute: 0 growisofs -Z /dev/hda -dvd-compat -dvd-video -publisher Dreambox -V Dreambox DVD record /hdd/movie/tmp/20101225161739/dvd [GROWISOFS] Executing 'mkisofs -dvd-video -publisher Dreambox -V Dreambox DVD record /hdd/movie/tmp/20101225161739/dvd | builtin_dd of=/dev/hda obs=32k seek=0' [GROWISOFS] 0.48% done, estimate finish Sat Dec 25 17:05:58 2010 progress: 0.48 [GROWISOFS] 0.97% done, estimate finish Sat Dec 25 17:05:58 2010 progress: 0.97 [GROWISOFS] 1.45% done, estimate finish Sat Dec 25 17:05:58 2010 progress: 1.45 [GROWISOFS] /dev/hda: restarting DVD+RW format... [GROWISOFS] /dev/hda: "Current Write Speed" is 4.1x1352KBps. [GROWISOFS] 1.94% done, estimate finish Sat Dec 25 17:12:50 2010 progress: 1.94 [GROWISOFS] 2.42% done, estimate finish Sat Dec 25 17:12:50 2010 progress: 2.42 [GROWISOFS] 2.91% done, estimate finish Sat Dec 25 17:13:25 2010 progress: 2.91 [GROWISOFS] 3.39% done, estimate finish Sat Dec 25 17:13:20 2010 progress: 3.39 [GROWISOFS] 3.88% done, estimate finish Sat Dec 25 17:15:51 2010 progress: 3.88 [GROWISOFS] 4.36% done, estimate finish Sat Dec 25 17:15:31 2010 progress: 4.36 [GROWISOFS] 4.84% done, estimate finish Sat Dec 25 17:15:15 2010 progress: 4.84 [GROWISOFS] 5.33% done, estimate finish Sat Dec 25 17:15:02 2010 progress: 5.33 [GROWISOFS] 5.81% done, estimate finish Sat Dec 25 17:15:08 2010 progress: 5.81 [GROWISOFS] 6.30% done, estimate finish Sat Dec 25 17:14:58 2010 progress: 6.3 [GROWISOFS] 6.78% done, estimate finish Sat Dec 25 17:14:48 2010 progress: 6.78 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 364471 bytes for cache used [GROWISOFS] 7.26% done, estimate finish Sat Dec 25 17:14:41 2010 progress: 7.26 [GROWISOFS] 7.75% done, estimate finish Sat Dec 25 17:14:47 2010 progress: 7.75 [GROWISOFS] 8.23% done, estimate finish Sat Dec 25 17:14:40 2010 progress: 8.23 [GROWISOFS] 8.72% done, estimate finish Sat Dec 25 17:14:34 2010 progress: 8.72 [GROWISOFS] 9.20% done, estimate finish Sat Dec 25 17:14:28 2010 progress: 9.2 [GROWISOFS] 9.69% done, estimate finish Sat Dec 25 17:14:34 2010 progress: 9.69 [GROWISOFS] 10.17% done, estimate finish Sat Dec 25 17:14:29 2010 progress: 10.17 [GROWISOFS] 10.66% done, estimate finish Sat Dec 25 17:14:24 2010 progress: 10.66 nr_read 108177 nr_write 38835 sum 147012 prev_sum 145186 hdd was accessed since previous check! [IDLE] 0 600 False [GROWISOFS] 11.14% done, estimate finish Sat Dec 25 17:14:20 2010 progress: 11.14 [GROWISOFS] 11.62% done, estimate finish Sat Dec 25 17:14:25 2010 progress: 11.62 It's now Sat Dec 25 17:06:58 2010 [timer.py] next activation: 1293293318 (in 99985 ms) It's now Sat Dec 25 17:06:58 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293318 (in 99971 ms) [GROWISOFS] 12.11% done, estimate finish Sat Dec 25 17:14:21 2010 progress: 12.11 [GROWISOFS] 12.59% done, estimate finish Sat Dec 25 17:14:18 2010 progress: 12.59 [GROWISOFS] 13.08% done, estimate finish Sat Dec 25 17:14:15 2010 progress: 13.08 [GROWISOFS] 13.56% done, estimate finish Sat Dec 25 17:14:19 2010 progress: 13.56 [GROWISOFS] 14.04% done, estimate finish Sat Dec 25 17:14:16 2010 progress: 14.04 [GROWISOFS] 14.53% done, estimate finish Sat Dec 25 17:14:13 2010 progress: 14.53 [GROWISOFS] 15.01% done, estimate finish Sat Dec 25 17:14:10 2010 progress: 15.01 [GROWISOFS] 15.50% done, estimate finish Sat Dec 25 17:14:14 2010 progress: 15.5 [GROWISOFS] 15.98% done, estimate finish Sat Dec 25 17:14:12 2010 progress: 15.98 [GROWISOFS] 16.47% done, estimate finish Sat Dec 25 17:14:09 2010 progress: 16.47 [GROWISOFS] 16.95% done, estimate finish Sat Dec 25 17:14:07 2010 progress: 16.95 [GROWISOFS] 17.43% done, estimate finish Sat Dec 25 17:14:11 2010 progress: 17.43 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 364471 bytes for cache used [GROWISOFS] 17.92% done, estimate finish Sat Dec 25 17:16:23 2010 progress: 17.92 [GROWISOFS] 18.40% done, estimate finish Sat Dec 25 17:16:22 2010 progress: 18.4 nr_read 109446 nr_write 38848 sum 148294 prev_sum 147012 hdd was accessed since previous check! [IDLE] 0 600 False [GROWISOFS] 18.89% done, estimate finish Sat Dec 25 17:16:17 2010 progress: 18.89 [GROWISOFS] 19.37% done, estimate finish Sat Dec 25 17:16:12 2010 progress: 19.37 [GROWISOFS] 19.86% done, estimate finish Sat Dec 25 17:16:07 2010 progress: 19.86 [GROWISOFS] 20.34% done, estimate finish Sat Dec 25 17:16:07 2010 progress: 20.34 [GROWISOFS] 20.82% done, estimate finish Sat Dec 25 17:16:03 2010 progress: 20.82 [GROWISOFS] 21.31% done, estimate finish Sat Dec 25 17:15:58 2010 progress: 21.31 [GROWISOFS] 21.79% done, estimate finish Sat Dec 25 17:15:54 2010 progress: 21.79 [GROWISOFS] 22.28% done, estimate finish Sat Dec 25 17:15:55 2010 progress: 22.28 [GROWISOFS] 22.76% done, estimate finish Sat Dec 25 17:15:51 2010 progress: 22.76 [GROWISOFS] 23.25% done, estimate finish Sat Dec 25 17:15:47 2010 progress: 23.25 [GROWISOFS] 23.73% done, estimate finish Sat Dec 25 17:15:43 2010 progress: 23.73 [GROWISOFS] 24.21% done, estimate finish Sat Dec 25 17:15:40 2010 progress: 24.21 [GROWISOFS] 24.70% done, estimate finish Sat Dec 25 17:15:41 2010 progress: 24.7 [GROWISOFS] 25.18% done, estimate finish Sat Dec 25 17:15:37 2010 progress: 25.18 [GROWISOFS] 25.67% done, estimate finish Sat Dec 25 17:15:34 2010 progress: 25.67 [GROWISOFS] 26.15% done, estimate finish Sat Dec 25 17:15:31 2010 progress: 26.15 [GROWISOFS] 26.64% done, estimate finish Sat Dec 25 17:15:32 2010 progress: 26.64 [GROWISOFS] 27.12% done, estimate finish Sat Dec 25 17:15:29 2010 progress: 27.12 [GROWISOFS] 27.60% done, estimate finish Sat Dec 25 17:15:26 2010 progress: 27.6 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 364471 bytes for cache used [GROWISOFS] 28.09% done, estimate finish Sat Dec 25 17:15:24 2010 progress: 28.09 It's now Sat Dec 25 17:08:38 2010 [timer.py] next activation: 1293293418 (in 99990 ms) It's now Sat Dec 25 17:08:38 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293418 (in 99970 ms) [GROWISOFS] 28.57% done, estimate finish Sat Dec 25 17:15:24 2010 progress: 28.57 [GROWISOFS] 29.06% done, estimate finish Sat Dec 25 17:15:22 2010 progress: 29.06 [GROWISOFS] 29.54% done, estimate finish Sat Dec 25 17:15:19 2010 progress: 29.54 [GROWISOFS] 30.03% done, estimate finish Sat Dec 25 17:15:17 2010 progress: 30.03 [GROWISOFS] 30.51% done, estimate finish Sat Dec 25 17:15:18 2010 progress: 30.51 [GROWISOFS] 30.99% done, estimate finish Sat Dec 25 17:15:16 2010 progress: 30.99 [GROWISOFS] 31.48% done, estimate finish Sat Dec 25 17:15:13 2010 progress: 31.48 nr_read 111594 nr_write 38870 sum 150464 prev_sum 148294 hdd was accessed since previous check! [IDLE] 0 600 False [GROWISOFS] 31.96% done, estimate finish Sat Dec 25 17:15:11 2010 progress: 31.96 [GROWISOFS] 32.45% done, estimate finish Sat Dec 25 17:15:12 2010 progress: 32.45 [GROWISOFS] 32.93% done, estimate finish Sat Dec 25 17:15:10 2010 progress: 32.93 [GROWISOFS] 33.41% done, estimate finish Sat Dec 25 17:15:08 2010 progress: 33.41 [GROWISOFS] 33.90% done, estimate finish Sat Dec 25 17:15:06 2010 progress: 33.9 [GROWISOFS] 34.38% done, estimate finish Sat Dec 25 17:15:07 2010 progress: 34.38 [GROWISOFS] 34.87% done, estimate finish Sat Dec 25 17:15:05 2010 progress: 34.87 [GROWISOFS] 35.35% done, estimate finish Sat Dec 25 17:15:03 2010 progress: 35.35 [GROWISOFS] 35.84% done, estimate finish Sat Dec 25 17:15:02 2010 progress: 35.84 [GROWISOFS] 36.32% done, estimate finish Sat Dec 25 17:15:03 2010 progress: 36.32 [GROWISOFS] 36.81% done, estimate finish Sat Dec 25 17:15:01 2010 progress: 36.81 [GROWISOFS] 37.29% done, estimate finish Sat Dec 25 17:14:59 2010 progress: 37.29 [GROWISOFS] 37.77% done, estimate finish Sat Dec 25 17:14:58 2010 progress: 37.77 [GROWISOFS] 38.26% done, estimate finish Sat Dec 25 17:14:59 2010 progress: 38.26 [GROWISOFS] 38.74% done, estimate finish Sat Dec 25 17:14:57 2010 progress: 38.74 [GROWISOFS] 39.23% done, estimate finish Sat Dec 25 17:14:55 2010 progress: 39.23 [GROWISOFS] 39.71% done, estimate finish Sat Dec 25 17:14:54 2010 progress: 39.71 [GROWISOFS] 40.19% done, estimate finish Sat Dec 25 17:14:55 2010 progress: 40.19 [GROWISOFS] 40.68% done, estimate finish Sat Dec 25 17:14:53 2010 progress: 40.68 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 364471 bytes for cache used [GROWISOFS] 41.16% done, estimate finish Sat Dec 25 17:14:52 2010 progress: 41.16 [GROWISOFS] 41.65% done, estimate finish Sat Dec 25 17:14:51 2010 progress: 41.65 [GROWISOFS] 42.13% done, estimate finish Sat Dec 25 17:14:52 2010 progress: 42.13 [GROWISOFS] 42.62% done, estimate finish Sat Dec 25 17:14:50 2010 progress: 42.62 [GROWISOFS] 43.10% done, estimate finish Sat Dec 25 17:14:49 2010 progress: 43.1 [GROWISOFS] 43.59% done, estimate finish Sat Dec 25 17:14:47 2010 progress: 43.59 [GROWISOFS] 44.07% done, estimate finish Sat Dec 25 17:14:48 2010 progress: 44.07 nr_read 113744 nr_write 38892 sum 152636 prev_sum 150464 hdd was accessed since previous check! [IDLE] 0 600 False [GROWISOFS] 44.55% done, estimate finish Sat Dec 25 17:14:47 2010 progress: 44.55 [GROWISOFS] 45.04% done, estimate finish Sat Dec 25 17:14:46 2010 progress: 45.04 [GROWISOFS] 45.52% done, estimate finish Sat Dec 25 17:14:45 2010 progress: 45.52 [GROWISOFS] 46.01% done, estimate finish Sat Dec 25 17:14:46 2010 progress: 46.01 [GROWISOFS] 46.49% done, estimate finish Sat Dec 25 17:14:44 2010 progress: 46.49 [GROWISOFS] 46.97% done, estimate finish Sat Dec 25 17:14:43 2010 progress: 46.97 [GROWISOFS] 47.46% done, estimate finish Sat Dec 25 17:14:42 2010 progress: 47.46 [GROWISOFS] 47.94% done, estimate finish Sat Dec 25 17:14:43 2010 progress: 47.94 [GROWISOFS] 48.43% done, estimate finish Sat Dec 25 17:14:42 2010 progress: 48.43 [GROWISOFS] 48.91% done, estimate finish Sat Dec 25 17:14:41 2010 progress: 48.91 [GROWISOFS] 49.40% done, estimate finish Sat Dec 25 17:14:40 2010 progress: 49.4 It's now Sat Dec 25 17:10:18 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293518 (in 99980 ms) It's now Sat Dec 25 17:10:18 2010 [timer.py] next activation: 1293293518 (in 99963 ms) [GROWISOFS] 49.88% done, estimate finish Sat Dec 25 17:14:41 2010 progress: 49.88 [GROWISOFS] 50.37% done, estimate finish Sat Dec 25 17:14:40 2010 progress: 50.37 [GROWISOFS] 50.85% done, estimate finish Sat Dec 25 17:14:39 2010 progress: 50.85 [GROWISOFS] 51.33% done, estimate finish Sat Dec 25 17:14:38 2010 progress: 51.33 [GROWISOFS] 51.82% done, estimate finish Sat Dec 25 17:14:39 2010 progress: 51.82 [GROWISOFS] 52.30% done, estimate finish Sat Dec 25 17:14:38 2010 progress: 52.3 [GROWISOFS] 52.79% done, estimate finish Sat Dec 25 17:14:37 2010 progress: 52.79 [GROWISOFS] 53.27% done, estimate finish Sat Dec 25 17:14:36 2010 progress: 53.27 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 363651 bytes for cache used [GROWISOFS] 53.75% done, estimate finish Sat Dec 25 17:14:37 2010 progress: 53.75 [GROWISOFS] 54.24% done, estimate finish Sat Dec 25 17:14:36 2010 progress: 54.24 [GROWISOFS] 54.72% done, estimate finish Sat Dec 25 17:14:35 2010 progress: 54.72 [GROWISOFS] 55.21% done, estimate finish Sat Dec 25 17:14:34 2010 progress: 55.21 [GROWISOFS] 55.69% done, estimate finish Sat Dec 25 17:14:35 2010 progress: 55.69 [GROWISOFS] 56.18% done, estimate finish Sat Dec 25 17:14:34 2010 progress: 56.18 [GROWISOFS] 56.66% done, estimate finish Sat Dec 25 17:14:33 2010 progress: 56.66 [GROWISOFS] 57.14% done, estimate finish Sat Dec 25 17:14:32 2010 progress: 57.14 nr_read 116008 nr_write 38915 sum 154923 prev_sum 152636 hdd was accessed since previous check! [IDLE] 0 600 False [GROWISOFS] 57.63% done, estimate finish Sat Dec 25 17:14:33 2010 progress: 57.63 [GROWISOFS] 58.11% done, estimate finish Sat Dec 25 17:14:32 2010 progress: 58.11 [GROWISOFS] 58.60% done, estimate finish Sat Dec 25 17:14:31 2010 progress: 58.6 [GROWISOFS] 59.08% done, estimate finish Sat Dec 25 17:14:30 2010 progress: 59.08 [GROWISOFS] 59.57% done, estimate finish Sat Dec 25 17:14:31 2010 progress: 59.57 [GROWISOFS] 60.05% done, estimate finish Sat Dec 25 17:14:30 2010 progress: 60.05 [GROWISOFS] 60.53% done, estimate finish Sat Dec 25 17:14:30 2010 progress: 60.53 [GROWISOFS] 61.02% done, estimate finish Sat Dec 25 17:14:29 2010 progress: 61.02 [GROWISOFS] 61.50% done, estimate finish Sat Dec 25 17:14:30 2010 progress: 61.5 [GROWISOFS] 61.99% done, estimate finish Sat Dec 25 17:14:29 2010 progress: 61.99 [GROWISOFS] 62.47% done, estimate finish Sat Dec 25 17:14:28 2010 progress: 62.47 [GROWISOFS] 62.96% done, estimate finish Sat Dec 25 17:14:27 2010 progress: 62.96 [GROWISOFS] 63.44% done, estimate finish Sat Dec 25 17:14:28 2010 progress: 63.44 [GROWISOFS] 63.92% done, estimate finish Sat Dec 25 17:14:27 2010 progress: 63.92 [GROWISOFS] 64.41% done, estimate finish Sat Dec 25 17:14:27 2010 progress: 64.41 [GROWISOFS] 64.89% done, estimate finish Sat Dec 25 17:14:26 2010 progress: 64.89 [GROWISOFS] 65.38% done, estimate finish Sat Dec 25 17:14:27 2010 progress: 65.38 [GROWISOFS] 65.86% done, estimate finish Sat Dec 25 17:14:26 2010 progress: 65.86 [GROWISOFS] 66.35% done, estimate finish Sat Dec 25 17:14:25 2010 progress: 66.35 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 363651 bytes for cache used [GROWISOFS] 66.83% done, estimate finish Sat Dec 25 17:14:25 2010 progress: 66.83 [GROWISOFS] 67.31% done, estimate finish Sat Dec 25 17:14:24 2010 progress: 67.31 [GROWISOFS] 67.80% done, estimate finish Sat Dec 25 17:14:23 2010 progress: 67.8 [GROWISOFS] 68.28% done, estimate finish Sat Dec 25 17:14:21 2010 progress: 68.28 ERROR reading PES (fd=39) - Value too large for defined data type poll: unhandled POLLERR/HUP/NVAL for fd 39(8) [GROWISOFS] :-( unable to WRITE@LBA=a8ab0h: Input/output error BurnTask: unknown error :-( unable to WRITE@LBA=a8ab0h: Input/output error [GROWISOFS] :-( write failed: Input/output error [GROWISOFS] /dev/hda: flushing cache nr_read 117874 nr_write 38935 sum 156809 prev_sum 154923 hdd was accessed since previous check! [IDLE] 0 600 False It's now Sat Dec 25 17:11:58 2010 [timer.py] next activation: 1293293618 (in 99996 ms) It's now Sat Dec 25 17:11:58 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293618 (in 99989 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 363651 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156809 hdd was accessed since previous check! [IDLE] 0 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 363651 bytes for cache used It's now Sat Dec 25 17:13:38 2010 [timer.py] next activation: 1293293718 (in 99997 ms) It's now Sat Dec 25 17:13:38 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293718 (in 99994 ms) nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 60.0000169277 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 363651 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 120.000003815 600 False It's now Sat Dec 25 17:15:18 2010 [timer.py] next activation: 1293293818 (in 99997 ms) It's now Sat Dec 25 17:15:18 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293818 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 362799 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 180.000018835 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 362799 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 240.001022816 600 False It's now Sat Dec 25 17:16:58 2010 [timer.py] next activation: 1293293918 (in 99997 ms) It's now Sat Dec 25 17:16:58 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293293918 (in 99993 ms) no version filtering 0014: 70 00 00 00 00 00 mask: fc 00 00 00 00 00 mode: 00 00 00 00 00 00 [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 362799 bytes for cache used [eDVBLocalTimerHandler] diff is 1 [eDVBLocalTimerHandler] diff < 120 .. use Transponder Time [eDVBLocalTimerHandler] difference between linux time and RTC time is < 60 sec... so the transponder time looks ok [eDVBLocalTimerHandler] time update to 17:17:40 [eDVBLocalTimerHandler] m_time_difference is 1 [eDVBLocalTimerHandler] set Linux Time [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 362799 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 301.009022951 600 False It's now Sat Dec 25 17:18:39 2010 [timer.py] next activation: 1293294019 (in 99988 ms) It's now Sat Dec 25 17:18:39 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294019 (in 99983 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 362799 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 361.008980989 600 False [EPGC] start caching events(1293293949) [EPGC] abort non avail viasat reading [EPGC] nownext finished(1293293973) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 440140 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 421.008978844 600 False It's now Sat Dec 25 17:20:19 2010 [timer.py] next activation: 1293294119 (in 99997 ms) It's now Sat Dec 25 17:20:19 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294119 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 554047 bytes for cache used [EPGC] schedule other finished(1293294046) [EPGC] schedule finished(1293294046) [EPGC] stop caching events(1293294046) [EPGC] next update in 60 min nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 481.008985996 600 False [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 564161 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 541.008980036 600 False It's now Sat Dec 25 17:21:59 2010 [timer.py] next activation: 1293294219 (in 99998 ms) It's now Sat Dec 25 17:21:59 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294219 (in 99993 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 564161 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 601.008981943 600 False [ePopen] command: ('hdparm', 'hdparm', '-y', '/dev/ide/host0/bus1/target0/lun0/disc') child has terminated pipes closed poll: unhandled POLLERR/HUP/NVAL for fd 62(16) It's now Sat Dec 25 17:23:39 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294319 (in 99994 ms) It's now Sat Dec 25 17:23:39 2010 [timer.py] next activation: 1293294319 (in 99992 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 564161 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 661.008993864 600 True [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 564161 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 721.008970976 600 True It's now Sat Dec 25 17:25:19 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294419 (in 99995 ms) It's now Sat Dec 25 17:25:19 2010 [timer.py] next activation: 1293294419 (in 99993 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 563866 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 781.008996964 600 True [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 563866 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 841.009002924 600 True It's now Sat Dec 25 17:26:59 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294519 (in 99995 ms) It's now Sat Dec 25 17:26:59 2010 [timer.py] next activation: 1293294519 (in 99993 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 563866 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 901.008998871 600 True It's now Sat Dec 25 17:28:39 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294619 (in 99995 ms) It's now Sat Dec 25 17:28:39 2010 [timer.py] next activation: 1293294619 (in 99993 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 563866 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 961.00898695 600 True [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 563866 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 1021.00900698 600 True It's now Sat Dec 25 17:30:19 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294719 (in 99996 ms) It's now Sat Dec 25 17:30:19 2010 [timer.py] next activation: 1293294719 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 562022 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 1081.00900292 600 True [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 562022 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 1141.00899386 600 True It's now Sat Dec 25 17:31:59 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294819 (in 99995 ms) It's now Sat Dec 25 17:31:59 2010 [timer.py] next activation: 1293294819 (in 99993 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 562022 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 1201.00902486 600 True It's now Sat Dec 25 17:33:39 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293294919 (in 99996 ms) It's now Sat Dec 25 17:33:39 2010 [timer.py] next activation: 1293294919 (in 99994 ms) [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 562022 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 1261.00897598 600 True [EPGC] start cleanloop [EPGC] stop cleanloop [EPGC] 562022 bytes for cache used nr_read 117874 nr_write 38936 sum 156810 prev_sum 156810 hdd IDLE! [IDLE] 1321.00898385 600 True It's now Sat Dec 25 17:35:19 2010 next real activation is Sat Dec 25 21:39:40 2010 [timer.py] next activation: 1293295019 (in 99996 ms) It's now Sat Dec 25 17:35:19 2010 [timer.py] next activation: 1293295019 (in 99994 ms) set wakeup time to 2010/12/25 21:35 stopService [eDVBCAService] free slot 0 demux 0 for service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: [eDVBCAService] free service 1:0:1:33E:442:FE00:FFFF0000:0:0:0: decoder state: play, vpid=-1, apid=-1 DEMUX_STOP - pcr - ok DEMUX_STOP - video - ok VIDEO_STOP - ok AUDIO_STOP - ok DEMUX_STOP - audio - ok AUDIO_CONTINUE - ok DEMUX_STOP - ttx - ok start release channel timer [CrashlogAutoSubmit] - autostart ---- saving lame channel db saved 31 channels and 264 services! release cached channel (timer timeout) [eDVBCAService] remove channel 0x108e3630 [eDVBLocalTimerHandler] remove channel 0x108e3630 [eEPGCache] remove channel 0x108e3630 stop release channel timer main thread is non-idle! display spinner! [EPGC] 4461 events written to /hdd/epg.dat thread joined 0 set RTC to previous valid time - (41) eServiceFactoryFS - (41) eServiceFactoryDVB - (41) eServiceFactoryMP3 - (41) eServiceFactoryDVD - (40) eServiceCenter - (35) CI Slots - (30) eActionMap - (21) Console RC Driver - (21) input device driver - (20) DVB-CI UI - (20) misc options - (20) UHF Modulator - (20) AVSwitch Driver - (20) RC Input layer - (15) eWindowStyleManager - (10) gRC waiting for gRC thread shutdown gRC thread has finished - (9) gLCDDC - (9) GFBDC - (9) Font Render Class - (8) graphics acceleration manager - (1) Background File Eraser reached rl -1 close frontend 2