Simply P6.0.1 RC2: зависает размонтирование расшаренного каталога

Simply P6.0.1 RC2: зависает размонтирование расшаренного каталога

[Igor Zakhrebetkov]

Поставил Simply P6.0.1 RC2 для пробы в Virtual Box, затем на SD карту в нетбук.
Заметил такую проблему (и под VBox, и на нетбуке): если примонтировать
расшаренный на виндовом компьютере каталог, то через некоторое время зависает
доступ к этому каталогу. Сам компьютер при этом работает.
Иногда удается отмонтировать с опцией -f, иногда нет.
Если вовремя не отмонтировать, то компьюнер при выключении зависает.
Проблема именно в altlinux: федора из-под того же Virtual Box'a работает нормально.


--
Игорь

Re: [Comm] Simply P6.0.1 RC2: зависает размонтирование расшаренного каталога

[Michael Shigorin]

On Tue, Nov 08, 2011 at 07:21:45PM +0400, Igor Zakhrebetkov wrote:
> Иногда удается отмонтировать с опцией -f, иногда нет.
> Если вовремя не отмонтировать, то компьюнер при выключении зависает.

Безотносительно источника проблемы -- возможно, поможет объехать
umount -l

PS: в /var/log/messages и выводе dmesg ничего интересного часом?

-- 
 ---- WBR, Michael Shigorin <mike@altlinux.ru>
  ------ Linux.Kiev http://www.linux.kiev.ua/

Re: Simply P6.0.1 RC2: ÚÁ×ÉÓÁÅÔ ÒÁÚÍÏÎÔÉÒÏ×ÁÎÉÅ ÒÁÓÛÁÒÅÎÎÏÇÏ ËÁÔÁÌÏÇÁ

[Igor Zakhrebetkov]

Michael Shigorin пишет:
> Безотносительно источника проблемы -- возможно, поможет объехать umount -l
попробую

> PS: в /var/log/messages и выводе dmesg ничего интересного часом?

Вот вроде кусок messages с зависанием при перезагрузке:
=====================
Nov  8 14:07:53 alt6simple exiting on signal 15
Nov  8 14:07:53 alt6simple syslogd 1.4.1: restart.
Nov  8 14:07:53 alt6simple syslogd: syslogd startup succeeded
Nov  8 14:07:53 alt6simple syslogd: syslogd shutdown succeeded
Nov  8 14:07:55 alt6simple last message repeated 14 times
Nov  8 14:08:28 alt6simple anacron[5786]: Job `cron.daily' terminated
Nov  8 14:12:48 alt6simple anacron[5786]: Job `cron.weekly' started
Nov  8 14:12:48 alt6simple anacron[9728]: Updated timestamp for job `cron.weekly' to 
`2011-11-08 14:12:48'
Nov  8 14:12:48 alt6simple auditd: sending signal 10 to auditd succeeded
Nov  8 14:17:06 alt6simple anacron[5786]: Job `cron.weekly' terminated
Nov  8 14:17:06 alt6simple anacron[5786]: Normal exit (2 jobs run)
Nov  8 14:18:55 alt6simple smbd[6644]: [2011/11/08 14:18:55.481247,  0] 
smbd/server.c:281(remove_child_pid)
Nov  8 14:18:55 alt6simple smbd[6644]:   Could not find child 24349 -- ignoring
Nov  8 14:31:55 alt6simple smbd[6644]: [2011/11/08 14:31:55.998789,  0] 
smbd/server.c:281(remove_child_pid)
Nov  8 14:31:56 alt6simple smbd[6644]:   Could not find child 24798 -- ignoring
Nov  8 14:44:56 alt6simple smbd[6644]: [2011/11/08 14:44:56.577509,  0] 
smbd/server.c:281(remove_child_pid)
Nov  8 14:44:56 alt6simple smbd[6644]:   Could not find child 25297 -- ignoring
Nov  8 14:55:41 alt6simple kernel: [ 3196.672113] CIFS VFS: Server 10.43.0.9 has not 
responded in 300 seconds. Reconnecting...
Nov  8 14:57:57 alt6simple smbd[6644]: [2011/11/08 14:57:57.061356,  0] 
smbd/server.c:281(remove_child_pid)
Nov  8 14:57:57 alt6simple smbd[6644]:   Could not find child 26256 -- ignoring
Nov  8 15:01:02 alt6simple crond[26367]: (root) CMD (run-parts /etc/cron.hourly)
Nov  8 15:04:24 alt6simple kernel: [ 3720.260113] INFO: task umount:26408 blocked for 
more than 120 seconds.
Nov  8 15:04:24 alt6simple kernel: [ 3720.260116] "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Nov  8 15:04:24 alt6simple kernel: [ 3720.260118] umount          D e68a06b4     0 26408 
   7644 0x00000000
=====================
но тут, насколько я понял, в основном ошибки сервиса smbd, который я теперь выключил.
-- 
Igor Z

Re: Simply P6.0.1 RC2: ÚÁ×ÉÓÁÅÔ ÒÁÚÍÏÎÔÉÒÏ×ÁÎÉÅ ÒÁÓÛÁÒÅÎÎÏÇÏ ËÁÔÁÌÏÇÁ

[Igor Zakhrebetkov]

Michael Shigorin пишет:
> PS: в /var/log/messages и выводе dmesg ничего интересного часом?
а вот уже свежее зависание (попробовал открыть примонтированную сетевую папку):

# dmesg|tail
[   25.834878] [drm] Initialized vboxvideo 1.0.0 20090303 for 0000:00:02.0 on minor 0
[   45.638344] NET: Registered protocol family 17
[   46.452182] ata1.00: configured for UDMA/133
[   46.452186] ata1: EH complete
[   46.927137] EXT4-fs (sda): re-mounted. Opts: commit=0
[   48.772186] Bluetooth: RFCOMM TTY layer initialized
[   48.772192] Bluetooth: RFCOMM socket layer initialized
[   48.772194] Bluetooth: RFCOMM ver 1.11
[  275.952162] CIFS VFS: default security mechanism requested.  The default security 
mechanism will be upgraded from ntlm to ntlmv2 in kernel release 3.1
[ 2743.040087] CIFS VFS: Server 10.99.0.99 has not responded in 300 seconds. Reconnecting...




-- 
Igor Z

Re: [Comm] Simply P6.0.1 RC2: зависает размонтирование расшаренного каталога

[Michael Shigorin]

On Wed, Nov 09, 2011 at 02:35:34PM +0400, Igor Zakhrebetkov wrote:
> >PS: в /var/log/messages и выводе dmesg ничего интересного часом?
> а вот уже свежее зависание (попробовал открыть примонтированную
> сетевую папку):
> 
> [ 2743.040087] CIFS VFS: Server 10.99.0.99 has not responded in
> 300 seconds. Reconnecting...

Я бы оставил на соседней консольке какой mtr 10.99.0.99
и посмотрел для начала, нет ли потерь на транспорте.
Если нету и на той стороне в журналах ничего подозрительного,
тогда думал бы, как промониторить доступность сервиса.

А если в такое время в него smbclient'ом ткнуться, в идеале
с соседнего хоста?

-- 
 ---- WBR, Michael Shigorin <mike@altlinux.ru>
  ------ Linux.Kiev http://www.linux.kiev.ua/

Re: Simply P6.0.1 RC2: ÚÁ×ÉÓÁÅÔ ÒÁÚÍÏÎÔÉÒÏ×ÁÎÉÅ ÒÁÓÛÁÒÅÎÎÏÇÏ ËÁÔÁÌÏÇÁ

[Igor Zakhrebetkov]

Michael Shigorin пишет:
> Я бы оставил на соседней консольке какой mtr 10.99.0.99
> и посмотрел для начала, нет ли потерь на транспорте.
В данном случае это виндовый сервер. Никаких проблем с ним нет.

Но дома я из VirtualBox, установленном на виндовом компьютере - хосте,
подключаюсь через самбу к каталогу этого же хоста.
Там никаких потерь не должно быть.

Попробую поотключать всякие сервисы типа lisa,smb и тп

-- 
Igor Z

Re: Simply P6.0.1 RC2: ÚÁ×ÉÓÁÅÔ ÒÁÚÍÏÎÔÉÒÏ×ÁÎÉÅ ÒÁÓÛÁÒÅÎÎÏÇÏ ËÁÔÁÌÏÇÁ

[Igor Zakhrebetkov]

Igor Zakhrebetkov пишет:
> Попробую поотключать всякие сервисы типа lisa,smb и тп

Поотключал, субъективно зависать стало медленнее - то есть не через полчаса, а позже.
Но если вовремя не отмонтировать сеть, терминал с командой # umount /mnt/net -f виснет.
При выключении такой системы "термометр" проходит правый край окружности
и на последнем делении зависает.


-- 
Igor Z

Re: [Comm] Simply P6.0.1 RC2: зависает размонтирование расшаренного каталога

[Michael Shigorin]

On Wed, Nov 23, 2011 at 04:30:15PM +0400, Igor Zakhrebetkov wrote:
> >Попробую поотключать всякие сервисы типа lisa,smb и тп
> Поотключал, субъективно зависать стало медленнее - то есть не
> через полчаса, а позже.

Так-так, а в /var/log/messages за период до отключения часом нет
никаких записей вроде no buffer space available, если сеть /8?

-- 
 ---- WBR, Michael Shigorin <mike@altlinux.ru>
  ------ Linux.Kiev http://www.linux.kiev.ua/

Re: Simply P6.0.1 RC2: зависает размонтирование расшаренного каталога

[Igor Zakhrebetkov]

Michael Shigorin пишет:
> Так-так, а в /var/log/messages за период до отключения часом нет
> никаких записей вроде no buffer space available, если сеть /8?
неа
Около 12:00 загрузил alt6simple и примонтировался к расшаренногу каталогу виндового сервера
======================
Nov 24 12:00:21 alt6simple avahi-daemon[6066]: Registering new address record for 
10.0.2.15 on eth1.IPv4.
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> (eth1): DHCPv4 state changed 
preinit -> bound
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Activation (eth1) Stage 4 of 5 
(IP4 Configure Get) scheduled...
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Activation (eth1) Stage 4 of 5 
(IP4 Configure Get) started...
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info>   address 10.0.2.15
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info>   prefix 24 (255.255.255.0)
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info>   gateway 10.0.2.2
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info>   nameserver '10.xx.xx.xx'
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Scheduling stage 5
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Activation (eth1) Stage 5 of 5 
(IP Configure Commit) scheduled...
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Done scheduling stage 5
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Activation (eth1) Stage 4 of 5 
(IP4 Configure Get) complete.
Nov 24 12:00:21 alt6simple NetworkManager[4019]: <info> Activation (eth1) Stage 5 of 5 
(IP Configure Commit) started...
Nov 24 12:00:22 alt6simple NetworkManager[4019]: <info> (eth1): writing resolv.conf to 
/sbin/resolvconf
Nov 24 12:00:22 alt6simple avahi-daemon[6066]: Got SIGHUP, reloading.
Nov 24 12:00:22 alt6simple avahi-daemon: Reloading Avahi mDNS/DNS-SD service: succeeded
Nov 24 12:00:23 alt6simple NetworkManager[4019]: <info> (eth1): device state change: 7 
-> 8 (reason 0)
Nov 24 12:00:23 alt6simple NetworkManager[4019]: <info> Policy set 
'Р\220РІС\202РѕРјР°С\202РёС\207РµС\201РєРѕРµ (Ethernet)' (eth1) as default for IPv4 
routing and DNS.
Nov 24 12:00:23 alt6simple NetworkManager[4019]: <info> Activation (eth1) successful, 
device activated.
Nov 24 12:00:23 alt6simple dbus[3714]: [system] Activating service 
name='org.freedesktop.nm_dispatcher' (using servicehelper)
Nov 24 12:00:23 alt6simple NetworkManager[4019]: <info> Activation (eth1) Stage 5 of 5 
(IP Configure Commit) complete.
Nov 24 12:00:23 alt6simple dbus[3714]: [system] Successfully activated service 
'org.freedesktop.nm_dispatcher'
Nov 24 12:00:23 alt6simple dbus[3714]: [system] Activating service 
name='org.blueman.Mechanism' (using servicehelper)
Nov 24 12:00:24 alt6simple blueman-mechanism: Starting blueman-mechanism
Nov 24 12:00:24 alt6simple dbus[3714]: [system] Successfully activated service 
'org.blueman.Mechanism'
Nov 24 12:00:24 alt6simple blueman-mechanism: reload 0 0
Nov 24 12:00:36 alt6simple kernel: [   68.261676] CIFS VFS: default security mechanism 
requested.  The default security mechanism will be upgraded from ntlm to ntlmv2 in 
kernel release 3.1
Nov 24 12:00:54 alt6simple blueman-mechanism: Exiting
Nov 24 12:01:01 alt6simple crond[7415]: (root) CMD (run-parts /etc/cron.hourly)
Nov 24 12:19:47 alt6simple -- MARK --
Nov 24 12:20:40 alt6simple kernel: [ 1272.064111] CIFS VFS: Server 10.xx.xx.xx has not 
responded in 300 seconds. Reconnecting...
Nov 24 12:39:47 alt6simple -- MARK --
Nov 24 12:57:23 alt6simple kernel: [ 3474.578849] UDF-fs: No VRS found
Nov 24 12:57:23 alt6simple kernel: [ 3474.578853] UDF-fs: No partition found (1)
======================
В 12:20 сообщение, что якобы сервер не отвечает.
В 12:39 попытка отмонтирования зависла

Но в то же время через "Сетевое окружение" спокойно подключился к тому же серверу и 
скопировал на него /var/log/messages (!?)

После зависания отмонтирования зависает и выключение компа, попытки убить процессы 
размонтирования не помогают.

-- 
Igor Z

Re: Simply P6.0.1 RC2: ÚÁ×ÉÓÁÅÔ ÒÁÚÍÏÎÔÉÒÏ×ÁÎÉÅ ÒÁÓÛÁÒÅÎÎÏÇÏ ËÁÔÁÌÏÇÁ

[Igor Zakhrebetkov]

Michael Shigorin пишет:
> Безотносительно источника проблемы -- возможно, поможет объехать umount -l
не помогает, команда umount /mnt/win -f -l висит уже около часа.

В messages повторяющиеся сообщения вида :
=======================================
Nov  9 13:31:14 alt6simple kernel: [  275.952162] CIFS VFS: default security mechanism 
requested.  The default security mechanism will be upgraded from ntlm to ntlmv2 in 
kernel release 3.1
Nov  9 13:46:58 alt6simple -- MARK --
Nov  9 14:01:01 alt6simple crond[8657]: (root) CMD (run-parts /etc/cron.hourly)
Nov  9 14:12:21 alt6simple kernel: [ 2743.040087] CIFS VFS: Server 10.99.0.99 has not 
responded in 300 seconds. Reconnecting...
Nov  9 14:26:58 alt6simple -- MARK --
Nov  9 14:46:38 alt6simple kernel: [ 4800.257097] INFO: task umount:10031 blocked for 
more than 120 seconds.
Nov  9 14:46:38 alt6simple kernel: [ 4800.257110] "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Nov  9 14:46:38 alt6simple kernel: [ 4800.257120] umount          D e68a06b4     0 10031 
   7496 0x00000000
Nov  9 14:46:38 alt6simple kernel: [ 4800.257132]  d380fd30 00200082 c10cc72d e68a06b4 
00000016 00000000 00000000 00000000
Nov  9 14:46:38 alt6simple kernel: [ 4800.257144]  c15b1b80 e6c54070 c15b1b80 c15b1b80 
36306e03 0000042c c15b1b80 e7405b80
Nov  9 14:46:38 alt6simple kernel: [ 4800.257155]  e6c54070 e6e7a030 00000001 e7d78920 
e7d78940 c15248c0 00000003 000280da
Nov  9 14:46:38 alt6simple kernel: [ 4800.257165] Call Trace:
Nov  9 14:46:38 alt6simple kernel: [ 4800.257181]  [<c10cc72d>] ? find_get_page+0x1d/0x90
Nov  9 14:46:38 alt6simple kernel: [ 4800.257193]  [<c103eff4>] schedule+0x34/0x50
Nov  9 14:46:38 alt6simple kernel: [ 4800.257203]  [<c1377dde>] 
__mutex_lock_slowpath+0xbe/0x120
Nov  9 14:46:38 alt6simple kernel: [ 4800.257213]  [<c117122a>] ? find_nls+0x7a/0xc0
Nov  9 14:46:38 alt6simple kernel: [ 4800.257221]  [<c1377949>] mutex_lock+0x19/0x30
Nov  9 14:46:38 alt6simple kernel: [ 4800.257235]  [<e9e22794>] 
cifs_reconnect_tcon+0x104/0x2a0 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257244]  [<c102b6b7>] ? 
kmap_atomic_prot+0x47/0x100
Nov  9 14:46:38 alt6simple kernel: [ 4800.257252]  [<c111ddce>] ? do_lookup+0x3e/0x2e0
Nov  9 14:46:38 alt6simple kernel: [ 4800.257261]  [<e9e2294f>] smb_init+0x1f/0x90 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257271]  [<e9e276dc>] 
CIFSSMBQPathInfo+0x3c/0x220 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257282]  [<e9e3a31b>] 
cifs_get_inode_info+0x2fb/0x480 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257290]  [<c112b704>] ? 
vfsmount_lock_local_unlock+0x14/0x20
Nov  9 14:46:38 alt6simple kernel: [ 4800.257300]  [<e9e327f5>] ? 
build_path_from_dentry+0xc5/0x240 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257309]  [<e9e32848>] ? 
build_path_from_dentry+0x118/0x240 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257316]  [<c1378cf8>] ? _raw_spin_lock+0x8/0x10
Nov  9 14:46:38 alt6simple kernel: [ 4800.257325]  [<e9e3d563>] ? _GetXid+0x53/0x80 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257334]  [<e9e3bb49>] 
cifs_revalidate_dentry_attr+0x119/0x1a0 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257344]  [<e9e3bc93>] cifs_getattr+0x53/0x120 
[cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257351]  [<c11173f9>] vfs_getattr+0x39/0x110
Nov  9 14:46:38 alt6simple kernel: [ 4800.257360]  [<e9e3bc40>] ? 
cifs_revalidate_dentry+0x30/0x30 [cifs]
Nov  9 14:46:38 alt6simple kernel: [ 4800.257366]  [<c1117545>] vfs_fstatat+0x75/0x90
Nov  9 14:46:38 alt6simple kernel: [ 4800.257371]  [<c111759b>] vfs_stat+0x1b/0x20
Nov  9 14:46:38 alt6simple kernel: [ 4800.257376]  [<c1117904>] sys_stat64+0x14/0x30
Nov  9 14:46:38 alt6simple kernel: [ 4800.257382]  [<c112cad3>] ? mntput+0x13/0x20
Nov  9 14:46:38 alt6simple kernel: [ 4800.257387]  [<c111c1f5>] ? path_put+0x15/0x20
Nov  9 14:46:38 alt6simple kernel: [ 4800.257392]  [<c1117881>] ? sys_readlinkat+0x61/0xa0
Nov  9 14:46:38 alt6simple kernel: [ 4800.257397]  [<c11178e7>] ? sys_readlink+0x27/0x30
Nov  9 14:46:38 alt6simple kernel: [ 4800.257403]  [<c1378f79>] syscall_call+0x7/0xb
Nov  9 14:48:19 alt6simple consolehelper[10211]: executing "/usr/sbin/acc": (igorz --> 
root --> root)
Nov  9 14:48:19 alt6simple alteratord: alteratord shutdown succeeded
Nov  9 14:48:38 alt6simple kernel: [ 4920.256084] INFO: task umount:10031 blocked for 
more than 120 seconds.
Nov  9 14:48:38 alt6simple kernel: [ 4920.256088] "echo 0 > 
/proc/sys/kernel/hung_task_timeout_secs" disables this message.
Nov  9 14:48:38 alt6simple kernel: [ 4920.256090] umount          D e68a06b4     0 10031 
   7496 0x00000000
Nov  9 14:48:38 alt6simple kernel: [ 4920.256094]  d380fd30 00200082 c10cc72d e68a06b4 
00000016 00000000 00000000 00000000
Nov  9 14:48:38 alt6simple kernel: [ 4920.256098]  c15b1b80 e6c54070 c15b1b80 c15b1b80 
36306e03 0000042c c15b1b80 e7405b80
Nov  9 14:48:38 alt6simple kernel: [ 4920.256102]  e6c54070 e6e7a030 00000001 e7d78920 
e7d78940 c15248c0 00000003 000280da
Nov  9 14:48:38 alt6simple kernel: [ 4920.256106] Call Trace:
=======================================


-- 
Igor Z