Ticket #10732: debug.2.txt

File debug.2.txt, 12.0 KB (added by Michael Harnden <mike@…>, 12 years ago)

Backend and Kernel logs of latest failure

Line 
1Backend Log
2
3Jun  8 14:29:29 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:1521 (HandlePendingRecordings) TVRec(2): ASK_RECORDING 2 20 0 0
4Jun  8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:1014 (HandleStateChange) TVRec(2): Changing from None to RecordingOnly
5Jun  8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent mythdbcon.cpp:395 (PurgeIdleConnections) New DB connection, total: 13
6Jun  8 14:29:49 mythtv-server mythbackend[2639]: I TVRecEvent tv_rec.cpp:3474 (TuningCheckForHWChange) TVRec(2): HW Tuner: 2->2
7Jun  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
8Jun  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
9Jun  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
10Jun  8 14:29:49 mythtv-server mythbackend[2639]: I Scheduler mythdbcon.cpp:237 (Reconnect) MySQL reconnected successfully
11Jun  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
12Jun  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'
13Jun  8 14:32:32 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
14Jun  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
15Jun  8 14:37:32 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
16Jun  8 14:42:37 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
17Jun  8 14:47:37 mythtv-server mythbackend[2639]: I HouseKeeping housekeeper.cpp:225 (RunHouseKeeping) Running housekeeping thread
18Jun  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
19Jun  8 14:51:45 mythtv-server mythbackend[2639]: E DeviceReadBuffer DeviceReadBuffer.cpp:513 (Poll) DevRdB(/dev/video0): Poll giving up 2
20Jun  8 14:51:45 mythtv-server mythbackend[2639]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
21Jun  8 14:51:50 mythtv-server mythbackend[2639]: E DeviceReadBuffer DeviceReadBuffer.cpp:513 (Poll) DevRdB(/dev/video0): Poll giving up 2
22Jun  8 14:51:50 mythtv-server mythbackend[2639]: E RecThread mpegrecorder.cpp:1010 (run) MPEGRec(/dev/video0): Device error detected
23
24
25Kernel Log
26modprobe ivtv debug=0x4f
27
28Jun  8 14:47:27 mythtv-server kernel: [150138.013118] ivtv0:  irq: VIM RST
29Jun  8 14:47:31 mythtv-server kernel: [150141.743700] ivtv0:  irq: VIM RST
30Jun  8 14:47:35 mythtv-server kernel: [150145.520598] ivtv0:  irq: VIM RST
31Jun  8 14:47:38 mythtv-server kernel: [150149.251332] ivtv0:  irq: VIM RST
32Jun  8 14:47:57 mythtv-server kernel: [150168.358458] ivtv0:  irq: VIM RST
33Jun  8 14:48:01 mythtv-server kernel: [150172.097220] ivtv0:  irq: VIM RST
34Jun  8 14:48:05 mythtv-server kernel: [150175.866056] ivtv0:  irq: VIM RST
35Jun  8 14:48:09 mythtv-server kernel: [150179.604376] ivtv0:  irq: VIM RST
36Jun  8 14:48:43 mythtv-server kernel: [150214.342162] ivtv0:  irq: VIM RST
37Jun  8 14:48:47 mythtv-server kernel: [150218.118425] ivtv0:  irq: VIM RST
38Jun  8 14:49:55 mythtv-server kernel: [150285.512944] ivtv0:  irq: VIM RST
39Jun  8 14:49:58 mythtv-server kernel: [150289.290749] ivtv0:  irq: VIM RST
40Jun  8 14:51:45 mythtv-server kernel: [150395.721817] ivtv0:  ioctl: V4L2_ENC_CMD_STOP
41Jun  8 14:51:45 mythtv-server kernel: [150395.721822] ivtv0:  info: close stopping capture
42Jun  8 14:51:45 mythtv-server kernel: [150395.721826] ivtv0:  info: Stop Capture
43Jun  8 14:51:45 mythtv-server kernel: [150395.721830] ivtv0:  mb: MB Call: CX2341X_ENC_STOP_CAPTURE
44Jun  8 14:51:47 mythtv-server kernel: [150397.720009] ivtv0:  warn: encoder MPG: EOS interrupt not received! stopping anyway.
45Jun  8 14:51:47 mythtv-server kernel: [150397.720015] ivtv0:  warn: encoder MPG: waited 2000 ms.
46Jun  8 14:51:47 mythtv-server kernel: [150397.820023] ivtv0:  mb: MB Call: CX2341X_ENC_SET_EVENT_NOTIFICATION
47Jun  8 14:51:47 mythtv-server kernel: [150397.820097] ivtv0:  mb: MB Call: CX2341X_ENC_STOP_CAPTURE
48Jun  8 14:51:47 mythtv-server kernel: [150397.820199] ivtv0 encoder MPG: VIDIOC_ENCODER_CMD cmd=1, flags=1
49Jun  8 14:51:47 mythtv-server kernel: [150397.820242] ivtv0:  mb: MB Call: CX2341X_ENC_PING_FW
50Jun  8 14:51:47 mythtv-server kernel: [150397.820302] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
51Jun  8 14:51:47 mythtv-server kernel: [150397.832041] ivtv0:  mb: MB Call: CX2341X_DEC_PING_FW
52Jun  8 14:51:47 mythtv-server kernel: [150397.832196] ivtv0:  ioctl: V4L2_ENC_CMD_START
53Jun  8 14:51:47 mythtv-server kernel: [150397.832200] ivtv0:  info: Start encoder stream encoder MPG
54Jun  8 14:51:47 mythtv-server kernel: [150397.832204] ivtv0:  mb: MB Call: CX2341X_ENC_SET_DMA_BLOCK_SIZE
55Jun  8 14:51:47 mythtv-server kernel: [150397.832207] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VERT_CROP_LINE
56Jun  8 14:51:47 mythtv-server kernel: [150397.832268] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
57Jun  8 14:51:47 mythtv-server kernel: [150397.832331] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
58Jun  8 14:51:47 mythtv-server kernel: [150397.832395] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
59Jun  8 14:51:47 mythtv-server kernel: [150397.832460] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
60Jun  8 14:51:47 mythtv-server kernel: [150397.832527] ivtv0:  mb: MB Call: CX2341X_ENC_SET_PLACEHOLDER
61Jun  8 14:51:47 mythtv-server kernel: [150397.832531] ivtv0:  mb: MB Call: CX2341X_ENC_SET_NUM_VSYNC_LINES
62Jun  8 14:51:47 mythtv-server kernel: [150397.832535] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
63Jun  8 14:51:47 mythtv-server kernel: [150397.850022] ivtv0:  info: Setup VBI API header 0x0000bd03 pkts 1 buffs 4 ln 24 sz 1456
64Jun  8 14:51:47 mythtv-server kernel: [150397.850027] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_CONFIG
65Jun  8 14:51:47 mythtv-server kernel: [150397.850100] ivtv0:  info: Setup VBI start 0x002fea04 frames 4 fpi 1
66Jun  8 14:51:47 mythtv-server kernel: [150397.850103] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
67Jun  8 14:51:47 mythtv-server kernel: [150397.850172] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
68Jun  8 14:51:47 mythtv-server kernel: [150397.850241] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
69Jun  8 14:51:47 mythtv-server kernel: [150397.850310] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
70Jun  8 14:51:47 mythtv-server kernel: [150397.850379] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
71Jun  8 14:51:47 mythtv-server kernel: [150397.850447] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
72Jun  8 14:51:47 mythtv-server kernel: [150397.850516] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
73Jun  8 14:51:47 mythtv-server kernel: [150397.850590] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
74Jun  8 14:51:47 mythtv-server kernel: [150397.850664] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
75Jun  8 14:51:47 mythtv-server kernel: [150397.853198] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
76Jun  8 14:51:47 mythtv-server kernel: [150397.853268] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
77Jun  8 14:51:47 mythtv-server kernel: [150397.853337] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VBI_LINE
78Jun  8 14:51:47 mythtv-server kernel: [150397.853408] ivtv0:  mb: MB Call: CX2341X_ENC_SET_PGM_INDEX_INFO
79Jun  8 14:51:47 mythtv-server kernel: [150397.853472] ivtv0:  info: PGM Index at 0x00180150 with 400 elements
80Jun  8 14:51:47 mythtv-server kernel: [150397.853476] ivtv0:  mb: MB Call: CX2341X_ENC_SET_OUTPUT_PORT
81Jun  8 14:51:47 mythtv-server kernel: [150397.853482] ivtv0:  mb: MB Call: CX2341X_ENC_SET_FRAME_RATE
82Jun  8 14:51:47 mythtv-server kernel: [150397.853487] ivtv0:  mb: MB Call: CX2341X_ENC_SET_FRAME_SIZE
83Jun  8 14:51:47 mythtv-server kernel: [150397.853496] ivtv0:  mb: MB Call: CX2341X_ENC_SET_STREAM_TYPE
84Jun  8 14:51:47 mythtv-server kernel: [150397.853501] ivtv0:  mb: MB Call: CX2341X_ENC_SET_BIT_RATE
85Jun  8 14:51:47 mythtv-server kernel: [150397.853507] ivtv0:  mb: MB Call: CX2341X_ENC_SET_AUDIO_PROPERTIES
86Jun  8 14:51:47 mythtv-server kernel: [150397.853513] ivtv0:  mb: MB Call: CX2341X_ENC_MUTE_AUDIO
87Jun  8 14:51:47 mythtv-server kernel: [150397.853576] ivtv0:  mb: MB Call: CX2341X_ENC_SET_ASPECT_RATIO
88Jun  8 14:51:47 mythtv-server kernel: [150397.853581] ivtv0:  mb: MB Call: CX2341X_ENC_SET_GOP_PROPERTIES
89Jun  8 14:51:47 mythtv-server kernel: [150397.853586] ivtv0:  mb: MB Call: CX2341X_ENC_SET_GOP_CLOSURE
90Jun  8 14:51:47 mythtv-server kernel: [150397.853591] ivtv0:  mb: MB Call: CX2341X_ENC_SET_FRAME_DROP_RATE
91Jun  8 14:51:47 mythtv-server kernel: [150397.853596] ivtv0:  mb: MB Call: CX2341X_ENC_MUTE_VIDEO
92Jun  8 14:51:47 mythtv-server kernel: [150397.853708] ivtv0:  mb: MB Call: CX2341X_ENC_SET_DNR_FILTER_MODE
93Jun  8 14:51:47 mythtv-server kernel: [150397.853714] ivtv0:  mb: MB Call: CX2341X_ENC_SET_DNR_FILTER_PROPS
94Jun  8 14:51:47 mythtv-server kernel: [150397.853718] ivtv0:  mb: MB Call: CX2341X_ENC_SET_SPATIAL_FILTER_TYPE
95Jun  8 14:51:47 mythtv-server kernel: [150397.853724] ivtv0:  mb: MB Call: CX2341X_ENC_SET_CORING_LEVELS
96Jun  8 14:51:47 mythtv-server kernel: [150397.853728] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
97Jun  8 14:51:47 mythtv-server kernel: [150397.853792] ivtv0:  mb: MB Call: CX2341X_ENC_SET_EVENT_NOTIFICATION
98Jun  8 14:51:47 mythtv-server kernel: [150398.152021] ivtv0:  mb: MB Call: CX2341X_ENC_INITIALIZE_INPUT
99Jun  8 14:51:47 mythtv-server kernel: [150398.152714] ivtv0:  mb: MB Call: CX2341X_ENC_START_CAPTURE
100Jun  8 14:51:47 mythtv-server kernel: [150398.164039] ivtv0 encoder MPG: VIDIOC_ENCODER_CMD cmd=0, flags=0
101Jun  8 14:51:47 mythtv-server kernel: [150398.183872] ivtv0:  irq: VIM RST
102Jun  8 14:51:50 mythtv-server kernel: [150400.676288] ivtv0:  ioctl: V4L2_ENC_CMD_STOP
103Jun  8 14:51:50 mythtv-server kernel: [150400.676296] ivtv0:  info: close stopping capture
104Jun  8 14:51:50 mythtv-server kernel: [150400.676300] ivtv0:  info: Stop Capture
105Jun  8 14:51:50 mythtv-server kernel: [150400.676305] ivtv0:  mb: MB Call: CX2341X_ENC_STOP_CAPTURE
106Jun  8 14:51:52 mythtv-server kernel: [150402.676010] ivtv0:  warn: encoder MPG: EOS interrupt not received! stopping anyway.
107Jun  8 14:51:52 mythtv-server kernel: [150402.676016] ivtv0:  warn: encoder MPG: waited 2000 ms.
108Jun  8 14:51:52 mythtv-server kernel: [150402.776026] ivtv0:  mb: MB Call: CX2341X_ENC_SET_EVENT_NOTIFICATION
109Jun  8 14:51:52 mythtv-server kernel: [150402.776097] ivtv0:  mb: MB Call: CX2341X_ENC_STOP_CAPTURE
110Jun  8 14:51:52 mythtv-server kernel: [150402.776198] ivtv0 encoder MPG: VIDIOC_ENCODER_CMD cmd=1, flags=1
111Jun  8 14:51:52 mythtv-server kernel: [150402.776241] ivtv0:  mb: MB Call: CX2341X_ENC_PING_FW
112Jun  8 14:51:52 mythtv-server kernel: [150402.776303] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
113Jun  8 14:51:52 mythtv-server kernel: [150402.776373] ivtv0:  mb: MB Call: CX2341X_DEC_PING_FW
114Jun  8 14:51:52 mythtv-server kernel: [150402.776532] ivtv0:  ioctl: V4L2_ENC_CMD_START
115Jun  8 14:51:52 mythtv-server kernel: [150402.776536] ivtv0:  info: Start encoder stream encoder MPG
116Jun  8 14:51:52 mythtv-server kernel: [150402.776539] ivtv0:  mb: MB Call: CX2341X_ENC_SET_DMA_BLOCK_SIZE
117Jun  8 14:51:52 mythtv-server kernel: [150402.776543] ivtv0:  mb: MB Call: CX2341X_ENC_SET_VERT_CROP_LINE
118Jun  8 14:51:52 mythtv-server kernel: [150402.776607] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
119Jun  8 14:51:52 mythtv-server kernel: [150402.776700] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
120Jun  8 14:51:52 mythtv-server kernel: [150402.776771] ivtv0:  mb: MB Call: CX2341X_ENC_MISC
121Jun  8 14:51:52 mythtv-server kernel: [150402.776836] ivtv0:  mb: MB Call: CX2341X_ENC_MISC