Ticket #10732: debug.2.txt

File debug.2.txt, 12.0 KB (added by Michael Harnden <mike@…>, 14 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