NFS-82/rpc.lockd/lockd_lock.c

234 static void debuglog(char const *fmt, ...);
274 debuglog(char const *fmt, ...)
309 		debuglog("object is size 0\n");
312 			debuglog("Object of size %d being clamped"
319 				debuglog("Hbuff not large enough."
331 				debuglog("Cbuff not large enough."
358 		debuglog("Null netobj pointer\n");
361 		debuglog("Size zero netobj\n");
365 		debuglog("netobj: len: %d  data: %s :::  %s\n",
384 		debuglog("Dumping file lock structure @ %p\n", fl);
390 		debuglog("Filehandle: %8s  :::  %8s\n", hbuff, cbuff);
393 		debuglog("Dumping nlm4_holder:\n"
399 		debuglog("Dumping client identity:\n");
402 		debuglog("nsm: %d  status: %d  flags: %d  locker: %d"
407 		debuglog("NULL file lock structure\n");
523 	debuglog("Entering region overlap with vals: %llu:%llu--%llu:%llu\n",
529 	debuglog("Exiting region overlap with val: %d\n",result);
721 	debuglog("Entering netobj identity check\n");
724 		debuglog("Preliminary length check passed\n");
726 		debuglog("netobj %smatch\n", retval ? "" : "mis");
741 	debuglog("Checking filelock identity\n");
749 	debuglog("Exiting checking filelock identity: retval: %d\n",retval);
769 	debuglog("Entering lock_matching_unlock\n");
770 	debuglog("********Dump of fl*****************\n");
774 		debuglog("Pointer to file lock: %p\n",ifl);
776 		debuglog("****Dump of ifl****\n");
778 		debuglog("*******************\n");
795 		debuglog("matching_unlock: Filehandles match, "
803 		debuglog("matching_unlock: Region overlap"
812 		debuglog("matching_unlock: Duplicate lock id.  Granting\n");
816 	debuglog("Exiting lock_matching_unlock\n");
840 	debuglog("Entering test_nfslock\n");
845 	debuglog("Entering lock search loop\n");
847 	debuglog("***********************************\n");
848 	debuglog("Dumping match filelock\n");
849 	debuglog("***********************************\n");
851 	debuglog("***********************************\n");
857 		debuglog("Top of lock loop\n");
858 		debuglog("Pointer to file lock: %p\n",ifl);
860 		debuglog("***********************************\n");
861 		debuglog("Dumping test filelock\n");
862 		debuglog("***********************************\n");
864 		debuglog("***********************************\n");
878 		debuglog("test_nfslock: filehandle match found\n");
885 		debuglog("test_nfslock: Region overlap found"
894 		debuglog("test_nfslock: Exclusivity failure: %d %d\n",
899 			debuglog("test_nfslock: Duplicate id.  Granting\n");
904 			debuglog("test_nfslock: Lock attempt failed\n");
905 			debuglog("Desired lock\n");
907 			debuglog("Conflicting lock\n");
914 	debuglog("Dumping file locks\n");
915 	debuglog("Exiting test_nfslock\n");
946 	debuglog("Entering lock_nfslock...\n");
951 		debuglog("Inserting lock...\n");
956 	debuglog("Exiting lock_nfslock...\n");
996 			debuglog("Unable to allocate resource for split 1\n");
1010 			debuglog("Unable to allocate resource for split 1\n");
1039 	debuglog("Entering unlock_nfslock\n");
1047 	debuglog("Attempting to match lock...\n");
1051 		debuglog("Unlock matched.  Querying for split\n");
1055 		debuglog("Split returned %d %p %p %p %p\n",spstatus,mfl,fl,*left_lock,*right_lock);
1056 		debuglog("********Split dumps********");
1061 		debuglog("********End Split dumps********");
1079 			debuglog("Split left activated\n");
1084 			debuglog("Split right activated\n");
1094 	debuglog("Exiting unlock_nfslock\n");
1133 		debuglog("hwlock resource allocation failure\n");
1138 		debuglog("hwlock resource allocation failure\n");
1144 		debuglog("hwlock: bad fh length %d (from %16s): %32s\n",
1158 		debuglog("fhopen failed (from %16s): %32s\n",
1184 		debuglog("flock failed (from %16s): %32s\n",
1205 	debuglog("flock succeeded (from %16s)\n", fl->client_name);
1214 	debuglog("Entering unlock_hwlock\n");
1215 	debuglog("Entering loop interation\n");
1227 	debuglog("Completed iteration.  Proceeding\n");
1231 		debuglog("Exiting unlock_hwlock (HW_DENIED_NOLOCK)\n");
1239 		debuglog("Negative hardware reference count\n");
1248 	debuglog("Exiting unlock_hwlock (HW_GRANTED)\n");
1278 	debuglog("Entering add_blockingfilelock\n");
1286 		debuglog("Pointer to file lock: %p\n",ifl);
1287 		debuglog("****Dump of ifl****\n");
1289 		debuglog("*******************\n");
1308 		debuglog("add_blockingfilelock: removing duplicate lock request.\n");
1322 	debuglog("Exiting add_blockingfilelock\n");
1329 	debuglog("Entering remove_blockingfilelock\n");
1333 	debuglog("Exiting remove_blockingfilelock\n");
1376 	debuglog("Entering retry_blockingfilelocklist\n");
1382 	debuglog("Iterator choice %p\n",ifl);
1390 		debuglog("Iterator choice %p\n",ifl);
1391 		debuglog("Prev iterator choice %p\n",pfl);
1392 		debuglog("Next iterator choice %p\n",nfl);
1411 			debuglog("Granted blocked lock\n");
1453 			debuglog("Replacing blocked lock\n");
1474 	debuglog("Exiting retry_blockingfilelocklist\n");
1516 	debuglog("Entering lock_partialfilelock\n");
1539 			debuglog("HW GRANTED\n");
1554 			debuglog("HW RESERR\n");
1558 			debuglog("HW DENIED\n");
1562 			debuglog("HW DENIED NOLOCK\n");
1566 			debuglog("HW STALE FH\n");
1570 			debuglog("HW READONLY\n");
1574 			debuglog("Unmatched hwstatus %d\n",hwstatus);
1581 			debuglog("Deleting trial NFS lock\n");
1586 			debuglog("Cleaning up replaced locks\n");
1597 		debuglog("Unmatched lnlstatus %d\n");
1613 				debuglog("BLOCKING LOCK RECEIVED\n");
1619 				debuglog("BLOCKING LOCK DENIED IN HARDWARE\n");
1624 			debuglog("Lock denied.  Non-blocking failure\n");
1629 	debuglog("Exiting lock_partialfilelock\n");
1655 	debuglog("Entering unlock_partialfilelock\n");
1685 		debuglog("Value of releasedfl: %p\n",releasedfl);
1688 		debuglog("Value of releasedfl: %p\n",releasedfl);
1696 				debuglog("HW duplicate lock failure for left split\n");
1706 				debuglog("HW duplicate lock failure for right split\n");
1715 			debuglog("NFS unlock granted.  Attempting hardware unlock\n");
1719 			debuglog("HW unlock returned with code %d\n",unlhwstatus);
1723 				debuglog("HW unlock granted\n");
1730 				debuglog("HW unlock denied no lock\n");
1736 				debuglog("HW unlock failed\n");
1743 			debuglog("Exiting with status retval: %d\n",retval);
1754 			debuglog("All locks cleaned out\n");
1758 			debuglog("NFS unlock failure\n");
1771 					debuglog("Attempt to unlock self\n");
1793 		debuglog("WARNING!  Destroying incoming lock pointer\n");
1797 	debuglog("Exiting unlock_partialfilelock\n");
1829 			debuglog("Clearing locks for %s (%s)\n",
1885 	debuglog("Entering testpartialfilelock...\n");
1888 	debuglog("test_partialfilelock: teststatus %d\n",teststatus);
1894 		debuglog("Dumping locks...\n");
1897 		debuglog("Done dumping locks...\n");
1900 		debuglog("NFS test denied.\n");
1902 		debuglog("Conflicting.\n");
1906 	debuglog("Exiting testpartialfilelock...\n");
1927 	debuglog("Entering do_test...\n");
1933 		debuglog("PFL test lock granted\n");
1939 		debuglog("PFL test lock granted--duplicate id detected\n");
1942 		debuglog("Clearing conflicting_fl for call semantics\n");
1948 		debuglog("PFL test lock denied\n");
1955 		debuglog("PFL test lock resource fail\n");
1961 		debuglog("PFL test lock *FAILED*\n");
1968 	debuglog("Exiting do_test...\n");
1986 	debuglog("Entering do_lock...\n");
1992 		debuglog("PFL lock granted");
1997 		debuglog("PFL lock granted--duplicate id detected");
2003 		debuglog("PFL_NFS lock denied");
2009 		debuglog("PFL_NFS blocking lock denied.  Queued.\n");
2017 		debuglog("PFL lock resource alocation fail\n");
2022 		debuglog("PFL_NFS lock denied STALEFH");
2027 		debuglog("PFL_NFS lock denied READONLY");
2032 		debuglog("PFL lock *FAILED*");
2038 	debuglog("Exiting do_lock...\n");
2049 	debuglog("Entering do_unlock...\n");
2054 		debuglog("PFL unlock granted");
2060 		debuglog("PFL_NFS unlock denied");
2066 		debuglog("PFL_NFS no lock found\n");
2071 		debuglog("PFL unlock resource failure");
2076 		debuglog("PFL unlock *FAILED*");
2082 	debuglog("Exiting do_unlock...\n");
2116 	debuglog("do_notify_mounts for %s", hostname);
2126 	debuglog("do_notify_mounts for %s, addrcount %d", hostname, addrcount);
2151 				debuglog("do_notify_mounts for %s, addr %d ai fam %d len %d %s",
2183 		debuglog("received fhandle size %d, max size %d",
2198 		debuglog("No conflicting lock found\n");
2202 		debuglog("Found conflicting lock\n");
2223 	debuglog("Entering getlock...\n");
2230 		debuglog("received fhandle size %d, max size %d",
2257 	debuglog("Pointer to new lock is %p\n",newfl);
2261 	debuglog("Pointer to new lock is %p\n",newfl);
2279 	debuglog("Exiting getlock...\n");
2292 	debuglog("Entering unlock...\n");
2295 		debuglog("received fhandle size %d, max size %d",
2312 	debuglog("Exiting unlock...\n");
2324 	debuglog("Entering cancellock...\n");
2327 		debuglog("received fhandle size %d, max size %d",
2384 	debuglog("Exiting cancellock...\n");
2411 	debuglog("get_lock_host %s\n", hostname ? hostname : "addr");
2434 	debuglog("get_lock_host %s %s\n",
2448 	debuglog("monitor_lock_host: %s\n", hostname);
2454 			debuglog("Monitor_lock_host: %s - %s (cached)\n",
2457 			debuglog("Monitor_lock_host: %s (cached)\n", ihp->name);
2473 		debuglog("monitor_lock_host: bad address\n");
2476 	debuglog("monitor_lock_host: %s\n", hostaddr);
2481 		debuglog("Monitor_lock_host: %s (cached)\n", ihp->name);
2503 	debuglog("Monitor_lock_host: %s (creating)\n", hostname);
2506 		debuglog("monitor_lock_host: hostname too long\n");
2511 		debuglog("Unable to allocate entry for statd mon\n");
2518 		debuglog("Unable to allocate entry name for statd mon\n");
2530 	debuglog("Locally Monitoring host '%s'\n", hostname);
2532 	debuglog("Attempting to tell statd\n");
2549 			debuglog("Statd call failed\n");
2562 				debuglog("Statd retry with '%s'\n", hostbuf);
2566 					debuglog("No memory for revname\n");
2576 		debuglog("Rpc call to statd failed with return value: %d\n",
2607 		debuglog("Could not find host %16s in mon list\n", hostname);
2615 		debuglog("Negative refcount!: %d\n", ihp->refcnt);
2638 	debuglog("Attempting to unmonitor host %16s\n", name);
2651 		debuglog("Rpc call to unmonitor statd failed with "
2654 		debuglog("Succeeded unmonitoring %16s\n", ihp->name);
2680 		debuglog("expire_lock_hosts: expiring %s %d %d %d\n",
2707 	debuglog("notify from %s, new state %d", hostname, state);
2714 	debuglog("Leaving notify\n");
2729 	debuglog("About to send granted on blocked lock\n");
2761 		debuglog("sending v4 reply%s",
2784 		debuglog("sending v1 reply%s",
2796 		debuglog("clnt_call returns %d(%s) for granted",
2815 	debuglog("Entering granted_failed, status %d\n", arg->stat.stat);
2818 		debuglog("Exiting granted_failed: bogus cookie size %d\n",
2823 	debuglog("granted_failed, cookie 0x%llx\n", cookie);
2826 		debuglog("Pointer to file lock: %p\n",ifl);
2828 		debuglog("****Dump of ifl****\n");
2834 		debuglog("granted_failed: cookie found\n");
2842 		debuglog("granted_failed: cookie NOT FOUND\n");
2845 	debuglog("Exiting granted_failed\n");
2858 	debuglog("Entering getshare...\n");
2861 		debuglog("getshare denied - grace period\n");
2868 		debuglog("received fhandle size %d, max size %d",
2891 			debuglog("fhopen failed (from %16s): %32s\n",
2904 			debuglog("getshare failed: can't allocate sharefile\n");
2911 			debuglog("getshare failed: can't allocate sharefile filehandle\n");
2932 			debuglog("getshare: updated existing share\n");
2938 			debuglog("getshare: conflicts with existing share\n");
2948 		debuglog("getshare failed: hostname too long\n");
2952 		debuglog("getshare failed: can't allocate share\n");
2965 		debuglog("getshare failed: can't allocate share owner handle\n");
2984 	debuglog("Exiting getshare...\n");
2997 	debuglog("Entering unshare...\n");
3000 		debuglog("received fhandle size %d, max size %d",
3017 		debuglog("unshare: no such share file\n");
3031 		debuglog("unshare: no such share\n");
3043 		debuglog("unshare: file has no more shares\n");
3050 	debuglog("Exiting unshare...\n");