LinHES Forums :: View topic - frontend crashes

# pico /var/log/mythtv/backend.log
2005-12-11 18:46:18.167 DVB#0 Recorder: Card opened successfully (using PS mode).
2005-12-11 18:46:18.183 DVB#0 Data read from DMX - This is for debugging with transform.c
2005-12-11 18:46:46.714 DVB#0 DVB signal 93b5 | snr f655 | ber 0 | unc 0
2005-12-11 18:46:46.725 DVB#0 Status: LOCK.
2005-12-11 18:46:46.730 DVB#0 Multiplex Locked
2005-12-11 18:46:47.622 DVB#0 Successfully tuned to channel 51.
2005-12-11 18:46:47.649 DVB#0 Data read from DMX - This is for debugging with transform.c
2005-12-11 18:54:59.245 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:10.514 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:12.857 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:18.584 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:24.738 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:35.532 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:41.339 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:47.774 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:55:58.664 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:06.168 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:17.987 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:24.334 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:31.200 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:39.201 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:43.870 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:56:49.042 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:57:06.688 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:57:15.616 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:57:29.902 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:58:01.680 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:58:08.068 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:58:39.585 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 18:58:27.714 DVB#0 ERROR - Reading Section.
(75) Value too large for defined data type
2005-12-11 18:59:28.016 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 19:00:34.160 IOBOUND - blocking in ThreadedFileWriter::Write()
2005-12-11 19:00:10.243 DVB#0 ERROR - Reading Section.
(75) Value too large for defined data type

# pico /var/log/syslog

Dec 11 18:57:45 mythbox kernel: spurious 8259A interrupt: IRQ7.
Dec 11 18:59:46 mythbox kernel: oom-killer: gfp_mask=0x201d2, order=0
Dec 11 18:59:50 mythbox kernel: Mem-info:
Dec 11 18:59:52 mythbox kernel: DMA per-cpu:
Dec 11 18:59:52 mythbox kernel: cpu 0 hot: low 2, high 6, batch 1 used:4
Dec 11 18:59:52 mythbox kernel: cpu 0 cold: low 0, high 2, batch 1 used:0
Dec 11 18:59:52 mythbox kernel: Normal per-cpu:
Dec 11 18:59:52 mythbox kernel: cpu 0 hot: low 62, high 186, batch 31 used:158
Dec 11 18:59:52 mythbox kernel: cpu 0 cold: low 0, high 62, batch 31 used:61
Dec 11 18:59:52 mythbox kernel: HighMem per-cpu: empty
Dec 11 18:59:52 mythbox kernel: Free pages: 4764kB (0kB HighMem)
Dec 11 18:59:52 mythbox kernel: Active:57741 inactive:57337 dirty:0 writeback:0 unstable:0 free:1191 slab:5894 mapped:115100 pagetables:506
Dec 11 18:59:52 mythbox kernel: DMA free:2068kB min:88kB low:108kB high:132kB active:4856kB inactive:4380kB present:16384kB pages_scanned:11377 all_unreclaimable? yes
Dec 11 18:59:52 mythbox kernel: lowmem_reserve[]: 0 495 495
Dec 11 18:59:52 mythbox kernel: Normal free:2696kB min:2800kB low:3500kB high:4200kB active:226108kB inactive:224968kB present:507072kB pages_scanned:562069 all_unreclaimable? yes
Dec 11 18:59:52 mythbox kernel: lowmem_reserve[]: 0 0 0
Dec 11 18:59:52 mythbox kernel: HighMem free:0kB min:128kB low:160kB high:192kB active:0kB inactive:0kB present:0kB pages_scanned:0 all_unreclaimable? no
Dec 11 18:59:52 mythbox kernel: lowmem_reserve[]: 0 0 0
Dec 11 18:59:52 mythbox kernel: DMA: 1*4kB 0*8kB 1*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 1*2048kB 0*4096kB = 2068kB
Dec 11 18:59:52 mythbox kernel: Normal: 0*4kB 1*8kB 0*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 1*2048kB 0*4096kB = 2696kB
Dec 11 18:59:52 mythbox kernel: HighMem: empty
Dec 11 18:59:52 mythbox kernel: Swap cache: add 191753, delete 191604, find 229/569, race 0+0
Dec 11 18:59:52 mythbox kernel: Free swap = 0kB
Dec 11 18:59:52 mythbox kernel: Total swap = 755044kB
Dec 11 18:59:52 mythbox kernel: Free swap: 0kB
Dec 11 18:59:52 mythbox kernel: 130864 pages of RAM
Dec 11 18:59:52 mythbox kernel: 0 pages of HIGHMEM
Dec 11 18:59:52 mythbox kernel: 2696 reserved pages
Dec 11 18:59:52 mythbox kernel: 3063 pages shared
Dec 11 18:59:52 mythbox kernel: 149 pages swap cached
Dec 11 18:59:52 mythbox kernel: 0 pages dirty
Dec 11 18:59:52 mythbox kernel: 0 pages writeback
Dec 11 18:59:52 mythbox kernel: 115100 pages mapped
Dec 11 18:59:52 mythbox kernel: 5894 pages slab
Dec 11 18:59:52 mythbox kernel: 506 pages pagetables
Dec 11 18:59:52 mythbox kernel: Out of Memory: Killed process 4699 (ssh-agent).

Author:	declanh [ Sun Dec 04, 2005 6:03 pm ]
Post subject:	frontend crashes - diagnosis
Since adding a second nova-t pci (909) card I am seeing more frontend crashes. Is there any technique to diagnose the cause ? I've just started the frontend from an xterm - but it has not crashed yet (typical ) I had thought it was possible due to using the Retro theme - but Ive see it crash a few times since switching back to blue. Im using a athlon 1.4 with 256mb ram and r5a16. Crashes seem to occur when im doing something in the ui - like leaving watching a recording.

Author:	cesman [ Sun Dec 04, 2005 8:06 pm ]
Post subject:
In addition, take a look at the backend log.

Author:	tjc [ Sun Dec 04, 2005 11:03 pm ]
Post subject:
This weeks favorite pages! The first one is the top level page, the second one has more detail for looking at the logs. http://www.knoppmythwiki.org/index.php?page=TroubleShooting http://www.knoppmythwiki.org/index.php?page=CheckingLogFiles

Author:	declanh [ Mon Dec 05, 2005 5:36 pm ]
Post subject:
ok - it crashed again - at 22:59 looking in mythbackend.log i see 2005-12-05 22:59:27.429 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-05 22:59:35.117 Reschedule requested for id 0. 2005-12-05 22:59:35.713 Scheduled 59 items in 0.6 = 0.03 match + 0.56 place tail .xsession-errors shows 2005-12-05 22:49:12.155 prebuffering pause 2005-12-05 22:49:12.171 prebuffering pause 2005-12-05 22:49:12.187 prebuffering pause 2005-12-05 22:49:12.203 prebuffering pause 2005-12-05 22:49:12.219 prebuffering pause 2005-12-05 22:49:12.235 prebuffering pause 2005-12-05 22:49:12.255 prebuffering pause 2005-12-05 22:59:15.882 Changing from WatchingPreRecorded to None 2005-12-05 22:59:15.937 Changing from None to None 2005-12-05 22:59:25.138 prebuffering pause nothing much evident there either tail /var/log/messages Dec 5 20:08:01 decivo -- MARK -- Dec 5 20:28:02 decivo -- MARK -- Dec 5 20:48:02 decivo -- MARK -- Dec 5 21:08:02 decivo -- MARK -- Dec 5 21:28:03 decivo -- MARK -- Dec 5 21:48:03 decivo -- MARK -- Dec 5 22:08:03 decivo -- MARK -- Dec 5 22:28:04 decivo -- MARK -- Dec 5 22:48:04 decivo -- MARK -- Dec 5 23:08:05 decivo -- MARK -- nothing there either tail /var/log/syslog Dec 5 22:17:01 decivo /USR/SBIN/CRON[4202]: (root) CMD ( run-parts --report /etc/cron.hourly) Dec 5 22:28:04 decivo -- MARK -- Dec 5 22:30:03 decivo kernel: cx88[0]/2: cx8802_restart_queue Dec 5 22:30:03 decivo kernel: cx88[0]/2: cx8802_restart_queue: queue is empty Dec 5 22:30:04 decivo kernel: cx88[0]/2: queue is empty - first active Dec 5 22:30:04 decivo kernel: cx88[0]/2: cx8802_start_dma 752 Dec 5 22:30:04 decivo kernel: cx88[0]/2: setting the interrupt mask Dec 5 22:30:04 decivo kernel: cx88[0]/2: [c6cfb360/0] cx8802_buf_queue - first active Dec 5 22:48:04 decivo -- MARK -- Dec 5 23:08:05 decivo -- MARK -- tail /var/log/daemon.log Dec 5 19:06:51 decivo init: Switching to runlevel: 6 Dec 5 19:06:55 decivo mysqld[2852]: 051205 19:06:55 /usr/sbin/mysqld: Normal shutdown Dec 5 19:06:55 decivo mysqld[2852]: Dec 5 19:06:55 decivo mysqld[2852]: 051205 19:06:55 /usr/sbin/mysqld: Shutdown Complete Dec 5 19:06:55 decivo mysqld[2852]: Dec 5 19:06:55 decivo mysqld_safe[10309]: ended Dec 5 19:08:03 decivo mysqld_safe[2868]: started Dec 5 19:08:04 decivo mysqld[2871]: /usr/sbin/mysqld: ready for connections. Dec 5 19:08:04 decivo mysqld[2871]: Version: '4.0.22-log' socket: '/var/run/mysqld/mysqld.sock' port: 3306 Source distribution Dec 5 19:08:04 decivo /etc/mysql/debian-start[2888]: Checking for crashed MySQL tables. is it the *2005-12-05 22:59:27.429 IOBOUND - blocking in ThreadedFileWriter::Write()* in mythbackend thats causing the crash ? I also spotted 2005-12-05 22:42:15.319 Realtime priority would require SUID as root. in .xsession-errors - is this significant ? is this possibly due to the addition of a second nova-t pci tuner card at the weekend ? thanks in advance

Author:	tjc [ Mon Dec 05, 2005 7:15 pm ]
Post subject:
declanh wrote: is it the *2005-12-05 22:59:27.429 IOBOUND - blocking in ThreadedFileWriter::Write()* in mythbackend thats causing the crash ? That or it was caused by the crash. declanh wrote: 2005-12-05 22:42:15.319 Realtime priority would require SUID as root. in .xsession-errors - is this significant ? No.

LinHES Forums http://forum.linhes.org/

frontend crashes - diagnosis http://forum.linhes.org/viewtopic.php?f=6&t=7218	Page 1 of 1

Author:	mihanson [ Mon Dec 12, 2005 3:16 pm ]
Post subject:
After fixing my swap problem I am getting IOBOUND errors, too. I only get them when using my pcHDTV HD-3000 as the source tuner for OTA HDTV broadcasts. After a few seconds of live HDTV, I get a lot of stuttering, pixelation and finnaly a freeze. My PVR-150 with DISH Network has no problems. I found this thread on the MythTV mailing list archive. What I got out of it was (1) check to see if your DMA is enabled and (2) it's possible that the recording file writer buffer size may be too small. (See about 2/3 way down on the MythTV mailing list thread referenced above). I looked for the file Chris Pinkham referred to, but it does not seem to be in the same place nor the same file name on Knoppmyth. Does anyone know where the "RingBuffer.cpp" file is? I also found this thread from OpenSubscriber. I'm guessing this is not the same as cache which we can easily adjust in the Knoppmyth Setup GUI. For kicks, here's a sampling from my logs: Code: # pico /var/log/mythtv/backend.log 2005-12-11 18:46:18.167 DVB#0 Recorder: Card opened successfully (using PS mode). 2005-12-11 18:46:18.183 DVB#0 Data read from DMX - This is for debugging with transform.c 2005-12-11 18:46:46.714 DVB#0 DVB signal 93b5 \| snr f655 \| ber 0 \| unc 0 2005-12-11 18:46:46.725 DVB#0 Status: LOCK. 2005-12-11 18:46:46.730 DVB#0 Multiplex Locked 2005-12-11 18:46:47.622 DVB#0 Successfully tuned to channel 51. 2005-12-11 18:46:47.649 DVB#0 Data read from DMX - This is for debugging with transform.c 2005-12-11 18:54:59.245 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:10.514 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:12.857 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:18.584 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:24.738 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:35.532 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:41.339 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:47.774 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:55:58.664 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:06.168 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:17.987 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:24.334 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:31.200 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:39.201 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:43.870 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:56:49.042 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:57:06.688 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:57:15.616 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:57:29.902 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:58:01.680 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:58:08.068 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:58:39.585 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 18:58:27.714 DVB#0 ERROR - Reading Section. (75) Value too large for defined data type 2005-12-11 18:59:28.016 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 19:00:34.160 IOBOUND - blocking in ThreadedFileWriter::Write() 2005-12-11 19:00:10.243 DVB#0 ERROR - Reading Section. (75) Value too large for defined data type Code: # pico /var/log/syslog Dec 11 18:57:45 mythbox kernel: spurious 8259A interrupt: IRQ7. Dec 11 18:59:46 mythbox kernel: oom-killer: gfp_mask=0x201d2, order=0 Dec 11 18:59:50 mythbox kernel: Mem-info: Dec 11 18:59:52 mythbox kernel: DMA per-cpu: Dec 11 18:59:52 mythbox kernel: cpu 0 hot: low 2, high 6, batch 1 used:4 Dec 11 18:59:52 mythbox kernel: cpu 0 cold: low 0, high 2, batch 1 used:0 Dec 11 18:59:52 mythbox kernel: Normal per-cpu: Dec 11 18:59:52 mythbox kernel: cpu 0 hot: low 62, high 186, batch 31 used:158 Dec 11 18:59:52 mythbox kernel: cpu 0 cold: low 0, high 62, batch 31 used:61 Dec 11 18:59:52 mythbox kernel: HighMem per-cpu: empty Dec 11 18:59:52 mythbox kernel: Free pages: 4764kB (0kB HighMem) Dec 11 18:59:52 mythbox kernel: Active:57741 inactive:57337 dirty:0 writeback:0 unstable:0 free:1191 slab:5894 mapped:115100 pagetables:506 Dec 11 18:59:52 mythbox kernel: DMA free:2068kB min:88kB low:108kB high:132kB active:4856kB inactive:4380kB present:16384kB pages_scanned:11377 all_unreclaimable? yes Dec 11 18:59:52 mythbox kernel: lowmem_reserve[]: 0 495 495 Dec 11 18:59:52 mythbox kernel: Normal free:2696kB min:2800kB low:3500kB high:4200kB active:226108kB inactive:224968kB present:507072kB pages_scanned:562069 all_unreclaimable? yes Dec 11 18:59:52 mythbox kernel: lowmem_reserve[]: 0 0 0 Dec 11 18:59:52 mythbox kernel: HighMem free:0kB min:128kB low:160kB high:192kB active:0kB inactive:0kB present:0kB pages_scanned:0 all_unreclaimable? no Dec 11 18:59:52 mythbox kernel: lowmem_reserve[]: 0 0 0 Dec 11 18:59:52 mythbox kernel: DMA: 14kB 08kB 116kB 032kB 064kB 0128kB 0256kB 0512kB 01024kB 12048kB 04096kB = 2068kB Dec 11 18:59:52 mythbox kernel: Normal: 04kB 18kB 016kB 032kB 064kB 1128kB 0256kB 1512kB 01024kB 12048kB 04096kB = 2696kB Dec 11 18:59:52 mythbox kernel: HighMem: empty Dec 11 18:59:52 mythbox kernel: Swap cache: add 191753, delete 191604, find 229/569, race 0+0 Dec 11 18:59:52 mythbox kernel: Free swap = 0kB Dec 11 18:59:52 mythbox kernel: Total swap = 755044kB Dec 11 18:59:52 mythbox kernel: Free swap: 0kB Dec 11 18:59:52 mythbox kernel: 130864 pages of RAM Dec 11 18:59:52 mythbox kernel: 0 pages of HIGHMEM Dec 11 18:59:52 mythbox kernel: 2696 reserved pages Dec 11 18:59:52 mythbox kernel: 3063 pages shared Dec 11 18:59:52 mythbox kernel: 149 pages swap cached Dec 11 18:59:52 mythbox kernel: 0 pages dirty Dec 11 18:59:52 mythbox kernel: 0 pages writeback Dec 11 18:59:52 mythbox kernel: 115100 pages mapped Dec 11 18:59:52 mythbox kernel: 5894 pages slab Dec 11 18:59:52 mythbox kernel: 506 pages pagetables Dec 11 18:59:52 mythbox kernel: Out of Memory: Killed process 4699 (ssh-agent).

Page 1 of 1	All times are UTC - 6 hours
Powered by phpBB® Forum Software © phpBB Group http://www.phpbb.com/