Hi, > I tagged the bug moreinfo because I suspected HAL was causing the timeout > mechanism to fail, and Roman hadn't made a final statement whether stopping > HAL fixed the problem for him.
I see. I also use HAL...
> Could you give a more detailed description of your bug? What version(s) do
> you use?
~$ dpkg -l autofs nfs-common
Desired=Unknown/Install/Remove/Purge/Hold
| Status=Not/Installed/Config-files/Unpacked/Failed-config/Half-installed
|/ Err?=(none)/Hold/Reinst-required/X=both-problems (Status,Err: uppercase=bad)
||/ Name Version Description
+++-================-================-================================================
ii autofs 4.1.4+debian-1 kernel-based automounter for Linux
ii nfs-common 1.0.10-6 NFS support files common to client and
server
> How do you see those periodic accesses - local log file or network
> sniffer? Is there some kind of pattern?
With tcpdump. There are mostly some periodic getattr calls. I can unmount the
NFS share at any time.
It looked like this may be caused by famd:
~# lsof|grep slug
famd 4885 marcus 211r DIR 0,20 0
73767 /mnt/nfs/slug
famd 4885 marcus 215r DIR 0,24 36864
2 /mnt/nfs/slug/music
famd 4885 marcus 216r DIR 0,23 200
2 /mnt/nfs/slug/home
But stopping famd has no effect.
Here is the tcpdump output:
09:48:42.142343 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
09:48:42.143397 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
09:48:42.143437 IP melech.local.816 > slug.home.better.se.nfs: . ack 805743073
win 46 <nop,nop,timestamp 32481910 6088108>
09:48:42.144465 IP melech.local.1155221492 > slug.home.better.se.nfs: 576 null
09:48:42.145800 IP slug.home.better.se.nfs > melech.local.816: . ack 576 win
3472 <nop,nop,timestamp 6088108 32481911>
09:48:42.164341 IP slug.home.better.se.nfs > melech.local.1155221492: reply ok
208 null
09:48:42.164398 IP melech.local.816 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32481916 6088110>
09:48:42.165151 IP melech.local.501130622 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:48:42.165296 IP melech.local.816 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32481916 6088110>
09:48:42.168510 IP slug.home.better.se.nfs > melech.local.501130622: reply ok
264 getattr [|nfs]
09:48:42.168573 IP melech.local.851 > slug.home.better.se.nfs: . ack 3961 win
498 <nop,nop,timestamp 32481917 6088110>
09:48:42.168699 IP melech.local.517907838 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:48:42.168699 IP slug.home.better.se.nfs > melech.local.816: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6088110 32481916>
09:48:42.168731 IP melech.local.816 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32481917 6088110>
09:48:42.170956 IP slug.home.better.se.nfs > melech.local.517907838: reply ok
264 getattr [|nfs]
09:48:42.172042 IP melech.local.534685054 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:48:42.172951 IP slug.home.better.se.nfs > melech.local.534685054: reply ok
264 getattr [|nfs]
09:48:42.173097 IP melech.local.551462270 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:48:42.176424 IP slug.home.better.se.nfs > melech.local.551462270: reply ok
264 getattr [|nfs]
09:48:42.176539 IP melech.local.568239486 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:48:42.176969 IP slug.home.better.se.nfs > melech.local.568239486: reply ok
264 getattr [|nfs]
09:48:42.216044 IP melech.local.851 > slug.home.better.se.nfs: . ack 5017 win
498 <nop,nop,timestamp 32481929 6088111>
09:49:42.406629 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
09:49:42.407684 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
09:49:42.407726 IP melech.local.818 > slug.home.better.se.nfs: . ack 884998938
win 46 <nop,nop,timestamp 32496976 6094074>
09:49:42.408820 IP melech.local.1155485366 > slug.home.better.se.nfs: 576 null
09:49:42.410115 IP slug.home.better.se.nfs > melech.local.818: . ack 576 win
3472 <nop,nop,timestamp 6094074 32496977>
09:49:42.428788 IP slug.home.better.se.nfs > melech.local.1155485366: reply ok
208 null
09:49:42.432089 IP melech.local.818 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32496983 6094076>
09:49:42.432882 IP melech.local.585016702 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:49:42.433016 IP melech.local.818 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32496983 6094076>
09:49:42.433608 IP slug.home.better.se.nfs > melech.local.585016702: reply ok
264 getattr [|nfs]
09:49:42.433663 IP melech.local.851 > slug.home.better.se.nfs: . ack 5281 win
498 <nop,nop,timestamp 32496983 6094077>
09:49:42.433770 IP slug.home.better.se.nfs > melech.local.818: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6094077 32496983>
09:49:42.433796 IP melech.local.818 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32496983 6094077>
09:49:42.434216 IP melech.local.601793918 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:49:42.437807 IP slug.home.better.se.nfs > melech.local.601793918: reply ok
264 getattr [|nfs]
09:49:42.438428 IP melech.local.618571134 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:49:42.440603 IP slug.home.better.se.nfs > melech.local.618571134: reply ok
264 getattr [|nfs]
09:49:42.440886 IP melech.local.635348350 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:49:42.442940 IP slug.home.better.se.nfs > melech.local.635348350: reply ok
264 getattr [|nfs]
09:49:42.443170 IP melech.local.652125566 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:49:42.445947 IP slug.home.better.se.nfs > melech.local.652125566: reply ok
264 getattr [|nfs]
09:49:42.484121 IP melech.local.851 > slug.home.better.se.nfs: . ack 6337 win
498 <nop,nop,timestamp 32496996 6094078>
09:50:42.674364 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
09:50:42.674913 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
09:50:42.674954 IP melech.local.820 > slug.home.better.se.nfs: . ack 938773188
win 46 <nop,nop,timestamp 32512043 6100040>
09:50:42.676517 IP melech.local.1154693951 > slug.home.better.se.nfs: 576 null
09:50:42.677709 IP slug.home.better.se.nfs > melech.local.820: . ack 576 win
3472 <nop,nop,timestamp 6100041 32512044>
09:50:42.696368 IP slug.home.better.se.nfs > melech.local.1154693951: reply ok
208 null
09:50:42.696421 IP melech.local.820 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32512049 6100043>
09:50:42.697114 IP melech.local.668902782 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:50:42.697237 IP melech.local.820 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32512049 6100043>
09:50:42.701579 IP slug.home.better.se.nfs > melech.local.668902782: reply ok
264 getattr [|nfs]
09:50:42.701625 IP melech.local.851 > slug.home.better.se.nfs: . ack 6601 win
498 <nop,nop,timestamp 32512050 6100043>
09:50:42.701716 IP melech.local.685679998 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:50:42.701781 IP slug.home.better.se.nfs > melech.local.820: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6100043 32512049>
09:50:42.701806 IP melech.local.820 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32512050 6100043>
09:50:42.704077 IP slug.home.better.se.nfs > melech.local.685679998: reply ok
264 getattr [|nfs]
09:50:42.704171 IP melech.local.702457214 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:50:42.706353 IP slug.home.better.se.nfs > melech.local.702457214: reply ok
264 getattr [|nfs]
09:50:42.706477 IP melech.local.719234430 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:50:42.708328 IP slug.home.better.se.nfs > melech.local.719234430: reply ok
264 getattr [|nfs]
09:50:42.708536 IP melech.local.736011646 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:50:42.710735 IP slug.home.better.se.nfs > melech.local.736011646: reply ok
264 getattr [|nfs]
09:50:42.748134 IP melech.local.851 > slug.home.better.se.nfs: . ack 7657 win
498 <nop,nop,timestamp 32512062 6100044>
After changing the autofs timeout from 60 to 300 seconds, I get this output:
09:57:40.142557 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
09:57:40.143015 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
09:57:40.143061 IP melech.local.832 > slug.home.better.se.nfs: . ack 1374596541
win 46 <nop,nop,timestamp 32616410 6141369>
09:57:40.144761 IP melech.local.1155226277 > slug.home.better.se.nfs: 576 null
09:57:40.145474 IP slug.home.better.se.nfs > melech.local.832: . ack 576 win
3472 <nop,nop,timestamp 6141369 32616411>
09:57:40.166403 IP slug.home.better.se.nfs > melech.local.1155226277: reply ok
208 null
09:57:40.166447 IP melech.local.832 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32616416 6141371>
09:57:40.167120 IP melech.local.832 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32616416 6141371>
09:57:40.167205 IP melech.local.1722082610 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:57:40.168153 IP slug.home.better.se.nfs > melech.local.832: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6141371 32616416>
09:57:40.168185 IP melech.local.832 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32616417 6141371>
09:57:40.169655 IP slug.home.better.se.nfs > melech.local.1722082610: reply ok
264 getattr [|nfs]
09:57:40.169686 IP melech.local.965 > slug.home.better.se.nfs: . ack 5173 win
222 <nop,nop,timestamp 32616417 6141371>
09:57:40.169835 IP melech.local.1738859826 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:57:40.173556 IP slug.home.better.se.nfs > melech.local.1738859826: reply ok
264 getattr [|nfs]
09:57:40.173703 IP melech.local.1755637042 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:57:40.176084 IP slug.home.better.se.nfs > melech.local.1755637042: reply ok
264 getattr [|nfs]
09:57:40.176255 IP melech.local.1772414258 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:57:40.178713 IP slug.home.better.se.nfs > melech.local.1772414258: reply ok
264 getattr [|nfs]
09:57:40.178824 IP melech.local.1789191474 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:57:40.180413 IP slug.home.better.se.nfs > melech.local.1789191474: reply ok
264 getattr [|nfs]
09:57:40.220190 IP melech.local.965 > slug.home.better.se.nfs: . ack 6229 win
255 <nop,nop,timestamp 32616430 6141372>
09:58:55.186381 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
09:58:55.186985 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
09:58:55.187033 IP melech.local.833 > slug.home.better.se.nfs: . ack 1460811585
win 46 <nop,nop,timestamp 32635171 6148798>
09:58:55.188593 IP melech.local.1155247935 > slug.home.better.se.nfs: 576 null
09:58:55.189914 IP slug.home.better.se.nfs > melech.local.833: . ack 576 win
3472 <nop,nop,timestamp 6148798 32635172>
09:58:55.210545 IP slug.home.better.se.nfs > melech.local.1155247935: reply ok
208 null
09:58:55.210598 IP melech.local.833 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32635177 6148800>
09:58:55.212596 IP melech.local.833 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32635178 6148800>
09:58:55.213497 IP slug.home.better.se.nfs > melech.local.833: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6148800 32635178>
09:58:55.213528 IP melech.local.833 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32635178 6148800>
09:58:55.213977 IP melech.local.1805968690 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:58:55.216135 IP slug.home.better.se.nfs > melech.local.1805968690: reply ok
264 getattr [|nfs]
09:58:55.216170 IP melech.local.965 > slug.home.better.se.nfs: . ack 6493 win
264 <nop,nop,timestamp 32635179 6148801>
09:58:55.216319 IP melech.local.1822745906 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:58:55.218391 IP slug.home.better.se.nfs > melech.local.1822745906: reply ok
264 getattr [|nfs]
09:58:55.218504 IP melech.local.1839523122 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:58:55.220467 IP slug.home.better.se.nfs > melech.local.1839523122: reply ok
264 getattr [|nfs]
09:58:55.220705 IP melech.local.1856300338 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:58:55.221201 IP slug.home.better.se.nfs > melech.local.1856300338: reply ok
264 getattr [|nfs]
09:58:55.221372 IP melech.local.1873077554 > slug.home.better.se.nfs: 156
getattr [|nfs]
09:58:55.224406 IP slug.home.better.se.nfs > melech.local.1873077554: reply ok
264 getattr [|nfs]
09:58:55.264136 IP melech.local.965 > slug.home.better.se.nfs: . ack 7549 win
297 <nop,nop,timestamp 32635191 6148802>
10:00:10.230968 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
10:00:10.232350 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
10:00:10.232404 IP melech.local.834 > slug.home.better.se.nfs: . ack 1549309288
win 46 <nop,nop,timestamp 32653933 6156227>
10:00:10.233450 IP melech.local.1155201064 > slug.home.better.se.nfs: 576 null
10:00:10.234705 IP slug.home.better.se.nfs > melech.local.834: . ack 576 win
3472 <nop,nop,timestamp 6156227 32653933>
10:00:10.256512 IP slug.home.better.se.nfs > melech.local.1155201064: reply ok
208 null
10:00:10.256571 IP melech.local.834 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32653939 6156229>
10:00:10.257381 IP melech.local.834 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32653939 6156229>
10:00:10.257836 IP melech.local.1889854770 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:00:10.262572 IP slug.home.better.se.nfs > melech.local.834: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6156230 32653939>
10:00:10.262634 IP melech.local.834 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32653940 6156230>
10:00:10.262749 IP slug.home.better.se.nfs > melech.local.1889854770: reply ok
264 getattr [|nfs]
10:00:10.262785 IP melech.local.965 > slug.home.better.se.nfs: . ack 7813 win
306 <nop,nop,timestamp 32653940 6156230>
10:00:10.263036 IP melech.local.1906631986 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:00:10.265216 IP slug.home.better.se.nfs > melech.local.1906631986: reply ok
264 getattr [|nfs]
10:00:10.265421 IP melech.local.1923409202 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:00:10.267566 IP slug.home.better.se.nfs > melech.local.1923409202: reply ok
264 getattr [|nfs]
10:00:10.267727 IP melech.local.1940186418 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:00:10.270214 IP slug.home.better.se.nfs > melech.local.1940186418: reply ok
264 getattr [|nfs]
10:00:10.270466 IP melech.local.1956963634 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:00:10.273777 IP slug.home.better.se.nfs > melech.local.1956963634: reply ok
264 getattr [|nfs]
10:00:10.312085 IP melech.local.965 > slug.home.better.se.nfs: . ack 8869 win
339 <nop,nop,timestamp 32653953 6156231>
10:01:25.278347 IP melech.local.0 > slug.home.better.se.nfs: 0 proc-4294967295
10:01:25.280485 IP slug.home.better.se.nfs > melech.local.0: reply ERR 0
proc-4294967295
10:01:25.280559 IP melech.local.835 > slug.home.better.se.nfs: . ack 1626924523
win 46 <nop,nop,timestamp 32672695 6163657>
10:01:25.281681 IP melech.local.1155349623 > slug.home.better.se.nfs: 576 null
10:01:25.283004 IP slug.home.better.se.nfs > melech.local.835: . ack 576 win
3472 <nop,nop,timestamp 6163657 32672695>
10:01:25.304345 IP slug.home.better.se.nfs > melech.local.1155349623: reply ok
208 null
10:01:25.304399 IP melech.local.835 > slug.home.better.se.nfs: . ack 209 win 54
<nop,nop,timestamp 32672701 6163659>
10:01:25.304995 IP melech.local.1973740850 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:01:25.305132 IP melech.local.835 > slug.home.better.se.nfs: F 576:576(0) ack
209 win 54 <nop,nop,timestamp 32672701 6163659>
10:01:25.307105 IP slug.home.better.se.nfs > melech.local.1973740850: reply ok
264 getattr [|nfs]
10:01:25.307147 IP melech.local.965 > slug.home.better.se.nfs: . ack 9133 win
348 <nop,nop,timestamp 32672701 6163659>
10:01:25.307279 IP slug.home.better.se.nfs > melech.local.835: F 209:209(0) ack
577 win 3472 <nop,nop,timestamp 6163659 32672701>
10:01:25.307304 IP melech.local.835 > slug.home.better.se.nfs: . ack 210 win 54
<nop,nop,timestamp 32672701 6163659>
10:01:25.307427 IP melech.local.1990518066 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:01:25.308886 IP slug.home.better.se.nfs > melech.local.1990518066: reply ok
264 getattr [|nfs]
10:01:25.309076 IP melech.local.2007295282 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:01:25.312609 IP slug.home.better.se.nfs > melech.local.2007295282: reply ok
264 getattr [|nfs]
10:01:25.312862 IP melech.local.2024072498 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:01:25.316613 IP slug.home.better.se.nfs > melech.local.2024072498: reply ok
264 getattr [|nfs]
10:01:25.316863 IP melech.local.2040849714 > slug.home.better.se.nfs: 156
getattr [|nfs]
10:01:25.317625 IP slug.home.better.se.nfs > melech.local.2040849714: reply ok
264 getattr [|nfs]
10:01:25.356142 IP melech.local.965 > slug.home.better.se.nfs: . ack 10189 win
381 <nop,nop,timestamp 32672714 6163660>
Apparently the interval changed from 60 to 75 seconds!
Note that automatic unmount does work if I set a short timeout (10 seconds).
This is because the periodic activity has a longer period than that.
Is there some way to find out which process is causing the activity?
Regards,
Marcus
pgplmP2G7GdrF.pgp
Description: PGP signature
