Re: Endless wait in transport_clear_lun_from_sessions

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi Nick,

Below is the requested dynamic_debug output. I highlighted
the lines you wanted to convert to printk by "[TCM]" prefix.

Few notes:

I'm not sure if it is a 3.1 regression because I've made a number
of changes to my SAN setup since 2.6.39 tests. Now, I have two
targets (san1 and san2) that provide multipathed access to the
same LUN under two different IQNs and two different NPs.
I put a mark in the output to show where the successful san1
TPG cleanup ends and failed san2 TPG removal starts.

Also, I simulate loss of cabling by setting a firewall rule "DROP all
INPUT on port 3260" immediately before the TPG removal sequence.
Don't know if it can have something to do with the bug but I mention
it for completeness.

Now, I want to repeat the test with only one TPG and also get kdb
backtrace of rx/tx threads. Side question -- is there a way to play
with kdb remotely? I don't have physical access to the servers
today.

Martin


Sep 17 22:34:31 goose2 kernel: [11252.235217] Moving to TARG_CONN_STATE_CLEANUP_WAIT.
Sep 17 22:34:31 goose2 kernel: [11252.235224] Performing cleanup for failed iSCSI Connection ID: 0 from iqn.1998-01.com.vmware:hv-esx8
Sep 17 22:34:31 goose2 kernel: [11252.235227] Closing iSCSI connection CID 0 on SID: 1
Sep 17 22:34:31 goose2 kernel: [11252.235236] rx_loop: -512 total_rx: 0
Sep 17 22:34:31 goose2 kernel: [11252.235296] Moving to TARG_CONN_STATE_FREE.
Sep 17 22:34:31 goose2 kernel: [11252.235300] Decremented iSCSI connection count to 0 from node: iqn.1998-01.com.vmware:hv-esx8
Sep 17 22:34:31 goose2 kernel: [11252.235303] Moving to TARG_SESS_STATE_FAILED.
Sep 17 22:34:31 goose2 kernel: [11252.235311] Moving to TARG_SESS_STATE_FREE.
Sep 17 22:34:31 goose2 kernel: [11252.235313] Released iSCSI session from node: iqn.1998-01.com.vmware:hv-esx8
Sep 17 22:34:31 goose2 kernel: [11252.235315] Decremented number of active iSCSI Sessions on iSCSI TPG: 1 to 1
Sep 17 22:34:31 goose2 kernel: [11252.235336] Moving to TARG_CONN_STATE_CLEANUP_WAIT.
Sep 17 22:34:31 goose2 kernel: [11252.235341] Performing cleanup for failed iSCSI Connection ID: 0 from iqn.1998-01.com.vmware:hv-esx9
Sep 17 22:34:31 goose2 kernel: [11252.235345] Closing iSCSI connection CID 0 on SID: 2
Sep 17 22:34:31 goose2 kernel: [11252.235354] rx_loop: 1880, total_rx: 1880, data: 8192
Sep 17 22:34:31 goose2 kernel: [11252.235358] rx_loop: -512 total_rx: 1880
Sep 17 22:34:31 goose2 kernel: [11252.235368] Stopped DataOUT Timer for ITT: 0xd1c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235371] Stopped DataOUT Timer for ITT: 0xd2c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235374] Stopped DataOUT Timer for ITT: 0xd3c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235377] Stopped DataOUT Timer for ITT: 0xd4c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235380] Stopped DataOUT Timer for ITT: 0xd5c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235382] Stopped DataOUT Timer for ITT: 0xd6c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235385] Stopped DataOUT Timer for ITT: 0xd7c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235388] Stopped DataOUT Timer for ITT: 0xd8c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235391] Stopped DataOUT Timer for ITT: 0xd9c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235393] Stopped DataOUT Timer for ITT: 0xdac9e300
Sep 17 22:34:31 goose2 kernel: [11252.235396] Stopped DataOUT Timer for ITT: 0xdbc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235399] Stopped DataOUT Timer for ITT: 0xdcc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235401] Stopped DataOUT Timer for ITT: 0xddc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235404] Stopped DataOUT Timer for ITT: 0xdec9e300
Sep 17 22:34:31 goose2 kernel: [11252.235407] Stopped DataOUT Timer for ITT: 0xdfc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235409] Stopped DataOUT Timer for ITT: 0xe0c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235412] Stopped DataOUT Timer for ITT: 0xe1c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235415] Stopped DataOUT Timer for ITT: 0xe2c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235418] Stopped DataOUT Timer for ITT: 0xe3c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235420] Stopped DataOUT Timer for ITT: 0xe4c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235423] Stopped DataOUT Timer for ITT: 0xe5c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235426] Stopped DataOUT Timer for ITT: 0xe7c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235429] Stopped DataOUT Timer for ITT: 0xe8c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235431] Stopped DataOUT Timer for ITT: 0xe9c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235434] Stopped DataOUT Timer for ITT: 0xeac9e300
Sep 17 22:34:31 goose2 kernel: [11252.235437] Stopped DataOUT Timer for ITT: 0xebc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235440] Stopped DataOUT Timer for ITT: 0xecc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235442] Stopped DataOUT Timer for ITT: 0xedc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235445] Stopped DataOUT Timer for ITT: 0xeec9e300
Sep 17 22:34:31 goose2 kernel: [11252.235447] Stopped DataOUT Timer for ITT: 0xefc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235450] Stopped DataOUT Timer for ITT: 0xf0c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235453] Stopped DataOUT Timer for ITT: 0xf1c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235455] Stopped DataOUT Timer for ITT: 0xf2c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235458] Stopped DataOUT Timer for ITT: 0xf3c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235461] Stopped DataOUT Timer for ITT: 0xf4c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235463] Stopped DataOUT Timer for ITT: 0xf5c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235466] Stopped DataOUT Timer for ITT: 0xf6c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235469] Stopped DataOUT Timer for ITT: 0xf7c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235471] Stopped DataOUT Timer for ITT: 0xf8c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235474] Stopped DataOUT Timer for ITT: 0xf9c9e300
Sep 17 22:34:31 goose2 kernel: [11252.235477] Stopped DataOUT Timer for ITT: 0xfac9e300
Sep 17 22:34:31 goose2 kernel: [11252.235479] Stopped DataOUT Timer for ITT: 0xfbc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235482] Stopped DataOUT Timer for ITT: 0xfcc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235485] Stopped DataOUT Timer for ITT: 0xfdc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235487] Stopped DataOUT Timer for ITT: 0xfec9e300
Sep 17 22:34:31 goose2 kernel: [11252.235490] Stopped DataOUT Timer for ITT: 0xffc9e300
Sep 17 22:34:31 goose2 kernel: [11252.235493] Stopped DataOUT Timer for ITT: 0x00cae300
Sep 17 22:34:31 goose2 kernel: [11252.235495] Stopped DataOUT Timer for ITT: 0x01cae300
Sep 17 22:34:31 goose2 kernel: [11252.235498] Stopped DataOUT Timer for ITT: 0x02cae300
Sep 17 22:34:31 goose2 kernel: [11252.235501] Stopped DataOUT Timer for ITT: 0x03cae300
Sep 17 22:34:31 goose2 kernel: [11252.235503] Stopped DataOUT Timer for ITT: 0x04cae300
Sep 17 22:34:31 goose2 kernel: [11252.235506] Stopped DataOUT Timer for ITT: 0x05cae300
Sep 17 22:34:31 goose2 kernel: [11252.235509] Stopped DataOUT Timer for ITT: 0x06cae300
Sep 17 22:34:31 goose2 kernel: [11252.235511] Stopped DataOUT Timer for ITT: 0x07cae300
Sep 17 22:34:31 goose2 kernel: [11252.235514] Stopped DataOUT Timer for ITT: 0x08cae300
Sep 17 22:34:31 goose2 kernel: [11252.235516] Stopped DataOUT Timer for ITT: 0x09cae300
Sep 17 22:34:31 goose2 kernel: [11252.235519] Stopped DataOUT Timer for ITT: 0x0acae300
Sep 17 22:34:31 goose2 kernel: [11252.235522] Stopped DataOUT Timer for ITT: 0x0bcae300
Sep 17 22:34:31 goose2 kernel: [11252.235590] Updated MaxCmdSN to 0x00e3c9be
Sep 17 22:34:31 goose2 kernel: [11252.235607] Updated MaxCmdSN to 0x00e3c9bf
Sep 17 22:34:31 goose2 kernel: [11252.235621] Updated MaxCmdSN to 0x00e3c9c0
Sep 17 22:34:31 goose2 kernel: [11252.235634] Updated MaxCmdSN to 0x00e3c9c1
Sep 17 22:34:31 goose2 kernel: [11252.235648] Updated MaxCmdSN to 0x00e3c9c2
Sep 17 22:34:31 goose2 kernel: [11252.235663] Updated MaxCmdSN to 0x00e3c9c3
Sep 17 22:34:31 goose2 kernel: [11252.235675] Updated MaxCmdSN to 0x00e3c9c4
Sep 17 22:34:31 goose2 kernel: [11252.235691] Updated MaxCmdSN to 0x00e3c9c5
Sep 17 22:34:31 goose2 kernel: [11252.235706] Updated MaxCmdSN to 0x00e3c9c6
Sep 17 22:34:31 goose2 kernel: [11252.235722] Updated MaxCmdSN to 0x00e3c9c7
Sep 17 22:34:31 goose2 kernel: [11252.235737] Updated MaxCmdSN to 0x00e3c9c8
Sep 17 22:34:31 goose2 kernel: [11252.235754] Updated MaxCmdSN to 0x00e3c9c9
Sep 17 22:34:31 goose2 kernel: [11252.235767] Updated MaxCmdSN to 0x00e3c9ca
Sep 17 22:34:31 goose2 kernel: [11252.235780] Updated MaxCmdSN to 0x00e3c9cb
Sep 17 22:34:31 goose2 kernel: [11252.235793] Updated MaxCmdSN to 0x00e3c9cc
Sep 17 22:34:31 goose2 kernel: [11252.235807] Updated MaxCmdSN to 0x00e3c9cd
Sep 17 22:34:31 goose2 kernel: [11252.235820] Updated MaxCmdSN to 0x00e3c9ce
Sep 17 22:34:31 goose2 kernel: [11252.235834] Updated MaxCmdSN to 0x00e3c9cf
Sep 17 22:34:31 goose2 kernel: [11252.235847] Updated MaxCmdSN to 0x00e3c9d0
Sep 17 22:34:31 goose2 kernel: [11252.235861] Updated MaxCmdSN to 0x00e3c9d1
Sep 17 22:34:31 goose2 kernel: [11252.235874] Updated MaxCmdSN to 0x00e3c9d2
Sep 17 22:34:31 goose2 kernel: [11252.235890] Updated MaxCmdSN to 0x00e3c9d3
Sep 17 22:34:31 goose2 kernel: [11252.235903] Updated MaxCmdSN to 0x00e3c9d4
Sep 17 22:34:31 goose2 kernel: [11252.235916] Updated MaxCmdSN to 0x00e3c9d5
Sep 17 22:34:31 goose2 kernel: [11252.235928] Updated MaxCmdSN to 0x00e3c9d6
Sep 17 22:34:31 goose2 kernel: [11252.235942] Updated MaxCmdSN to 0x00e3c9d7
Sep 17 22:34:31 goose2 kernel: [11252.235953] Updated MaxCmdSN to 0x00e3c9d8
Sep 17 22:34:31 goose2 kernel: [11252.235965] Updated MaxCmdSN to 0x00e3c9d9
Sep 17 22:34:31 goose2 kernel: [11252.235977] Updated MaxCmdSN to 0x00e3c9da
Sep 17 22:34:31 goose2 kernel: [11252.235992] Updated MaxCmdSN to 0x00e3c9db
Sep 17 22:34:31 goose2 kernel: [11252.236005] Updated MaxCmdSN to 0x00e3c9dc
Sep 17 22:34:31 goose2 kernel: [11252.236019] Updated MaxCmdSN to 0x00e3c9dd
Sep 17 22:34:31 goose2 kernel: [11252.236032] Updated MaxCmdSN to 0x00e3c9de
Sep 17 22:34:31 goose2 kernel: [11252.236045] Updated MaxCmdSN to 0x00e3c9df
Sep 17 22:34:31 goose2 kernel: [11252.236056] Updated MaxCmdSN to 0x00e3c9e0
Sep 17 22:34:31 goose2 kernel: [11252.236073] Updated MaxCmdSN to 0x00e3c9e1
Sep 17 22:34:31 goose2 kernel: [11252.236088] Updated MaxCmdSN to 0x00e3c9e2
Sep 17 22:34:31 goose2 kernel: [11252.236101] Updated MaxCmdSN to 0x00e3c9e3
Sep 17 22:34:31 goose2 kernel: [11252.236113] Updated MaxCmdSN to 0x00e3c9e4
Sep 17 22:34:31 goose2 kernel: [11252.236125] Updated MaxCmdSN to 0x00e3c9e5
Sep 17 22:34:31 goose2 kernel: [11252.236137] Updated MaxCmdSN to 0x00e3c9e6
Sep 17 22:34:31 goose2 kernel: [11252.236152] Updated MaxCmdSN to 0x00e3c9e7
Sep 17 22:34:31 goose2 kernel: [11252.236164] Updated MaxCmdSN to 0x00e3c9e8
Sep 17 22:34:31 goose2 kernel: [11252.236175] Updated MaxCmdSN to 0x00e3c9e9
Sep 17 22:34:31 goose2 kernel: [11252.236187] Updated MaxCmdSN to 0x00e3c9ea
Sep 17 22:34:31 goose2 kernel: [11252.236198] Updated MaxCmdSN to 0x00e3c9eb
Sep 17 22:34:31 goose2 kernel: [11252.236210] Updated MaxCmdSN to 0x00e3c9ec
Sep 17 22:34:31 goose2 kernel: [11252.236223] Updated MaxCmdSN to 0x00e3c9ed
Sep 17 22:34:31 goose2 kernel: [11252.236234] Updated MaxCmdSN to 0x00e3c9ee
Sep 17 22:34:31 goose2 kernel: [11252.236245] Updated MaxCmdSN to 0x00e3c9ef
Sep 17 22:34:31 goose2 kernel: [11252.236257] Updated MaxCmdSN to 0x00e3c9f0
Sep 17 22:34:31 goose2 kernel: [11252.236269] Updated MaxCmdSN to 0x00e3c9f1
Sep 17 22:34:31 goose2 kernel: [11252.236281] Updated MaxCmdSN to 0x00e3c9f2
Sep 17 22:34:31 goose2 kernel: [11252.236294] Updated MaxCmdSN to 0x00e3c9f3
Sep 17 22:34:31 goose2 kernel: [11252.236306] Updated MaxCmdSN to 0x00e3c9f4
Sep 17 22:34:31 goose2 kernel: [11252.236317] Updated MaxCmdSN to 0x00e3c9f5
Sep 17 22:34:31 goose2 kernel: [11252.236327] Updated MaxCmdSN to 0x00e3c9f6
Sep 17 22:34:31 goose2 kernel: [11252.236338] Updated MaxCmdSN to 0x00e3c9f7
Sep 17 22:34:31 goose2 kernel: [11252.236399] Moving to TARG_CONN_STATE_FREE.
Sep 17 22:34:31 goose2 kernel: [11252.236403] Decremented iSCSI connection count to 0 from node: iqn.1998-01.com.vmware:hv-esx9
Sep 17 22:34:31 goose2 kernel: [11252.236405] Moving to TARG_SESS_STATE_FAILED.
Sep 17 22:34:31 goose2 kernel: [11252.236417] Moving to TARG_SESS_STATE_FREE.
Sep 17 22:34:31 goose2 kernel: [11252.236420] Released iSCSI session from node: iqn.1998-01.com.vmware:hv-esx9
Sep 17 22:34:31 goose2 kernel: [11252.236422] Decremented number of active iSCSI Sessions on iSCSI TPG: 1 to 0
Sep 17 22:34:31 goose2 kernel: [11252.236426] Released 2 iSCSI Session(s) from Target Portal Group: 1
Sep 17 22:34:31 goose2 kernel: [11252.236429] iSCSI_TPG[1] - Disabled iSCSI Target Portal Group
Sep 17 22:34:31 goose2 kernel: [11252.240083] LIO_Target_ConfigFS: DEREGISTER -> iqn.2011-07.com.zoner:inetstore.vmw0.san1 TPGT: 1 PORTAL: 192.168.114.10:3260
Sep 17 22:34:31 goose2 kernel: [11252.240089] CORE[iqn.2011-07.com.zoner:inetstore.vmw0.san1] - Removed Network Portal: 192.168.114.10:3260,1 on TCP
Sep 17 22:34:31 goose2 kernel: [11252.240117] CORE[0] - Removed Network Portal: 192.168.114.10:3260 on TCP
Sep 17 22:34:31 goose2 kernel: [11252.240120] LIO_Target_ConfigFS: delnpfromtpg done!
Sep 17 22:34:31 goose2 kernel: [11252.240713] LIO_Target_ConfigFS: DEREGISTER -> Releasing TPG
Sep 17 22:34:31 goose2 kernel: [11252.240717] Released 0 iSCSI Session(s) from Target Portal Group: 1
Sep 17 22:34:31 goose2 kernel: [11252.240760] CORE[iqn.2011-07.com.zoner:inetstore.vmw0.san1]_TPG[1] - Deleted iSCSI Target Portal Group

---- end of san1 cleanup ---

Sep 17 22:34:31 goose2 kernel: [11252.240833] Moving to TARG_CONN_STATE_CLEANUP_WAIT.
Sep 17 22:34:31 goose2 kernel: [11252.240836] Performing cleanup for failed iSCSI Connection ID: 0 from iqn.1998-01.com.vmware:hv-esx8
Sep 17 22:34:31 goose2 kernel: [11252.240840] Closing iSCSI connection CID 0 on SID: 1
Sep 17 22:34:31 goose2 kernel: [11252.240847] rx_loop: -512 total_rx: 0
Sep 17 22:34:31 goose2 kernel: [11252.240895] Moving to TARG_CONN_STATE_FREE.
Sep 17 22:34:31 goose2 kernel: [11252.240898] Decremented iSCSI connection count to 0 from node: iqn.1998-01.com.vmware:hv-esx8
Sep 17 22:34:31 goose2 kernel: [11252.240900] Moving to TARG_SESS_STATE_FAILED.
Sep 17 22:34:31 goose2 kernel: [11252.240934] Moving to TARG_SESS_STATE_FREE.
Sep 17 22:34:31 goose2 kernel: [11252.240937] Released iSCSI session from node: iqn.1998-01.com.vmware:hv-esx8
Sep 17 22:34:31 goose2 kernel: [11252.240939] Decremented number of active iSCSI Sessions on iSCSI TPG: 1 to 1
Sep 17 22:34:31 goose2 kernel: [11252.265021] Moving to TARG_CONN_STATE_CLEANUP_WAIT.
Sep 17 22:34:31 goose2 kernel: [11252.265028] Performing cleanup for failed iSCSI Connection ID: 0 from iqn.1998-01.com.vmware:hv-esx9
Sep 17 22:34:31 goose2 kernel: [11252.265032] Closing iSCSI connection CID 0 on SID: 2
Sep 17 22:34:31 goose2 kernel: [11252.265044] rx_loop: -512 total_rx: 0
Sep 17 22:34:31 goose2 kernel: [11252.265079] Updated MaxCmdSN to 0x00e75fc3
Sep 17 22:34:31 goose2 kernel: [11252.265083] [TCM] TGWFT: wait_for_tasks: Stopping ffff8800b8af8d00 ITT: 0x1f5de700 i_state: 1, t_state/def_t_state: 5/0, t_transport_stop = TRUE
Sep 17 22:34:32 goose2 kernel: [11252.501136] [TCM] TGWFT: wait_for_tasks: Stopped wait_for_compltion(&cmd->t_transport_stop_comp) for ITT: 0x1f5de700
Sep 17 22:34:32 goose2 kernel: [11252.501149] Updated MaxCmdSN to 0x00e75fc4
Sep 17 22:34:32 goose2 kernel: [11252.501152] [TCM] TGWFT: wait_for_tasks: Stopping ffff8800b7dfed00 ITT: 0x4c5fe700 i_state: 6, t_state/def_t_state: 5/0, t_transport_stop = TRUE
Sep 17 22:34:32 goose2 kernel: [11252.506286] [TCM] TGWFT: wait_for_tasks: Stopped wait_for_compltion(&cmd->t_transport_stop_comp) for ITT: 0x4c5fe700
Sep 17 22:34:32 goose2 kernel: [11252.506766] Moving to TARG_CONN_STATE_FREE.
Sep 17 22:34:32 goose2 kernel: [11252.506770] Decremented iSCSI connection count to 0 from node: iqn.1998-01.com.vmware:hv-esx9
Sep 17 22:34:32 goose2 kernel: [11252.506773] Moving to TARG_SESS_STATE_FAILED.
Sep 17 22:34:32 goose2 kernel: [11252.506784] Moving to TARG_SESS_STATE_FREE.
Sep 17 22:34:32 goose2 kernel: [11252.506788] Released iSCSI session from node: iqn.1998-01.com.vmware:hv-esx9
Sep 17 22:34:32 goose2 kernel: [11252.506791] Decremented number of active iSCSI Sessions on iSCSI TPG: 1 to 0
Sep 17 22:34:32 goose2 kernel: [11252.506796] Released 2 iSCSI Session(s) from Target Portal Group: 1
Sep 17 22:34:32 goose2 kernel: [11252.506799] iSCSI_TPG[1] - Disabled iSCSI Target Portal Group
Sep 17 22:34:32 goose2 kernel: [11252.506878] LIO_Target_ConfigFS: DEREGISTER -> iqn.2011-07.com.zoner:inetstore.vmw0.san2 TPGT: 1 PORTAL: 192.168.224.10:3260
Sep 17 22:34:32 goose2 kernel: [11252.506883] CORE[iqn.2011-07.com.zoner:inetstore.vmw0.san2] - Removed Network Portal: 192.168.224.10:3260,1 on TCP
Sep 17 22:34:32 goose2 kernel: [11252.506921] CORE[0] - Removed Network Portal: 192.168.224.10:3260 on TCP
Sep 17 22:34:32 goose2 kernel: [11252.506923] LIO_Target_ConfigFS: delnpfromtpg done!
Sep 17 22:34:32 goose2 kernel: [11252.507388] [TCM] TCLFS: SE_LUN[9] - Setting cmd->transport_lun_stop for  ITT: 0xc95fe700
Sep 17 22:34:32 goose2 kernel: [11252.507392] [TCM] TCLFS: SE_LUN[9] - ITT: 0xc95fe700 before transport_lun_wait_for_tasks()
Sep 17 22:34:32 goose2 kernel: [11252.507395] [TCM] TSTFC: ITT[0xc95fe700] - Stopping tasks
Sep 17 22:34:32 goose2 kernel: [11252.507398] [TCM] TLWFT: ConfigFS: cmd: ffff8800b6eef7c0 t_tasks: 0 stop tasks ret: 0
Sep 17 22:34:32 goose2 kernel: [11252.507401] [TCM] TLWFT: ConfigFS: ITT[0xc95fe700] - stopping cmd....
Sep 17 22:37:00 goose2 kernel: [11400.770031] INFO: task liomtpg:17065 blocked for more than 120 seconds.
Sep 17 22:37:00 goose2 kernel: [11400.770034] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 17 22:37:00 goose2 kernel: [11400.770037] liomtpg         D ffff88012ac2e500     0 17065   2651 0x00000004
Sep 17 22:37:00 goose2 kernel: [11400.770042]  ffff8800b723bc48 0000000000000086 ffff8800b723bb0a 0000001000000000
Sep 17 22:37:00 goose2 kernel: [11400.770047]  ffff88012ac2e290 00000000000116c0 ffff8800b723bfd8 ffff8800b723a010
Sep 17 22:37:00 goose2 kernel: [11400.770052]  ffff8800b723bfd8 00000000000116c0 ffff88012b385290 ffff88012ac2e290
Sep 17 22:37:00 goose2 kernel: [11400.770057] Call Trace:
Sep 17 22:37:00 goose2 kernel: [11400.770066]  [<ffffffff8103968a>] schedule+0x3a/0x60
Sep 17 22:37:00 goose2 kernel: [11400.770071]  [<ffffffff815587e5>] schedule_timeout+0x1b5/0x290
Sep 17 22:37:00 goose2 kernel: [11400.770075]  [<ffffffff8103bf1c>] ? set_cpus_allowed_ptr+0x7c/0x110
Sep 17 22:37:00 goose2 kernel: [11400.770081]  [<ffffffff81557b22>] wait_for_common+0xc2/0x160
Sep 17 22:37:00 goose2 kernel: [11400.770084]  [<ffffffff8103bd00>] ? try_to_wake_up+0x290/0x290
Sep 17 22:37:00 goose2 kernel: [11400.770087]  [<ffffffff81557c68>] wait_for_completion+0x18/0x20
Sep 17 22:37:00 goose2 kernel: [11400.770107]  [<ffffffffa003d1d1>] transport_clear_lun_from_sessions+0x51/0x80 [target_core_mod]
Sep 17 22:37:00 goose2 kernel: [11400.770118]  [<ffffffffa003a93a>] core_tpg_post_dellun+0x2a/0x60 [target_core_mod]
Sep 17 22:37:00 goose2 kernel: [11400.770128]  [<ffffffffa002ca35>] core_dev_del_lun+0x35/0xc0 [target_core_mod]
Sep 17 22:37:00 goose2 kernel: [11400.770132]  [<ffffffff811133fe>] ? simple_unlink+0x4e/0x60
Sep 17 22:37:00 goose2 kernel: [11400.770142]  [<ffffffffa002fa3e>] target_fabric_port_unlink+0x4e/0x60 [target_core_mod]
Sep 17 22:37:00 goose2 kernel: [11400.770147]  [<ffffffff8115a24a>] configfs_unlink+0xfa/0x1c0
Sep 17 22:37:00 goose2 kernel: [11400.770151]  [<ffffffff810fcbee>] vfs_unlink+0x8e/0x100
Sep 17 22:37:00 goose2 kernel: [11400.770154]  [<ffffffff81100133>] do_unlinkat+0x1a3/0x1e0
Sep 17 22:37:00 goose2 kernel: [11400.770159]  [<ffffffff810f1d3d>] ? vfs_write+0x12d/0x180
Sep 17 22:37:00 goose2 kernel: [11400.770162]  [<ffffffff810f1e7c>] ? sys_write+0x4c/0x90
Sep 17 22:37:00 goose2 kernel: [11400.770165]  [<ffffffff81100181>] sys_unlink+0x11/0x20
Sep 17 22:37:00 goose2 kernel: [11400.770169]  [<ffffffff8155a83b>] system_call_fastpath+0x16/0x1b
Sep 17 22:37:00 goose2 kernel: [11400.770172] INFO: task tcm_cl_9:17086 blocked for more than 120 seconds.
Sep 17 22:37:00 goose2 kernel: [11400.770175] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
Sep 17 22:37:00 goose2 kernel: [11400.770177] tcm_cl_9        D ffff88012ac92380     0 17086      2 0x00000000
Sep 17 22:37:00 goose2 kernel: [11400.770181]  ffff880106bffd60 0000000000000046 0000000000000002 06bffdc000000000
Sep 17 22:37:00 goose2 kernel: [11400.770186]  ffff88012ac92110 00000000000116c0 ffff880106bfffd8 ffff880106bfe010
Sep 17 22:37:00 goose2 kernel: [11400.770190]  ffff880106bfffd8 00000000000116c0 ffff88012b33d210 ffff88012ac92110
Sep 17 22:37:00 goose2 kernel: [11400.770195] Call Trace:
Sep 17 22:37:00 goose2 kernel: [11400.770198]  [<ffffffff8103968a>] schedule+0x3a/0x60
Sep 17 22:37:00 goose2 kernel: [11400.770201]  [<ffffffff815587e5>] schedule_timeout+0x1b5/0x290
Sep 17 22:37:00 goose2 kernel: [11400.770205]  [<ffffffff8103f470>] ? console_trylock+0x10/0x50
Sep 17 22:37:00 goose2 kernel: [11400.770208]  [<ffffffff81557b22>] wait_for_common+0xc2/0x160
Sep 17 22:37:00 goose2 kernel: [11400.770212]  [<ffffffff8103bd00>] ? try_to_wake_up+0x290/0x290
Sep 17 22:37:00 goose2 kernel: [11400.770215]  [<ffffffff81557c68>] wait_for_completion+0x18/0x20
Sep 17 22:37:00 goose2 kernel: [11400.770226]  [<ffffffffa003f3a6>] transport_clear_lun_thread+0x386/0x410 [target_core_mod]
Sep 17 22:37:00 goose2 kernel: [11400.770237]  [<ffffffffa003f020>] ? transport_stop_tasks_for_cmd+0x1f0/0x1f0 [target_core_mod]
Sep 17 22:37:00 goose2 kernel: [11400.770242]  [<ffffffff8105e636>] kthread+0x96/0xa0
Sep 17 22:37:00 goose2 kernel: [11400.770246]  [<ffffffff8155c634>] kernel_thread_helper+0x4/0x10
Sep 17 22:37:00 goose2 kernel: [11400.770250]  [<ffffffff8105e5a0>] ? kthread_worker_fn+0x130/0x130
Sep 17 22:37:00 goose2 kernel: [11400.770253]  [<ffffffff8155c630>] ? gs_change+0xb/0xb

--
To unsubscribe from this list: send the line "unsubscribe target-devel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html


[Index of Archives]     [Linux SCSI]     [Kernel Newbies]     [Linux SCSI Target Infrastructure]     [Share Photos]     [IDE]     [Security]     [Git]     [Netfilter]     [Bugtraq]     [Yosemite News]     [MIPS Linux]     [ARM Linux]     [Linux Security]     [Linux RAID]     [Linux ATA RAID]     [Linux IIO]     [Device Mapper]

  Powered by Linux