Backend Log Jun 8 14:29:29 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:1521 (HandlePendingRecordings) TVRec(2): ASK_RECORDING 2 20 0 0 Jun 8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(2): Changing from None to RecordingOnly Jun 8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent mythdbcon.cpp:395 (PurgeIdleConnections) New DB connection, total: 13 Jun 8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:3474 (TuningCheckForHWChange) TVRec(2): HW Tuner: 2->2 Jun 8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent v4lchannel.cpp:661 (SetInputAndFormat) V4LChannel(/dev/video0): SetInputAndFormat(4, NTSC) (v4l v2) input_switch: 0 mode_switch: 0 Jun 8 14:29:49 mythtv-server mythbackend[2639]: N Scheduler autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min Jun 8 14:29:49 mythtv-server mythbackend[2639]: I Scheduler scheduler.cpp:2459 (HandleRecordingStatusChange) Tuning recording: "Peppa Pig":"The Sleepover; Daddy Pig's Office; Pirate Island; George Catches a Cold; Cold Winter's Day": channel 1519 on cardid 2, sourceid 1 Jun 8 14:29:49 mythtv-server mythbackend[2639]: I Scheduler mythdbcon.cpp:237 (Reconnect) MySQL reconnected successfully Jun 8 14:29:50 mythtv-server mythbackend[2639]: I Scheduler scheduler.cpp:2459 (HandleRecordingStatusChange) Started recording: "Peppa Pig":"The Sleepover; Daddy Pig's Office; Pirate Island; George Catches a Cold; Cold Winter's Day": channel 1519 on cardid 2, sourceid 1 Jun 8 14:29:50 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:3968 (TuningNewRecorder) TVRec(2): rec->GetPathname(): '/mnt/store/d4/video/1519_20120608143000.mpg' Jun 8 14:32:32 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread Jun 8 14:36:26 mythtv-server mythbackend[2639]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min Jun 8 14:37:32 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread Jun 8 14:42:37 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread Jun 8 14:47:37 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread Jun 8 14:51:26 mythtv-server mythbackend[2639]: N Expire autoexpire.cpp:263 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 2.0 GB w/freq: 15 min Jun 8 14:51:45 mythtv-server mythbackend[2639]: E DeviceReadBuffer DeviceReadBuffer.cpp:513 (Poll) DevRdB(/dev/video0): Poll giving up 2 Jun 8 14:51:45 mythtv-server mythbackend[2639]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected Jun 8 14:51:50 mythtv-server mythbackend[2639]: E DeviceReadBuffer DeviceReadBuffer.cpp:513 (Poll) DevRdB(/dev/video0): Poll giving up 2 Jun 8 14:51:50 mythtv-server mythbackend[2639]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected Kernel Log modprobe ivtv debug=0x4f Jun 8 14:47:27 mythtv-server kernel: [150138.013118] ivtv0: irq: VIM RST Jun 8 14:47:31 mythtv-server kernel: [150141.743700] ivtv0: irq: VIM RST Jun 8 14:47:35 mythtv-server kernel: [150145.520598] ivtv0: irq: VIM RST Jun 8 14:47:38 mythtv-server kernel: [150149.251332] ivtv0: irq: VIM RST Jun 8 14:47:57 mythtv-server kernel: [150168.358458] ivtv0: irq: VIM RST Jun 8 14:48:01 mythtv-server kernel: [150172.097220] ivtv0: irq: VIM RST Jun 8 14:48:05 mythtv-server kernel: [150175.866056] ivtv0: irq: VIM RST Jun 8 14:48:09 mythtv-server kernel: [150179.604376] ivtv0: irq: VIM RST Jun 8 14:48:43 mythtv-server kernel: [150214.342162] ivtv0: irq: VIM RST Jun 8 14:48:47 mythtv-server kernel: [150218.118425] ivtv0: irq: VIM RST Jun 8 14:49:55 mythtv-server kernel: [150285.512944] ivtv0: irq: VIM RST Jun 8 14:49:58 mythtv-server kernel: [150289.290749] ivtv0: irq: VIM RST Jun 8 14:51:45 mythtv-server kernel: [150395.721817] ivtv0: ioctl: V4L2_ENC_CMD_STOP Jun 8 14:51:45 mythtv-server kernel: [150395.721822] ivtv0: info: close stopping capture Jun 8 14:51:45 mythtv-server kernel: [150395.721826] ivtv0: info: Stop Capture Jun 8 14:51:45 mythtv-server kernel: [150395.721830] ivtv0: mb: MB Call: CX2341X_ENC_STOP_CAPTURE Jun 8 14:51:47 mythtv-server kernel: [150397.720009] ivtv0: warn: encoder MPG: EOS interrupt not received! stopping anyway. Jun 8 14:51:47 mythtv-server kernel: [150397.720015] ivtv0: warn: encoder MPG: waited 2000 ms. Jun 8 14:51:47 mythtv-server kernel: [150397.820023] ivtv0: mb: MB Call: CX2341X_ENC_SET_EVENT_NOTIFICATION Jun 8 14:51:47 mythtv-server kernel: [150397.820097] ivtv0: mb: MB Call: CX2341X_ENC_STOP_CAPTURE Jun 8 14:51:47 mythtv-server kernel: [150397.820199] ivtv0 encoder MPG: VIDIOC_ENCODER_CMD cmd=1, flags=1 Jun 8 14:51:47 mythtv-server kernel: [150397.820242] ivtv0: mb: MB Call: CX2341X_ENC_PING_FW Jun 8 14:51:47 mythtv-server kernel: [150397.820302] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:47 mythtv-server kernel: [150397.832041] ivtv0: mb: MB Call: CX2341X_DEC_PING_FW Jun 8 14:51:47 mythtv-server kernel: [150397.832196] ivtv0: ioctl: V4L2_ENC_CMD_START Jun 8 14:51:47 mythtv-server kernel: [150397.832200] ivtv0: info: Start encoder stream encoder MPG Jun 8 14:51:47 mythtv-server kernel: [150397.832204] ivtv0: mb: MB Call: CX2341X_ENC_SET_DMA_BLOCK_SIZE Jun 8 14:51:47 mythtv-server kernel: [150397.832207] ivtv0: mb: MB Call: CX2341X_ENC_SET_VERT_CROP_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.832268] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:47 mythtv-server kernel: [150397.832331] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:47 mythtv-server kernel: [150397.832395] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:47 mythtv-server kernel: [150397.832460] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:47 mythtv-server kernel: [150397.832527] ivtv0: mb: MB Call: CX2341X_ENC_SET_PLACEHOLDER Jun 8 14:51:47 mythtv-server kernel: [150397.832531] ivtv0: mb: MB Call: CX2341X_ENC_SET_NUM_VSYNC_LINES Jun 8 14:51:47 mythtv-server kernel: [150397.832535] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850022] ivtv0: info: Setup VBI API header 0x0000bd03 pkts 1 buffs 4 ln 24 sz 1456 Jun 8 14:51:47 mythtv-server kernel: [150397.850027] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_CONFIG Jun 8 14:51:47 mythtv-server kernel: [150397.850100] ivtv0: info: Setup VBI start 0x002fea04 frames 4 fpi 1 Jun 8 14:51:47 mythtv-server kernel: [150397.850103] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850172] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850241] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850310] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850379] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850447] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850516] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850590] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.850664] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.853198] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.853268] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.853337] ivtv0: mb: MB Call: CX2341X_ENC_SET_VBI_LINE Jun 8 14:51:47 mythtv-server kernel: [150397.853408] ivtv0: mb: MB Call: CX2341X_ENC_SET_PGM_INDEX_INFO Jun 8 14:51:47 mythtv-server kernel: [150397.853472] ivtv0: info: PGM Index at 0x00180150 with 400 elements Jun 8 14:51:47 mythtv-server kernel: [150397.853476] ivtv0: mb: MB Call: CX2341X_ENC_SET_OUTPUT_PORT Jun 8 14:51:47 mythtv-server kernel: [150397.853482] ivtv0: mb: MB Call: CX2341X_ENC_SET_FRAME_RATE Jun 8 14:51:47 mythtv-server kernel: [150397.853487] ivtv0: mb: MB Call: CX2341X_ENC_SET_FRAME_SIZE Jun 8 14:51:47 mythtv-server kernel: [150397.853496] ivtv0: mb: MB Call: CX2341X_ENC_SET_STREAM_TYPE Jun 8 14:51:47 mythtv-server kernel: [150397.853501] ivtv0: mb: MB Call: CX2341X_ENC_SET_BIT_RATE Jun 8 14:51:47 mythtv-server kernel: [150397.853507] ivtv0: mb: MB Call: CX2341X_ENC_SET_AUDIO_PROPERTIES Jun 8 14:51:47 mythtv-server kernel: [150397.853513] ivtv0: mb: MB Call: CX2341X_ENC_MUTE_AUDIO Jun 8 14:51:47 mythtv-server kernel: [150397.853576] ivtv0: mb: MB Call: CX2341X_ENC_SET_ASPECT_RATIO Jun 8 14:51:47 mythtv-server kernel: [150397.853581] ivtv0: mb: MB Call: CX2341X_ENC_SET_GOP_PROPERTIES Jun 8 14:51:47 mythtv-server kernel: [150397.853586] ivtv0: mb: MB Call: CX2341X_ENC_SET_GOP_CLOSURE Jun 8 14:51:47 mythtv-server kernel: [150397.853591] ivtv0: mb: MB Call: CX2341X_ENC_SET_FRAME_DROP_RATE Jun 8 14:51:47 mythtv-server kernel: [150397.853596] ivtv0: mb: MB Call: CX2341X_ENC_MUTE_VIDEO Jun 8 14:51:47 mythtv-server kernel: [150397.853708] ivtv0: mb: MB Call: CX2341X_ENC_SET_DNR_FILTER_MODE Jun 8 14:51:47 mythtv-server kernel: [150397.853714] ivtv0: mb: MB Call: CX2341X_ENC_SET_DNR_FILTER_PROPS Jun 8 14:51:47 mythtv-server kernel: [150397.853718] ivtv0: mb: MB Call: CX2341X_ENC_SET_SPATIAL_FILTER_TYPE Jun 8 14:51:47 mythtv-server kernel: [150397.853724] ivtv0: mb: MB Call: CX2341X_ENC_SET_CORING_LEVELS Jun 8 14:51:47 mythtv-server kernel: [150397.853728] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:47 mythtv-server kernel: [150397.853792] ivtv0: mb: MB Call: CX2341X_ENC_SET_EVENT_NOTIFICATION Jun 8 14:51:47 mythtv-server kernel: [150398.152021] ivtv0: mb: MB Call: CX2341X_ENC_INITIALIZE_INPUT Jun 8 14:51:47 mythtv-server kernel: [150398.152714] ivtv0: mb: MB Call: CX2341X_ENC_START_CAPTURE Jun 8 14:51:47 mythtv-server kernel: [150398.164039] ivtv0 encoder MPG: VIDIOC_ENCODER_CMD cmd=0, flags=0 Jun 8 14:51:47 mythtv-server kernel: [150398.183872] ivtv0: irq: VIM RST Jun 8 14:51:50 mythtv-server kernel: [150400.676288] ivtv0: ioctl: V4L2_ENC_CMD_STOP Jun 8 14:51:50 mythtv-server kernel: [150400.676296] ivtv0: info: close stopping capture Jun 8 14:51:50 mythtv-server kernel: [150400.676300] ivtv0: info: Stop Capture Jun 8 14:51:50 mythtv-server kernel: [150400.676305] ivtv0: mb: MB Call: CX2341X_ENC_STOP_CAPTURE Jun 8 14:51:52 mythtv-server kernel: [150402.676010] ivtv0: warn: encoder MPG: EOS interrupt not received! stopping anyway. Jun 8 14:51:52 mythtv-server kernel: [150402.676016] ivtv0: warn: encoder MPG: waited 2000 ms. Jun 8 14:51:52 mythtv-server kernel: [150402.776026] ivtv0: mb: MB Call: CX2341X_ENC_SET_EVENT_NOTIFICATION Jun 8 14:51:52 mythtv-server kernel: [150402.776097] ivtv0: mb: MB Call: CX2341X_ENC_STOP_CAPTURE Jun 8 14:51:52 mythtv-server kernel: [150402.776198] ivtv0 encoder MPG: VIDIOC_ENCODER_CMD cmd=1, flags=1 Jun 8 14:51:52 mythtv-server kernel: [150402.776241] ivtv0: mb: MB Call: CX2341X_ENC_PING_FW Jun 8 14:51:52 mythtv-server kernel: [150402.776303] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:52 mythtv-server kernel: [150402.776373] ivtv0: mb: MB Call: CX2341X_DEC_PING_FW Jun 8 14:51:52 mythtv-server kernel: [150402.776532] ivtv0: ioctl: V4L2_ENC_CMD_START Jun 8 14:51:52 mythtv-server kernel: [150402.776536] ivtv0: info: Start encoder stream encoder MPG Jun 8 14:51:52 mythtv-server kernel: [150402.776539] ivtv0: mb: MB Call: CX2341X_ENC_SET_DMA_BLOCK_SIZE Jun 8 14:51:52 mythtv-server kernel: [150402.776543] ivtv0: mb: MB Call: CX2341X_ENC_SET_VERT_CROP_LINE Jun 8 14:51:52 mythtv-server kernel: [150402.776607] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:52 mythtv-server kernel: [150402.776700] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:52 mythtv-server kernel: [150402.776771] ivtv0: mb: MB Call: CX2341X_ENC_MISC Jun 8 14:51:52 mythtv-server kernel: [150402.776836] ivtv0: mb: MB Call: CX2341X_ENC_MISC