/* This Source Code Form is subject to the terms of the Mozilla Public * License, v. 2.0. If a copy of the MPL was not distributed with this * file, You can obtain one at http://mozilla.org/MPL/2.0/. */ /* * nssilock.c - NSS lock instrumentation wrapper functions * * NOTE - These are not public interfaces * * Implementation Notes: * I've tried to make the instrumentation relatively non-intrusive. * To do this, I have used a single PR_LOG() call in each * instrumented function. There's room for improvement. * * */ #include "prinit.h" #include "prerror.h" #include "prlock.h" #include "prmem.h" #include "prenv.h" #include "prcvar.h" #include "prio.h" #if defined(NEED_NSS_ILOCK) #include "prlog.h" #include "nssilock.h" /* ** Declare the instrumented PZLock */ struct pzlock_s { PRLock *lock; /* the PZLock to be instrumented */ PRIntervalTime time; /* timestamp when the lock was aquired */ nssILockType ltype; }; /* ** Declare the instrumented PZMonitor */ struct pzmonitor_s { PRMonitor *mon; /* the PZMonitor to be instrumented */ PRIntervalTime time; /* timestamp when the monitor was aquired */ nssILockType ltype; }; /* ** Declare the instrumented PZCondVar */ struct pzcondvar_s { PRCondVar *cvar; /* the PZCondVar to be instrumented */ nssILockType ltype; }; /* ** Define a CallOnce type to ensure serialized self-initialization */ static PRCallOnceType coNssILock; /* CallOnce type */ static PRIntn nssILockInitialized; /* initialization done when 1 */ static PRLogModuleInfo *nssILog; /* Log instrumentation to this handle */ #define NUM_TT_ENTRIES 6000000 static PRInt32 traceIndex = -1; /* index into trace table */ static struct pzTrace_s *tt; /* pointer to trace table */ static PRInt32 ttBufSize = (NUM_TT_ENTRIES * sizeof(struct pzTrace_s)); static PRCondVar *ttCVar; static PRLock *ttLock; static PRFileDesc *ttfd; /* trace table file */ /* ** Vtrace() -- Trace events, write events to external media ** ** Vtrace() records traced events in an in-memory trace table ** when the trace table fills, Vtrace writes the entire table ** to a file. ** ** data can be lost! ** */ static void Vtrace( nssILockOp op, nssILockType ltype, PRIntervalTime callTime, PRIntervalTime heldTime, void *lock, PRIntn line, char *file) { PRInt32 idx; struct pzTrace_s *tp; RetryTrace: idx = PR_ATOMIC_INCREMENT(&traceIndex); while (NUM_TT_ENTRIES <= idx || op == FlushTT) { if (NUM_TT_ENTRIES == idx || op == FlushTT) { int writeSize = idx * sizeof(struct pzTrace_s); PR_Lock(ttLock); PR_Write(ttfd, tt, writeSize); traceIndex = -1; PR_NotifyAllCondVar(ttCVar); PR_Unlock(ttLock); goto RetryTrace; } else { PR_Lock(ttLock); while (NUM_TT_ENTRIES < idx) PR_WaitCondVar(ttCVar, PR_INTERVAL_NO_WAIT); PR_Unlock(ttLock); goto RetryTrace; } } /* end while() */ /* create the trace entry */ tp = tt + idx; tp->threadID = PR_GetThreadID(PR_GetCurrentThread()); tp->op = op; tp->ltype = ltype; tp->callTime = callTime; tp->heldTime = heldTime; tp->lock = lock; tp->line = line; strcpy(tp->file, file); return; } /* --- end Vtrace() --- */ /* ** pz_TraceFlush() -- Force trace table write to file ** */ extern void pz_TraceFlush(void) { Vtrace(FlushTT, nssILockSelfServ, 0, 0, NULL, 0, ""); return; } /* --- end pz_TraceFlush() --- */ /* ** nssILockInit() -- Initialization for nssilock ** ** This function is called from the CallOnce mechanism. */ static PRStatus nssILockInit(void) { int i; nssILockInitialized = 1; /* new log module */ nssILog = PR_NewLogModule("nssilock"); if (NULL == nssILog) { return (PR_FAILURE); } tt = PR_Calloc(NUM_TT_ENTRIES, sizeof(struct pzTrace_s)); if (NULL == tt) { fprintf(stderr, "nssilock: can't allocate trace table\n"); exit(1); } ttfd = PR_Open("xxxTTLog", PR_CREATE_FILE | PR_WRONLY, 0666); if (NULL == ttfd) { fprintf(stderr, "Oh Drat! Can't open 'xxxTTLog'\n"); exit(1); } ttLock = PR_NewLock(); ttCVar = PR_NewCondVar(ttLock); return (PR_SUCCESS); } /* --- end nssILockInit() --- */ extern PZLock * pz_NewLock( nssILockType ltype, char *file, PRIntn line) { PRStatus rc; PZLock *lock; /* Self Initialize the nssILock feature */ if (!nssILockInitialized) { rc = PR_CallOnce(&coNssILock, nssILockInit); if (PR_FAILURE == rc) { PR_SetError(PR_UNKNOWN_ERROR, 0); return (NULL); } } lock = PR_NEWZAP(PZLock); if (NULL != lock) { lock->ltype = ltype; lock->lock = PR_NewLock(); if (NULL == lock->lock) { PR_DELETE(lock); PORT_SetError(SEC_ERROR_NO_MEMORY); } } else { PORT_SetError(SEC_ERROR_NO_MEMORY); } Vtrace(NewLock, ltype, 0, 0, lock, line, file); return (lock); } /* --- end pz_NewLock() --- */ extern void pz_Lock( PZLock *lock, char *file, PRIntn line) { PRIntervalTime callTime; callTime = PR_IntervalNow(); PR_Lock(lock->lock); lock->time = PR_IntervalNow(); callTime = lock->time - callTime; Vtrace(Lock, lock->ltype, callTime, 0, lock, line, file); return; } /* --- end pz_Lock() --- */ extern PRStatus pz_Unlock( PZLock *lock, char *file, PRIntn line) { PRStatus rc; PRIntervalTime callTime, now, heldTime; callTime = PR_IntervalNow(); rc = PR_Unlock(lock->lock); now = PR_IntervalNow(); callTime = now - callTime; heldTime = now - lock->time; Vtrace(Unlock, lock->ltype, callTime, heldTime, lock, line, file); return (rc); } /* --- end pz_Unlock() --- */ extern void pz_DestroyLock( PZLock *lock, char *file, PRIntn line) { Vtrace(DestroyLock, lock->ltype, 0, 0, lock, line, file); PR_DestroyLock(lock->lock); PR_DELETE(lock); return; } /* --- end pz_DestroyLock() --- */ extern PZCondVar * pz_NewCondVar( PZLock *lock, char *file, PRIntn line) { PZCondVar *cvar; cvar = PR_NEWZAP(PZCondVar); if (NULL == cvar) { PORT_SetError(SEC_ERROR_NO_MEMORY); } else { cvar->ltype = lock->ltype; cvar->cvar = PR_NewCondVar(lock->lock); if (NULL == cvar->cvar) { PR_DELETE(cvar); PORT_SetError(SEC_ERROR_NO_MEMORY); } } Vtrace(NewCondVar, lock->ltype, 0, 0, cvar, line, file); return (cvar); } /* --- end pz_NewCondVar() --- */ extern void pz_DestroyCondVar( PZCondVar *cvar, char *file, PRIntn line) { Vtrace(DestroyCondVar, cvar->ltype, 0, 0, cvar, line, file); PR_DestroyCondVar(cvar->cvar); PR_DELETE(cvar); } /* --- end pz_DestroyCondVar() --- */ extern PRStatus pz_WaitCondVar( PZCondVar *cvar, PRIntervalTime timeout, char *file, PRIntn line) { PRStatus rc; PRIntervalTime callTime; callTime = PR_IntervalNow(); rc = PR_WaitCondVar(cvar->cvar, timeout); callTime = PR_IntervalNow() - callTime; Vtrace(WaitCondVar, cvar->ltype, callTime, 0, cvar, line, file); return (rc); } /* --- end pz_WaitCondVar() --- */ extern PRStatus pz_NotifyCondVar( PZCondVar *cvar, char *file, PRIntn line) { PRStatus rc; rc = PR_NotifyCondVar(cvar->cvar); Vtrace(NotifyCondVar, cvar->ltype, 0, 0, cvar, line, file); return (rc); } /* --- end pz_NotifyCondVar() --- */ extern PRStatus pz_NotifyAllCondVar( PZCondVar *cvar, char *file, PRIntn line) { PRStatus rc; rc = PR_NotifyAllCondVar(cvar->cvar); Vtrace(NotifyAllCondVar, cvar->ltype, 0, 0, cvar, line, file); return (rc); } /* --- end pz_NotifyAllCondVar() --- */ extern PZMonitor * pz_NewMonitor( nssILockType ltype, char *file, PRIntn line) { PRStatus rc; PZMonitor *mon; /* Self Initialize the nssILock feature */ if (!nssILockInitialized) { rc = PR_CallOnce(&coNssILock, nssILockInit); if (PR_FAILURE == rc) { PR_SetError(PR_UNKNOWN_ERROR, 0); return (NULL); } } mon = PR_NEWZAP(PZMonitor); if (NULL != mon) { mon->ltype = ltype; mon->mon = PR_NewMonitor(); if (NULL == mon->mon) { PR_DELETE(mon); PORT_SetError(SEC_ERROR_NO_MEMORY); } } else { PORT_SetError(SEC_ERROR_NO_MEMORY); } Vtrace(NewMonitor, ltype, 0, 0, mon, line, file); return (mon); } /* --- end pz_NewMonitor() --- */ extern void pz_DestroyMonitor( PZMonitor *mon, char *file, PRIntn line) { Vtrace(DestroyMonitor, mon->ltype, 0, 0, mon, line, file); PR_DestroyMonitor(mon->mon); PR_DELETE(mon); return; } /* --- end pz_DestroyMonitor() --- */ extern void pz_EnterMonitor( PZMonitor *mon, char *file, PRIntn line) { PRIntervalTime callTime, now; callTime = PR_IntervalNow(); PR_EnterMonitor(mon->mon); now = PR_IntervalNow(); callTime = now - callTime; if (PR_GetMonitorEntryCount(mon->mon) == 1) { mon->time = now; } Vtrace(EnterMonitor, mon->ltype, callTime, 0, mon, line, file); return; } /* --- end pz_EnterMonitor() --- */ extern PRStatus pz_ExitMonitor( PZMonitor *mon, char *file, PRIntn line) { PRStatus rc; PRIntervalTime callTime, now, heldTime; PRIntn mec = PR_GetMonitorEntryCount(mon->mon); heldTime = (PRIntervalTime)-1; callTime = PR_IntervalNow(); rc = PR_ExitMonitor(mon->mon); now = PR_IntervalNow(); callTime = now - callTime; if (mec == 1) heldTime = now - mon->time; Vtrace(ExitMonitor, mon->ltype, callTime, heldTime, mon, line, file); return (rc); } /* --- end pz_ExitMonitor() --- */ extern PRIntn pz_GetMonitorEntryCount( PZMonitor *mon, char *file, PRIntn line) { return (PR_GetMonitorEntryCount(mon->mon)); } /* --- end pz_GetMonitorEntryCount() --- */ extern PRStatus pz_Wait( PZMonitor *mon, PRIntervalTime ticks, char *file, PRIntn line) { PRStatus rc; PRIntervalTime callTime; callTime = PR_IntervalNow(); rc = PR_Wait(mon->mon, ticks); callTime = PR_IntervalNow() - callTime; Vtrace(Wait, mon->ltype, callTime, 0, mon, line, file); return (rc); } /* --- end pz_Wait() --- */ extern PRStatus pz_Notify( PZMonitor *mon, char *file, PRIntn line) { PRStatus rc; PRIntervalTime callTime; callTime = PR_IntervalNow(); rc = PR_Notify(mon->mon); callTime = PR_IntervalNow() - callTime; Vtrace(Notify, mon->ltype, callTime, 0, mon, line, file); return (rc); } /* --- end pz_Notify() --- */ extern PRStatus pz_NotifyAll( PZMonitor *mon, char *file, PRIntn line) { PRStatus rc; PRIntervalTime callTime; callTime = PR_IntervalNow(); rc = PR_NotifyAll(mon->mon); callTime = PR_IntervalNow() - callTime; Vtrace(NotifyAll, mon->ltype, callTime, 0, mon, line, file); return (rc); } /* --- end pz_NotifyAll() --- */ #endif /* NEED_NSS_ILOCK */ /* --- end nssilock.c --------------------------------- */