Bug 700932

Summary: dm_task_run failed, errno = 9, Bad file descriptor
Product: Red Hat Enterprise Linux 5 Reporter: Corey Marthaler <cmarthal>
Component: lvm2Assignee: Jonathan Earl Brassow <jbrassow>
Status: CLOSED WONTFIX QA Contact: Corey Marthaler <cmarthal>
Severity: low Docs Contact:
Priority: low    
Version: 5.6CC: agk, dwysocha, heinzm, jbrassow, mbroz, prajnoha, prockai, thornber, zkabelac
Target Milestone: rc   
Target Release: ---   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of:
: 726124 (view as bug list) Environment:
Last Closed: 2012-04-20 13:44:58 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Bug Depends On:    
Bug Blocks: 726124, 807971    

Description Corey Marthaler 2011-04-29 19:59:58 UTC 
Description of problem:
I'm seeing this error quite a bit while doing mirror failure testing.

[root@taft-01 ~]# grep dm_task_run /var/log/messages | grep failed | grep 9,
Apr 29 12:12:52 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor
Apr 29 12:17:23 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor
Apr 29 13:09:49 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor
Apr 29 13:14:00 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor
Apr 29 13:28:37 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor
Apr 29 13:33:29 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor




Apr 29 13:28:25 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2 for events.
Apr 29 13:28:25 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:28:25 taft-01 lvm[7300]: Trying to up-convert to 2 images, 2 logs.
Apr 29 13:28:30 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2_mlog for events.
Apr 29 13:28:30 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:28:35 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2_mlog for events.
Apr 29 13:28:35 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:28:36 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2 for events.
Apr 29 13:28:36 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:28:36 taft-01 lvm[7300]: 2 missing and now unallocated Physical Volumes removed from VG.
Apr 29 13:28:36 taft-01 lvm[7300]: Repair of mirrored LV helter_skelter/syncd_sec_leg_sec_log_3legs_2logs_2 finished successfully.
Apr 29 13:28:36 taft-01 lvm[7300]: Secondary mirror device 253:6 has failed (D).
Apr 29 13:28:36 taft-01 lvm[7300]: Device failure in helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_1.
Apr 29 13:28:36 taft-01 lvm[7300]: syncd_sec_leg_sec_log_3legs_2logs_1 is consistent. Nothing to repair.
Apr 29 13:28:36 taft-01 lvm[7300]: Repair of mirrored LV helter_skelter/syncd_sec_leg_sec_log_3legs_2logs_1 finished successfully.
Apr 29 13:28:36 taft-01 lvm[7300]: helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_1 is now in-sync.
Apr 29 13:28:36 taft-01 lvm[7300]: helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_1_mlog is now in-sync.
Apr 29 13:28:36 taft-01 lvm[7300]: helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_1 is now in-sync.
Apr 29 13:28:36 taft-01 lvm[7300]: Secondary mirror device 253:13 has failed (D).
Apr 29 13:28:36 taft-01 lvm[7300]: Device failure in helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2.
Apr 29 13:28:37 taft-01 lvm[7300]: syncd_sec_leg_sec_log_3legs_2logs_2 is consistent. Nothing to repair.
Apr 29 13:28:37 taft-01 lvm[7300]: Repair of mirrored LV helter_skelter/syncd_sec_leg_sec_log_3legs_2logs_2 finished successfully.
Apr 29 13:28:37 taft-01 lvm[7300]: helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2 is now in-sync.
Apr 29 13:28:37 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor
Apr 29 13:28:37 taft-01 lvm[7300]: helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2_mlog is now in-sync.
Apr 29 13:28:37 taft-01 lvm[7300]: helter_skelter-syncd_sec_leg_sec_log_3legs_2logs_2 is now in-sync.





Apr 29 13:33:15 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_primary_log_2legs_2logs_2 for events.
Apr 29 13:33:15 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:33:15 taft-01 lvm[7300]: Trying to up-convert to 2 images, 2 logs.
Apr 29 13:33:22 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_primary_log_2legs_2logs_2_mlog for events.
Apr 29 13:33:22 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:33:29 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_primary_log_2legs_2logs_2_mlog for events.
Apr 29 13:33:29 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:33:29 taft-01 lvm[7300]: Monitoring mirror device helter_skelter-syncd_primary_log_2legs_2logs_2 for events.
Apr 29 13:33:29 taft-01 lvm[7300]: Another thread is handling an event. Waiting...
Apr 29 13:33:29 taft-01 lvm[7300]: 1 missing and now unallocated Physical Volumes removed from VG.
Apr 29 13:33:29 taft-01 lvm[7300]: Repair of mirrored LV helter_skelter/syncd_primary_log_2legs_2logs_2 finished successfully.
Apr 29 13:33:29 taft-01 lvm[7300]: helter_skelter-syncd_primary_log_2legs_2logs_1 is now in-sync.
Apr 29 13:33:29 taft-01 lvm[7300]: helter_skelter-syncd_primary_log_2legs_2logs_1_mlog is now in-sync.
Apr 29 13:33:29 taft-01 lvm[7300]: helter_skelter-syncd_primary_log_2legs_2logs_1 is now in-sync.
Apr 29 13:33:29 taft-01 lvm[7300]: helter_skelter-syncd_primary_log_2legs_2logs_2 is now in-sync.
Apr 29 13:33:29 taft-01 lvm[7300]: helter_skelter-syncd_primary_log_2legs_2logs_2_mlog is now in-sync.
Apr 29 13:33:29 taft-01 lvm[7300]: helter_skelter-syncd_primary_log_2legs_2logs_2 is now in-sync.
Apr 29 13:33:29 taft-01 lvm[7300]: dm_task_run failed, errno = 9, Bad file descriptor




Version-Release number of selected component (if applicable):
2.6.18-256.el5

lvm2-2.02.84-3.el5    BUILT: Wed Apr 27 03:42:24 CDT 2011
lvm2-cluster-2.02.84-3.el5    BUILT: Wed Apr 27 03:42:43 CDT 2011
device-mapper-1.02.63-2.el5    BUILT: Fri Mar  4 10:23:17 CST 2011
device-mapper-event-1.02.63-2.el5    BUILT: Fri Mar  4 10:23:17 CST 2011
cmirror-1.1.39-10.el5    BUILT: Wed Sep  8 16:32:05 CDT 2010
kmod-cmirror-0.1.22-3.el5    BUILT: Tue Dec 22 13:39:47 CST 2009


How reproducible:
Often
Comment 1 Milan Broz 2011-06-06 11:50:02 UTC 
Is it still issue with the latest rpms?
Comment 2 Corey Marthaler 2011-06-06 22:25:56 UTC 
Yes.

Jun  6 16:55:24 taft-01 lvm[6413]: No longer monitoring mirror device helter_skelter-syncd_secondary_4legs_1_mimagetmp_4 for events.
Jun  6 16:55:24 taft-01 lvm[6413]: helter_skelter-syncd_secondary_4legs_1 is now in-sync.
Jun  6 16:55:24 taft-01 lvm[6413]: dm_task_run failed, errno = 9, Bad file descriptor
Jun  6 16:55:24 taft-01 lvm[6413]: helter_skelter-syncd_secondary_4legs_1 is now in-sync.
Jun  6 16:55:26 taft-01 lvm[6413]: No longer monitoring mirror device helter_skelter-syncd_secondary_4legs_1 for events.


Version:
2.6.18-261.el5

lvm2-2.02.84-4.el5    BUILT: Thu Jun  2 05:04:32 CDT 2011
lvm2-cluster-2.02.84-3.el5    BUILT: Wed Apr 27 03:42:43 CDT 2011
device-mapper-1.02.63-3.el5    BUILT: Thu May 19 08:09:22 CDT 2011
device-mapper-event-1.02.63-3.el5    BUILT: Thu May 19 08:09:22 CDT 2011
cmirror-1.1.39-10.el5    BUILT: Wed Sep  8 16:32:05 CDT 2010
kmod-cmirror-0.1.22-3.el5    BUILT: Tue Dec 22 13:39:47 CST 2009
Comment 8 RHEL Program Management 2012-04-02 10:22:29 UTC 
This request was evaluated by Red Hat Product Management for inclusion
in a Red Hat Enterprise Linux release.  Product Management has
requested further review of this request by Red Hat Engineering, for
potential inclusion in a Red Hat Enterprise Linux release for currently
deployed products.  This request is not yet committed for inclusion in
a release.
Comment 10 RHEL Program Management 2012-04-20 13:44:58 UTC 
Development Management has reviewed and declined this request.
You may appeal this decision by reopening this request.