Ticket #11206: mythbackend-crash_at_boot.txt

File mythbackend-crash_at_boot.txt, 11.7 KB (added by bob.havlin@…, 11 years ago)

kern.log tail

Line 
1mythbackend --version
2Please attach all output as a file in bug reports.
3MythTV Version : v0.26.0-28-ge3087dd
4MythTV Branch : fixes/0.26
5Network Protocol : 75
6Library API : 0.26.20120822-1
7QT Version : 4.8.3
8Options compiled in:
9 linux profile use_hidesyms using_alsa using_oss using_pulse using_pulseoutput using_backend using_bindings_perl using_bindings_python using_bindings_php using_crystalhd using_dvb using_firewire using_frontend using_hdhomerun using_ceton using_hdpvr using_iptv using_ivtv using_joystick_menu using_libcec using_libcrypto using_libdns_sd using_libxml2 using_lirc using_mheg using_opengl_video using_qtwebkit using_qtscript using_qtdbus using_v4l2 using_x11 using_xrandr using_xv using_bindings_perl using_bindings_python using_bindings_php using_mythtranscode using_opengl using_vaapi using_vdpau using_ffmpeg_threads using_live using_mheg using_libass using_libxml2
10
11
12tail /var/log/kern.log
13...
14Oct 30 08:30:27 mythbox kernel: [   17.220203] lirc_dev: IR Remote Control driver registered, major 250
15Oct 30 08:30:27 mythbox kernel: [   17.221995] lirc_zilog: module is from the staging directory, the quality is unknown, you have been warned.
16Oct 30 08:30:27 mythbox kernel: [   17.222794] lirc_zilog: Zilog/Hauppauge IR driver initializing
17Oct 30 08:30:27 mythbox kernel: [   17.225861] lirc_zilog: initialization complete
18Oct 30 08:30:27 mythbox kernel: [   17.396210] lirc_zilog: probing IR Tx on Hauppage HD PVR I2C (i2c-0)
19Oct 30 08:30:27 mythbox kernel: [   17.404198] i2c i2c-0: >lirc_dev: driver lirc_zilog registered at minor = 0
20Oct 30 08:30:27 mythbox kernel: [   17.404203] lirc_zilog: IR unit on Hauppage HD PVR I2C (i2c-0) registered as lirc0 and ready
21Oct 30 08:30:27 mythbox kernel: [   17.404205] lirc_zilog: probe of IR Tx on Hauppage HD PVR I2C (i2c-0) done
22Oct 30 08:30:27 mythbox kernel: [   17.404244] hdpvr 1-1:1.0: device now attached to video0
23Oct 30 08:30:27 mythbox kernel: [   17.404276] usbcore: registered new interface driver hdpvr
24Oct 30 08:30:27 mythbox kernel: [   17.433072] intel8x0_measure_ac97_clock: measured 56740 usecs (7680 samples)
25Oct 30 08:30:27 mythbox kernel: [   17.433075] intel8x0: measured clock 135354 rejected
26Oct 30 08:30:27 mythbox kernel: [   17.502217] vboxsf: Successfully loaded version 4.2.0 (interface 0x00010004)
27Oct 30 08:30:27 mythbox kernel: [   17.792267] intel8x0_measure_ac97_clock: measured 55992 usecs (7680 samples)
28Oct 30 08:30:27 mythbox kernel: [   17.792271] intel8x0: measured clock 137162 rejected
29Oct 30 08:30:27 mythbox kernel: [   17.792273] intel8x0: clocking to 48000
30Oct 30 08:30:28 mythbox kernel: [   17.843786] vesafb: mode is 640x480x32, linelength=2560, pages=0
31Oct 30 08:30:28 mythbox kernel: [   17.843790] vesafb: scrolling: redraw
32Oct 30 08:30:28 mythbox kernel: [   17.843792] vesafb: Truecolor: size=8:8:8:8, shift=24:16:8:0
33Oct 30 08:30:28 mythbox kernel: [   17.843805] mtrr: your processor doesn't support write-combining
34Oct 30 08:30:28 mythbox kernel: [   17.843890] vesafb: framebuffer at 0xe0000000, mapped to 0xffffc90002280000, using 1216k, total 1216k
35Oct 30 08:30:28 mythbox kernel: [   17.844590] Console: switching to colour frame buffer device 80x30
36Oct 30 08:30:28 mythbox kernel: [   17.844609] fb0: VESA VGA frame buffer device
37Oct 30 08:30:28 mythbox kernel: [   18.090810] sf_read_super_aux err=-71
38Oct 30 08:30:28 mythbox kernel: [   18.099333] sf_read_super_aux err=-71
39Oct 30 08:30:28 mythbox kernel: [   18.111628] sf_read_super_aux err=-71
40Oct 30 08:30:30 mythbox kernel: [   20.582080] lirc_zilog: Zilog/Hauppauge IR blaster firmware version 2.1.0 loaded
41Oct 30 08:30:32 mythbox kernel: [   22.210950] ------------[ cut here ]------------
42Oct 30 08:30:32 mythbox kernel: [   22.210958] WARNING: at /build/buildd/linux-3.5.0/fs/dcache.c:1375 d_set_d_op+0x7c/0xb0()
43Oct 30 08:30:32 mythbox kernel: [   22.210960] Hardware name: VirtualBox
44Oct 30 08:30:32 mythbox kernel: [   22.210961] Modules linked in: vesafb vboxsf(O) lirc_zilog(C) lirc_dev ppdev snd_intel8x0 snd_ac97_codec ac97_bus joydev snd_pcm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq hdpvr v4l2_common snd_timer snd_seq_device videodev microcode snd psmouse serio_raw parport_pc soundcore snd_page_alloc mac_hid vboxguest(O) i2c_piix4 lp parport hid_generic usbhid hid e1000
45Oct 30 08:30:32 mythbox kernel: [   22.210983] Pid: 1837, comm: mythbackend Tainted: G         C O 3.5.0-17-generic #28-Ubuntu
46Oct 30 08:30:32 mythbox kernel: [   22.210984] Call Trace:
47Oct 30 08:30:32 mythbox kernel: [   22.210990]  [<ffffffff81051c4f>] warn_slowpath_common+0x7f/0xc0
48Oct 30 08:30:32 mythbox kernel: [   22.210992]  [<ffffffff81051caa>] warn_slowpath_null+0x1a/0x20
49Oct 30 08:30:32 mythbox kernel: [   22.210994]  [<ffffffff811964cc>] d_set_d_op+0x7c/0xb0
50Oct 30 08:30:32 mythbox kernel: [   22.210998]  [<ffffffffa01e135b>] sf_instantiate.isra.1+0xcb/0x180 [vboxsf]
51Oct 30 08:30:32 mythbox kernel: [   22.211001]  [<ffffffffa01e15bc>] sf_create_aux+0x1ac/0x250 [vboxsf]
52Oct 30 08:30:32 mythbox kernel: [   22.211004]  [<ffffffffa01e1693>] sf_create+0x13/0x20 [vboxsf]
53Oct 30 08:30:32 mythbox kernel: [   22.211006]  [<ffffffff8118eb04>] vfs_create+0xb4/0x120
54Oct 30 08:30:32 mythbox kernel: [   22.211007]  [<ffffffff8118ff4b>] do_last+0x8ab/0xa10
55Oct 30 08:30:32 mythbox kernel: [   22.211011]  [<ffffffff812ec60c>] ? apparmor_file_alloc_security+0x2c/0x60
56Oct 30 08:30:32 mythbox kernel: [   22.211013]  [<ffffffff81191399>] path_openat+0xd9/0x430
57Oct 30 08:30:32 mythbox kernel: [   22.211016]  [<ffffffffa01e2bde>] ? sf_inode_revalidate+0xae/0xd0 [vboxsf]
58Oct 30 08:30:32 mythbox kernel: [   22.211018]  [<ffffffff81191811>] do_filp_open+0x41/0xa0
59Oct 30 08:30:32 mythbox kernel: [   22.211020]  [<ffffffff8119e906>] ? alloc_fd+0xc6/0x110
60Oct 30 08:30:32 mythbox kernel: [   22.211023]  [<ffffffff81181355>] do_sys_open+0xf5/0x230
61Oct 30 08:30:32 mythbox kernel: [   22.211036]  [<ffffffff811814b1>] sys_open+0x21/0x30
62Oct 30 08:30:32 mythbox kernel: [   22.211041]  [<ffffffff81689d29>] system_call_fastpath+0x16/0x1b
63Oct 30 08:30:32 mythbox kernel: [   22.211042] ---[ end trace 52ccb644471ff58c ]---
64Oct 30 08:30:32 mythbox kernel: [   22.211043] ------------[ cut here ]------------
65Oct 30 08:30:32 mythbox kernel: [   22.211045] WARNING: at /build/buildd/linux-3.5.0/fs/dcache.c:1379 d_set_d_op+0x9f/0xb0()
66Oct 30 08:30:32 mythbox kernel: [   22.211046] Hardware name: VirtualBox
67Oct 30 08:30:32 mythbox kernel: [   22.211046] Modules linked in: vesafb vboxsf(O) lirc_zilog(C) lirc_dev ppdev snd_intel8x0 snd_ac97_codec ac97_bus joydev snd_pcm snd_seq_midi snd_rawmidi snd_seq_midi_event snd_seq hdpvr v4l2_common snd_timer snd_seq_device videodev microcode snd psmouse serio_raw parport_pc soundcore snd_page_alloc mac_hid vboxguest(O) i2c_piix4 lp parport hid_generic usbhid hid e1000
68Oct 30 08:30:32 mythbox kernel: [   22.211062] Pid: 1837, comm: mythbackend Tainted: G        WC O 3.5.0-17-generic #28-Ubuntu
69Oct 30 08:30:32 mythbox kernel: [   22.211063] Call Trace:
70Oct 30 08:30:32 mythbox kernel: [   22.211065]  [<ffffffff81051c4f>] warn_slowpath_common+0x7f/0xc0
71Oct 30 08:30:32 mythbox kernel: [   22.211068]  [<ffffffff81051caa>] warn_slowpath_null+0x1a/0x20
72Oct 30 08:30:32 mythbox kernel: [   22.211069]  [<ffffffff811964ef>] d_set_d_op+0x9f/0xb0
73Oct 30 08:30:32 mythbox kernel: [   22.211072]  [<ffffffffa01e135b>] sf_instantiate.isra.1+0xcb/0x180 [vboxsf]
74Oct 30 08:30:32 mythbox kernel: [   22.211075]  [<ffffffffa01e15bc>] sf_create_aux+0x1ac/0x250 [vboxsf]
75Oct 30 08:30:32 mythbox kernel: [   22.211077]  [<ffffffffa01e1693>] sf_create+0x13/0x20 [vboxsf]
76Oct 30 08:30:32 mythbox kernel: [   22.211079]  [<ffffffff8118eb04>] vfs_create+0xb4/0x120
77Oct 30 08:30:32 mythbox kernel: [   22.211081]  [<ffffffff8118ff4b>] do_last+0x8ab/0xa10
78Oct 30 08:30:32 mythbox kernel: [   22.211083]  [<ffffffff812ec60c>] ? apparmor_file_alloc_security+0x2c/0x60
79Oct 30 08:30:32 mythbox kernel: [   22.211085]  [<ffffffff81191399>] path_openat+0xd9/0x430
80Oct 30 08:30:32 mythbox kernel: [   22.211087]  [<ffffffffa01e2bde>] ? sf_inode_revalidate+0xae/0xd0 [vboxsf]
81Oct 30 08:30:32 mythbox kernel: [   22.211089]  [<ffffffff81191811>] do_filp_open+0x41/0xa0
82Oct 30 08:30:32 mythbox kernel: [   22.211091]  [<ffffffff8119e906>] ? alloc_fd+0xc6/0x110
83Oct 30 08:30:32 mythbox kernel: [   22.211093]  [<ffffffff81181355>] do_sys_open+0xf5/0x230
84Oct 30 08:30:32 mythbox kernel: [   22.211095]  [<ffffffff811814b1>] sys_open+0x21/0x30
85Oct 30 08:30:32 mythbox kernel: [   22.211097]  [<ffffffff81689d29>] system_call_fastpath+0x16/0x1b
86Oct 30 08:30:32 mythbox kernel: [   22.211098] ---[ end trace 52ccb644471ff58d ]---
87
88
89tail /var/log/mythtv/mythbackend.log (during relevant time)
90
91Oct 30 08:30:30 mythbox mythlogserver: mythbackend[1837]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [::1]:6544
92Oct 30 08:30:30 mythbox mythlogserver: mythbackend[1837]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [fe80::a00:27ff:fe95:883b%eth1]:6544
93Oct 30 08:30:32 mythbox mythlogserver: mythbackend[1837]: I CoreContext main_helpers.cpp:645 (run_backend) Main::Registering HttpStatus Extension
94Oct 30 08:30:32 mythbox mythlogserver: mythbackend[1837]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 127.0.0.1:6543
95Oct 30 08:30:32 mythbox mythlogserver: mythbackend[1837]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP 192.168.2.128:6543
96Oct 30 08:30:32 mythbox mythlogserver: mythbackend[1837]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [::1]:6543
97Oct 30 08:30:32 mythbox mythlogserver: mythbackend[1837]: I CoreContext serverpool.cpp:395 (listen) Listening on TCP [fe80::a00:27ff:fe95:883b%eth1]:6543
98Oct 30 08:30:32 mythbox mythlogserver: mythbackend[1837]: N CoreContext autoexpire.cpp:264 (CalcParams) AutoExpire: CalcParams(): Max required Free Space: 1.0 GB w/freq: 15 min
99Oct 30 08:30:33 mythbox mythlogserver: mythbackend[1837]: I CoreContext bonjourregister.cpp:100 (BonjourCallback) Bonjour: Service registration complete: name 'Mythbackend on mythbox' type '_mythbackend-master._tcp.' domain: 'local.'
100Oct 30 08:30:33 mythbox mythlogserver: mythbackend[1837]: I ProcessRequest mainserver.cpp:1445 (HandleAnnounce) adding: mythfe as a slave backend server
101Oct 30 08:30:33 mythbox mythlogserver: mythbackend[1837]: I Scheduler scheduler.cpp:2126 (HandleReschedule) Reschedule requested for MATCH 0 0 0 - SchedulerInit
102Oct 30 08:30:37 mythbox mythlogserver: mythbackend[1837]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
103Oct 30 08:30:37 mythbox mythlogserver: mythbackend[1837]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: mythfe as a client (events: 0)
104Oct 30 08:30:37 mythbox mythlogserver: mythbackend[1837]: I ProcessRequest mainserver.cpp:1395 (HandleAnnounce) MainServer::ANN Monitor
105Oct 30 08:30:37 mythbox mythlogserver: mythbackend[1837]: I ProcessRequest mainserver.cpp:1397 (HandleAnnounce) adding: mythfe as a client (events: 1)
106Oct 30 08:30:40 mythbox mythlogserver: mythbackend[1837]: I HouseKeeping housekeeper.cpp:221 (RunHouseKeeping) Running housekeeping thread
107Oct 30 08:30:40 mythbox mythlogserver: mythbackend[1837]: I HouseKeeping housekeeper.cpp:309 (RunHouseKeeping) Running mythfilldatabase
108Oct 30 08:30:42 mythbox mythlogserver: mythbackend[1837]: I Scheduler scheduler.cpp:2239 (HandleReschedule) Scheduled 300 items in 9.0 = 8.66 match + 0.28 check + 0.10 place
109Oct 30 08:30:43 mythbox mythlogserver: mythbackend[1837]: I Scheduler scheduler.cpp:2306 (HandleRunSchedulerStartup) Scheduler: Seem to be woken up by USER
110Oct 30 08:31:09 mythbox mythlogserver: mythbackend[1837]: E Update mythsocket.cpp:520 (readStringList) MythSocket(fbee20:67): readStringList: Error, timed out after 30000 ms.
111Oct 30 08:31:09 mythbox mythlogserver: mythbackend[1837]: E Update mainserver.cpp:5738 (connectionClosed) Slave backend: mythfe no longer connected
112