2 * Time auditing VFS module for samba. Log time taken for VFS call to syslog
5 * Copyright (C) Abhidnya Chirmule <achirmul@in.ibm.com> 2009
7 * This program is free software; you can redistribute it and/or modify
8 * it under the terms of the GNU General Public License as published by
9 * the Free Software Foundation; either version 3 of the License, or
10 * (at your option) any later version.
12 * This program is distributed in the hope that it will be useful,
13 * but WITHOUT ANY WARRANTY; without even the implied warranty of
14 * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the
15 * GNU General Public License for more details.
17 * You should have received a copy of the GNU General Public License
18 * along with this program; if not, see <http://www.gnu.org/licenses/>.
22 * This module implements logging for time taken for all Samba VFS operations.
24 * vfs objects = time_audit
29 #include "smbd/smbd.h"
31 #include "lib/util/tevent_unix.h"
32 #include "lib/util/tevent_ntstatus.h"
35 #define DBGC_CLASS DBGC_VFS
37 static double audit_timeout;
39 static void smb_time_audit_log_msg(const char *syscallname, double elapsed,
42 DEBUG(0, ("WARNING: VFS call \"%s\" took unexpectedly long "
43 "(%.2f seconds) %s%s-- Validate that file and storage "
44 "subsystems are operating normally\n", syscallname,
45 elapsed, (msg != NULL) ? msg : "",
46 (msg != NULL) ? " " : ""));
49 static void smb_time_audit_log(const char *syscallname, double elapsed)
51 smb_time_audit_log_msg(syscallname, elapsed, NULL);
54 static void smb_time_audit_log_fsp(const char *syscallname, double elapsed,
55 const struct files_struct *fsp)
57 char *base_name = NULL;
58 char *connectpath = NULL;
62 smb_time_audit_log(syscallname, elapsed);
66 connectpath = fsp->conn->connectpath;
68 base_name = fsp->fsp_name->base_name;
70 if (connectpath != NULL && base_name != NULL) {
71 msg = talloc_asprintf(talloc_tos(), "filename = \"%s/%s\"",
72 connectpath, base_name);
73 } else if (connectpath != NULL && base_name == NULL) {
74 msg = talloc_asprintf(talloc_tos(), "connectpath = \"%s\", "
77 } else if (connectpath == NULL && base_name != NULL) {
78 msg = talloc_asprintf(talloc_tos(), "connectpath = <NULL>, "
81 } else { /* connectpath == NULL && base_name == NULL */
82 msg = talloc_asprintf(talloc_tos(), "connectpath = <NULL>, "
83 "base_name = <NULL>");
85 smb_time_audit_log_msg(syscallname, elapsed, msg);
89 static void smb_time_audit_log_fname(const char *syscallname, double elapsed,
95 if (getcwd(cwd, sizeof(cwd)) == NULL) {
96 snprintf(cwd, sizeof(cwd), "<getcwd() error %d>", errno);
99 msg = talloc_asprintf(talloc_tos(),
100 "cwd = \"%s\", filename = \"%s\"",
103 msg = talloc_asprintf(talloc_tos(),
104 "cwd = \"%s\", filename = <NULL>",
107 smb_time_audit_log_msg(syscallname, elapsed, msg);
111 static void smb_time_audit_log_smb_fname(const char *syscallname, double elapsed,
112 const struct smb_filename *smb_fname)
114 if (smb_fname != NULL) {
115 smb_time_audit_log_fname(syscallname, elapsed,
116 smb_fname->base_name);
118 smb_time_audit_log_fname(syscallname, elapsed,
119 "smb_fname = <NULL>");
123 static int smb_time_audit_connect(vfs_handle_struct *handle,
124 const char *svc, const char *user)
127 struct timespec ts1,ts2;
134 clock_gettime_mono(&ts1);
135 result = SMB_VFS_NEXT_CONNECT(handle, svc, user);
136 clock_gettime_mono(&ts2);
137 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
138 if (timediff > audit_timeout) {
139 smb_time_audit_log_msg("connect", timediff, user);
144 static void smb_time_audit_disconnect(vfs_handle_struct *handle)
146 struct timespec ts1,ts2;
149 clock_gettime_mono(&ts1);
150 SMB_VFS_NEXT_DISCONNECT(handle);
151 clock_gettime_mono(&ts2);
152 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
154 if (timediff > audit_timeout) {
155 smb_time_audit_log("disconnect", timediff);
159 static uint64_t smb_time_audit_disk_free(vfs_handle_struct *handle,
161 bool small_query, uint64_t *bsize,
162 uint64_t *dfree, uint64_t *dsize)
165 struct timespec ts1,ts2;
168 clock_gettime_mono(&ts1);
169 result = SMB_VFS_NEXT_DISK_FREE(handle, path, small_query, bsize,
171 clock_gettime_mono(&ts2);
172 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
174 /* Don't have a reasonable notion of failure here */
175 if (timediff > audit_timeout) {
176 smb_time_audit_log_fname("disk_free", timediff, path);
182 static int smb_time_audit_get_quota(struct vfs_handle_struct *handle,
183 enum SMB_QUOTA_TYPE qtype, unid_t id,
187 struct timespec ts1,ts2;
190 clock_gettime_mono(&ts1);
191 result = SMB_VFS_NEXT_GET_QUOTA(handle, qtype, id, qt);
192 clock_gettime_mono(&ts2);
193 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
195 if (timediff > audit_timeout) {
196 smb_time_audit_log("get_quota", timediff);
201 static int smb_time_audit_set_quota(struct vfs_handle_struct *handle,
202 enum SMB_QUOTA_TYPE qtype, unid_t id,
206 struct timespec ts1,ts2;
209 clock_gettime_mono(&ts1);
210 result = SMB_VFS_NEXT_SET_QUOTA(handle, qtype, id, qt);
211 clock_gettime_mono(&ts2);
212 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
214 if (timediff > audit_timeout) {
215 smb_time_audit_log("set_quota", timediff);
221 static int smb_time_audit_get_shadow_copy_data(struct vfs_handle_struct *handle,
222 struct files_struct *fsp,
223 struct shadow_copy_data *shadow_copy_data,
227 struct timespec ts1,ts2;
230 clock_gettime_mono(&ts1);
231 result = SMB_VFS_NEXT_GET_SHADOW_COPY_DATA(handle, fsp,
232 shadow_copy_data, labels);
233 clock_gettime_mono(&ts2);
234 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
236 if (timediff > audit_timeout) {
237 smb_time_audit_log_fsp("get_shadow_copy_data", timediff, fsp);
243 static int smb_time_audit_statvfs(struct vfs_handle_struct *handle,
245 struct vfs_statvfs_struct *statbuf)
248 struct timespec ts1,ts2;
251 clock_gettime_mono(&ts1);
252 result = SMB_VFS_NEXT_STATVFS(handle, path, statbuf);
253 clock_gettime_mono(&ts2);
254 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
256 if (timediff > audit_timeout) {
257 smb_time_audit_log_fname("statvfs", timediff, path);
263 static uint32_t smb_time_audit_fs_capabilities(struct vfs_handle_struct *handle,
264 enum timestamp_set_resolution *p_ts_res)
267 struct timespec ts1,ts2;
270 clock_gettime_mono(&ts1);
271 result = SMB_VFS_NEXT_FS_CAPABILITIES(handle, p_ts_res);
272 clock_gettime_mono(&ts2);
273 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
275 if (timediff > audit_timeout) {
276 smb_time_audit_log("fs_capabilities", timediff);
282 static DIR *smb_time_audit_opendir(vfs_handle_struct *handle,
284 const char *mask, uint32 attr)
287 struct timespec ts1,ts2;
290 clock_gettime_mono(&ts1);
291 result = SMB_VFS_NEXT_OPENDIR(handle, fname, mask, attr);
292 clock_gettime_mono(&ts2);
293 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
295 if (timediff > audit_timeout) {
296 smb_time_audit_log_fname("opendir", timediff, fname);
302 static DIR *smb_time_audit_fdopendir(vfs_handle_struct *handle,
304 const char *mask, uint32 attr)
307 struct timespec ts1,ts2;
310 clock_gettime_mono(&ts1);
311 result = SMB_VFS_NEXT_FDOPENDIR(handle, fsp, mask, attr);
312 clock_gettime_mono(&ts2);
313 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
315 if (timediff > audit_timeout) {
316 smb_time_audit_log_fsp("fdopendir", timediff, fsp);
322 static struct dirent *smb_time_audit_readdir(vfs_handle_struct *handle,
324 SMB_STRUCT_STAT *sbuf)
326 struct dirent *result;
327 struct timespec ts1,ts2;
330 clock_gettime_mono(&ts1);
331 result = SMB_VFS_NEXT_READDIR(handle, dirp, sbuf);
332 clock_gettime_mono(&ts2);
333 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
335 if (timediff > audit_timeout) {
336 smb_time_audit_log("readdir", timediff);
342 static void smb_time_audit_seekdir(vfs_handle_struct *handle,
343 DIR *dirp, long offset)
345 struct timespec ts1,ts2;
348 clock_gettime_mono(&ts1);
349 SMB_VFS_NEXT_SEEKDIR(handle, dirp, offset);
350 clock_gettime_mono(&ts2);
351 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
353 if (timediff > audit_timeout) {
354 smb_time_audit_log("seekdir", timediff);
359 static long smb_time_audit_telldir(vfs_handle_struct *handle,
363 struct timespec ts1,ts2;
366 clock_gettime_mono(&ts1);
367 result = SMB_VFS_NEXT_TELLDIR(handle, dirp);
368 clock_gettime_mono(&ts2);
369 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
371 if (timediff > audit_timeout) {
372 smb_time_audit_log("telldir", timediff);
378 static void smb_time_audit_rewinddir(vfs_handle_struct *handle,
381 struct timespec ts1,ts2;
384 clock_gettime_mono(&ts1);
385 SMB_VFS_NEXT_REWINDDIR(handle, dirp);
386 clock_gettime_mono(&ts2);
387 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
389 if (timediff > audit_timeout) {
390 smb_time_audit_log("rewinddir", timediff);
395 static int smb_time_audit_mkdir(vfs_handle_struct *handle,
396 const char *path, mode_t mode)
399 struct timespec ts1,ts2;
402 clock_gettime_mono(&ts1);
403 result = SMB_VFS_NEXT_MKDIR(handle, path, mode);
404 clock_gettime_mono(&ts2);
405 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
407 if (timediff > audit_timeout) {
408 smb_time_audit_log_fname("mkdir", timediff, path);
414 static int smb_time_audit_rmdir(vfs_handle_struct *handle,
418 struct timespec ts1,ts2;
421 clock_gettime_mono(&ts1);
422 result = SMB_VFS_NEXT_RMDIR(handle, path);
423 clock_gettime_mono(&ts2);
424 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
426 if (timediff > audit_timeout) {
427 smb_time_audit_log_fname("rmdir", timediff, path);
433 static int smb_time_audit_closedir(vfs_handle_struct *handle,
437 struct timespec ts1,ts2;
440 clock_gettime_mono(&ts1);
441 result = SMB_VFS_NEXT_CLOSEDIR(handle, dirp);
442 clock_gettime_mono(&ts2);
443 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
445 if (timediff > audit_timeout) {
446 smb_time_audit_log("closedir", timediff);
452 static void smb_time_audit_init_search_op(vfs_handle_struct *handle,
455 struct timespec ts1,ts2;
458 clock_gettime_mono(&ts1);
459 SMB_VFS_NEXT_INIT_SEARCH_OP(handle, dirp);
460 clock_gettime_mono(&ts2);
461 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
463 if (timediff > audit_timeout) {
464 smb_time_audit_log("init_search_op", timediff);
468 static int smb_time_audit_open(vfs_handle_struct *handle,
469 struct smb_filename *fname,
471 int flags, mode_t mode)
474 struct timespec ts1,ts2;
477 clock_gettime_mono(&ts1);
478 result = SMB_VFS_NEXT_OPEN(handle, fname, fsp, flags, mode);
479 clock_gettime_mono(&ts2);
480 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
482 if (timediff > audit_timeout) {
483 smb_time_audit_log_fsp("open", timediff, fsp);
489 static NTSTATUS smb_time_audit_create_file(vfs_handle_struct *handle,
490 struct smb_request *req,
491 uint16_t root_dir_fid,
492 struct smb_filename *fname,
493 uint32_t access_mask,
494 uint32_t share_access,
495 uint32_t create_disposition,
496 uint32_t create_options,
497 uint32_t file_attributes,
498 uint32_t oplock_request,
499 uint64_t allocation_size,
500 uint32_t private_flags,
501 struct security_descriptor *sd,
502 struct ea_list *ea_list,
503 files_struct **result_fsp,
507 struct timespec ts1,ts2;
510 clock_gettime_mono(&ts1);
511 result = SMB_VFS_NEXT_CREATE_FILE(
514 root_dir_fid, /* root_dir_fid */
516 access_mask, /* access_mask */
517 share_access, /* share_access */
518 create_disposition, /* create_disposition*/
519 create_options, /* create_options */
520 file_attributes, /* file_attributes */
521 oplock_request, /* oplock_request */
522 allocation_size, /* allocation_size */
525 ea_list, /* ea_list */
526 result_fsp, /* result */
528 clock_gettime_mono(&ts2);
529 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
531 if (timediff > audit_timeout) {
533 * can't use result_fsp this time, may have
534 * invalid content causing smbd crash
536 smb_time_audit_log_smb_fname("create_file", timediff,
543 static int smb_time_audit_close(vfs_handle_struct *handle, files_struct *fsp)
546 struct timespec ts1,ts2;
549 clock_gettime_mono(&ts1);
550 result = SMB_VFS_NEXT_CLOSE(handle, fsp);
551 clock_gettime_mono(&ts2);
552 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
554 if (timediff > audit_timeout) {
555 smb_time_audit_log_fsp("close", timediff, fsp);
561 static ssize_t smb_time_audit_read(vfs_handle_struct *handle,
562 files_struct *fsp, void *data, size_t n)
565 struct timespec ts1,ts2;
568 clock_gettime_mono(&ts1);
569 result = SMB_VFS_NEXT_READ(handle, fsp, data, n);
570 clock_gettime_mono(&ts2);
571 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
573 if (timediff > audit_timeout) {
574 smb_time_audit_log_fsp("read", timediff, fsp);
580 static ssize_t smb_time_audit_pread(vfs_handle_struct *handle,
582 void *data, size_t n, off_t offset)
585 struct timespec ts1,ts2;
588 clock_gettime_mono(&ts1);
589 result = SMB_VFS_NEXT_PREAD(handle, fsp, data, n, offset);
590 clock_gettime_mono(&ts2);
591 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
593 if (timediff > audit_timeout) {
594 smb_time_audit_log_fsp("pread", timediff, fsp);
600 struct smb_time_audit_pread_state {
601 struct files_struct *fsp;
607 static void smb_time_audit_pread_done(struct tevent_req *subreq);
609 static struct tevent_req *smb_time_audit_pread_send(
610 struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
611 struct tevent_context *ev, struct files_struct *fsp,
612 void *data, size_t n, off_t offset)
614 struct tevent_req *req, *subreq;
615 struct smb_time_audit_pread_state *state;
617 req = tevent_req_create(mem_ctx, &state,
618 struct smb_time_audit_pread_state);
622 clock_gettime_mono(&state->ts1);
625 subreq = SMB_VFS_NEXT_PREAD_SEND(state, ev, handle, fsp, data,
627 if (tevent_req_nomem(subreq, req)) {
628 return tevent_req_post(req, ev);
630 tevent_req_set_callback(subreq, smb_time_audit_pread_done, req);
634 static void smb_time_audit_pread_done(struct tevent_req *subreq)
636 struct tevent_req *req = tevent_req_callback_data(
637 subreq, struct tevent_req);
638 struct smb_time_audit_pread_state *state = tevent_req_data(
639 req, struct smb_time_audit_pread_state);
641 state->ret = SMB_VFS_PREAD_RECV(subreq, &state->err);
643 tevent_req_done(req);
646 static ssize_t smb_time_audit_pread_recv(struct tevent_req *req, int *err)
648 struct smb_time_audit_pread_state *state = tevent_req_data(
649 req, struct smb_time_audit_pread_state);
653 clock_gettime_mono(&ts2);
654 timediff = nsec_time_diff(&ts2,&state->ts1)*1.0e-9;
656 if (timediff > audit_timeout) {
657 smb_time_audit_log_fsp("pread", timediff, state->fsp);
660 if (tevent_req_is_unix_error(req, err)) {
667 static ssize_t smb_time_audit_write(vfs_handle_struct *handle,
669 const void *data, size_t n)
672 struct timespec ts1,ts2;
675 clock_gettime_mono(&ts1);
676 result = SMB_VFS_NEXT_WRITE(handle, fsp, data, n);
677 clock_gettime_mono(&ts2);
678 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
680 if (timediff > audit_timeout) {
681 smb_time_audit_log_fsp("write", timediff, fsp);
687 static ssize_t smb_time_audit_pwrite(vfs_handle_struct *handle,
689 const void *data, size_t n,
693 struct timespec ts1,ts2;
696 clock_gettime_mono(&ts1);
697 result = SMB_VFS_NEXT_PWRITE(handle, fsp, data, n, offset);
698 clock_gettime_mono(&ts2);
699 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
701 if (timediff > audit_timeout) {
702 smb_time_audit_log_fsp("pwrite", timediff, fsp);
708 struct smb_time_audit_pwrite_state {
709 struct files_struct *fsp;
715 static void smb_time_audit_pwrite_done(struct tevent_req *subreq);
717 static struct tevent_req *smb_time_audit_pwrite_send(
718 struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
719 struct tevent_context *ev, struct files_struct *fsp,
720 const void *data, size_t n, off_t offset)
722 struct tevent_req *req, *subreq;
723 struct smb_time_audit_pwrite_state *state;
725 req = tevent_req_create(mem_ctx, &state,
726 struct smb_time_audit_pwrite_state);
730 clock_gettime_mono(&state->ts1);
733 subreq = SMB_VFS_NEXT_PWRITE_SEND(state, ev, handle, fsp, data,
735 if (tevent_req_nomem(subreq, req)) {
736 return tevent_req_post(req, ev);
738 tevent_req_set_callback(subreq, smb_time_audit_pwrite_done, req);
742 static void smb_time_audit_pwrite_done(struct tevent_req *subreq)
744 struct tevent_req *req = tevent_req_callback_data(
745 subreq, struct tevent_req);
746 struct smb_time_audit_pwrite_state *state = tevent_req_data(
747 req, struct smb_time_audit_pwrite_state);
749 state->ret = SMB_VFS_PWRITE_RECV(subreq, &state->err);
751 tevent_req_done(req);
754 static ssize_t smb_time_audit_pwrite_recv(struct tevent_req *req, int *err)
756 struct smb_time_audit_pwrite_state *state = tevent_req_data(
757 req, struct smb_time_audit_pwrite_state);
761 clock_gettime_mono(&ts2);
762 timediff = nsec_time_diff(&ts2,&state->ts1)*1.0e-9;
764 if (timediff > audit_timeout) {
765 smb_time_audit_log_fsp("pwrite", timediff, state->fsp);
768 if (tevent_req_is_unix_error(req, err)) {
775 static off_t smb_time_audit_lseek(vfs_handle_struct *handle,
777 off_t offset, int whence)
780 struct timespec ts1,ts2;
783 clock_gettime_mono(&ts1);
784 result = SMB_VFS_NEXT_LSEEK(handle, fsp, offset, whence);
785 clock_gettime_mono(&ts2);
786 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
788 if (timediff > audit_timeout) {
789 smb_time_audit_log_fsp("lseek", timediff, fsp);
795 static ssize_t smb_time_audit_sendfile(vfs_handle_struct *handle, int tofd,
796 files_struct *fromfsp,
797 const DATA_BLOB *hdr, off_t offset,
801 struct timespec ts1,ts2;
804 clock_gettime_mono(&ts1);
805 result = SMB_VFS_NEXT_SENDFILE(handle, tofd, fromfsp, hdr, offset, n);
806 clock_gettime_mono(&ts2);
807 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
809 if (timediff > audit_timeout) {
810 smb_time_audit_log_fsp("sendfile", timediff, fromfsp);
816 static ssize_t smb_time_audit_recvfile(vfs_handle_struct *handle, int fromfd,
822 struct timespec ts1,ts2;
825 clock_gettime_mono(&ts1);
826 result = SMB_VFS_NEXT_RECVFILE(handle, fromfd, tofsp, offset, n);
827 clock_gettime_mono(&ts2);
828 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
830 if (timediff > audit_timeout) {
831 smb_time_audit_log_fsp("recvfile", timediff, tofsp);
837 static int smb_time_audit_rename(vfs_handle_struct *handle,
838 const struct smb_filename *oldname,
839 const struct smb_filename *newname)
842 struct timespec ts1,ts2;
845 clock_gettime_mono(&ts1);
846 result = SMB_VFS_NEXT_RENAME(handle, oldname, newname);
847 clock_gettime_mono(&ts2);
848 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
850 if (timediff > audit_timeout) {
851 smb_time_audit_log_smb_fname("rename", timediff, newname);
857 static int smb_time_audit_fsync(vfs_handle_struct *handle, files_struct *fsp)
860 struct timespec ts1,ts2;
863 clock_gettime_mono(&ts1);
864 result = SMB_VFS_NEXT_FSYNC(handle, fsp);
865 clock_gettime_mono(&ts2);
866 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
868 if (timediff > audit_timeout) {
869 smb_time_audit_log_fsp("fsync", timediff, fsp);
875 struct smb_time_audit_fsync_state {
876 struct files_struct *fsp;
882 static void smb_time_audit_fsync_done(struct tevent_req *subreq);
884 static struct tevent_req *smb_time_audit_fsync_send(
885 struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
886 struct tevent_context *ev, struct files_struct *fsp)
888 struct tevent_req *req, *subreq;
889 struct smb_time_audit_fsync_state *state;
891 req = tevent_req_create(mem_ctx, &state,
892 struct smb_time_audit_fsync_state);
896 clock_gettime_mono(&state->ts1);
899 subreq = SMB_VFS_NEXT_FSYNC_SEND(state, ev, handle, fsp);
900 if (tevent_req_nomem(subreq, req)) {
901 return tevent_req_post(req, ev);
903 tevent_req_set_callback(subreq, smb_time_audit_fsync_done, req);
907 static void smb_time_audit_fsync_done(struct tevent_req *subreq)
909 struct tevent_req *req = tevent_req_callback_data(
910 subreq, struct tevent_req);
911 struct smb_time_audit_fsync_state *state = tevent_req_data(
912 req, struct smb_time_audit_fsync_state);
914 state->ret = SMB_VFS_FSYNC_RECV(subreq, &state->err);
916 tevent_req_done(req);
919 static int smb_time_audit_fsync_recv(struct tevent_req *req, int *err)
921 struct smb_time_audit_fsync_state *state = tevent_req_data(
922 req, struct smb_time_audit_fsync_state);
926 clock_gettime_mono(&ts2);
927 timediff = nsec_time_diff(&ts2,&state->ts1)*1.0e-9;
929 if (timediff > audit_timeout) {
930 smb_time_audit_log_fsp("fsync", timediff, state->fsp);
933 if (tevent_req_is_unix_error(req, err)) {
940 static int smb_time_audit_stat(vfs_handle_struct *handle,
941 struct smb_filename *fname)
944 struct timespec ts1,ts2;
947 clock_gettime_mono(&ts1);
948 result = SMB_VFS_NEXT_STAT(handle, fname);
949 clock_gettime_mono(&ts2);
950 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
952 if (timediff > audit_timeout) {
953 smb_time_audit_log_smb_fname("stat", timediff, fname);
959 static int smb_time_audit_fstat(vfs_handle_struct *handle, files_struct *fsp,
960 SMB_STRUCT_STAT *sbuf)
963 struct timespec ts1,ts2;
966 clock_gettime_mono(&ts1);
967 result = SMB_VFS_NEXT_FSTAT(handle, fsp, sbuf);
968 clock_gettime_mono(&ts2);
969 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
971 if (timediff > audit_timeout) {
972 smb_time_audit_log_fsp("fstat", timediff, fsp);
978 static int smb_time_audit_lstat(vfs_handle_struct *handle,
979 struct smb_filename *path)
982 struct timespec ts1,ts2;
985 clock_gettime_mono(&ts1);
986 result = SMB_VFS_NEXT_LSTAT(handle, path);
987 clock_gettime_mono(&ts2);
988 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
990 if (timediff > audit_timeout) {
991 smb_time_audit_log_smb_fname("lstat", timediff, path);
997 static uint64_t smb_time_audit_get_alloc_size(vfs_handle_struct *handle,
999 const SMB_STRUCT_STAT *sbuf)
1002 struct timespec ts1,ts2;
1005 clock_gettime_mono(&ts1);
1006 result = SMB_VFS_NEXT_GET_ALLOC_SIZE(handle, fsp, sbuf);
1007 clock_gettime_mono(&ts2);
1008 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1010 if (timediff > audit_timeout) {
1011 smb_time_audit_log_fsp("get_alloc_size", timediff, fsp);
1017 static int smb_time_audit_unlink(vfs_handle_struct *handle,
1018 const struct smb_filename *path)
1021 struct timespec ts1,ts2;
1024 clock_gettime_mono(&ts1);
1025 result = SMB_VFS_NEXT_UNLINK(handle, path);
1026 clock_gettime_mono(&ts2);
1027 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1029 if (timediff > audit_timeout) {
1030 smb_time_audit_log_smb_fname("unlink", timediff, path);
1036 static int smb_time_audit_chmod(vfs_handle_struct *handle,
1037 const char *path, mode_t mode)
1040 struct timespec ts1,ts2;
1043 clock_gettime_mono(&ts1);
1044 result = SMB_VFS_NEXT_CHMOD(handle, path, mode);
1045 clock_gettime_mono(&ts2);
1046 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1048 if (timediff > audit_timeout) {
1049 smb_time_audit_log_fname("chmod", timediff, path);
1055 static int smb_time_audit_fchmod(vfs_handle_struct *handle, files_struct *fsp,
1059 struct timespec ts1,ts2;
1062 clock_gettime_mono(&ts1);
1063 result = SMB_VFS_NEXT_FCHMOD(handle, fsp, mode);
1064 clock_gettime_mono(&ts2);
1065 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1067 if (timediff > audit_timeout) {
1068 smb_time_audit_log_fsp("fchmod", timediff, fsp);
1074 static int smb_time_audit_chown(vfs_handle_struct *handle,
1075 const char *path, uid_t uid, gid_t gid)
1078 struct timespec ts1,ts2;
1081 clock_gettime_mono(&ts1);
1082 result = SMB_VFS_NEXT_CHOWN(handle, path, uid, gid);
1083 clock_gettime_mono(&ts2);
1084 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1086 if (timediff > audit_timeout) {
1087 smb_time_audit_log_fname("chown", timediff, path);
1093 static int smb_time_audit_fchown(vfs_handle_struct *handle, files_struct *fsp,
1094 uid_t uid, gid_t gid)
1097 struct timespec ts1,ts2;
1100 clock_gettime_mono(&ts1);
1101 result = SMB_VFS_NEXT_FCHOWN(handle, fsp, uid, gid);
1102 clock_gettime_mono(&ts2);
1103 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1105 if (timediff > audit_timeout) {
1106 smb_time_audit_log_fsp("fchown", timediff, fsp);
1112 static int smb_time_audit_lchown(vfs_handle_struct *handle,
1113 const char *path, uid_t uid, gid_t gid)
1116 struct timespec ts1,ts2;
1119 clock_gettime_mono(&ts1);
1120 result = SMB_VFS_NEXT_LCHOWN(handle, path, uid, gid);
1121 clock_gettime_mono(&ts2);
1122 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1124 if (timediff > audit_timeout) {
1125 smb_time_audit_log_fname("lchown", timediff, path);
1131 static int smb_time_audit_chdir(vfs_handle_struct *handle, const char *path)
1134 struct timespec ts1,ts2;
1137 clock_gettime_mono(&ts1);
1138 result = SMB_VFS_NEXT_CHDIR(handle, path);
1139 clock_gettime_mono(&ts2);
1140 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1142 if (timediff > audit_timeout) {
1143 smb_time_audit_log_fname("chdir", timediff, path);
1149 static char *smb_time_audit_getwd(vfs_handle_struct *handle)
1152 struct timespec ts1,ts2;
1155 clock_gettime_mono(&ts1);
1156 result = SMB_VFS_NEXT_GETWD(handle);
1157 clock_gettime_mono(&ts2);
1158 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1160 if (timediff > audit_timeout) {
1161 smb_time_audit_log("getwd", timediff);
1167 static int smb_time_audit_ntimes(vfs_handle_struct *handle,
1168 const struct smb_filename *path,
1169 struct smb_file_time *ft)
1172 struct timespec ts1,ts2;
1175 clock_gettime_mono(&ts1);
1176 result = SMB_VFS_NEXT_NTIMES(handle, path, ft);
1177 clock_gettime_mono(&ts2);
1178 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1180 if (timediff > audit_timeout) {
1181 smb_time_audit_log_smb_fname("ntimes", timediff, path);
1187 static int smb_time_audit_ftruncate(vfs_handle_struct *handle,
1192 struct timespec ts1,ts2;
1195 clock_gettime_mono(&ts1);
1196 result = SMB_VFS_NEXT_FTRUNCATE(handle, fsp, len);
1197 clock_gettime_mono(&ts2);
1198 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1200 if (timediff > audit_timeout) {
1201 smb_time_audit_log_fsp("ftruncate", timediff, fsp);
1207 static int smb_time_audit_fallocate(vfs_handle_struct *handle,
1209 enum vfs_fallocate_mode mode,
1214 struct timespec ts1,ts2;
1217 clock_gettime_mono(&ts1);
1218 result = SMB_VFS_NEXT_FALLOCATE(handle, fsp, mode, offset, len);
1219 clock_gettime_mono(&ts2);
1220 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1222 if (timediff > audit_timeout) {
1223 smb_time_audit_log_fsp("fallocate", timediff, fsp);
1229 static bool smb_time_audit_lock(vfs_handle_struct *handle, files_struct *fsp,
1230 int op, off_t offset, off_t count,
1234 struct timespec ts1,ts2;
1237 clock_gettime_mono(&ts1);
1238 result = SMB_VFS_NEXT_LOCK(handle, fsp, op, offset, count, type);
1239 clock_gettime_mono(&ts2);
1240 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1242 if (timediff > audit_timeout) {
1243 smb_time_audit_log_fsp("lock", timediff, fsp);
1249 static int smb_time_audit_kernel_flock(struct vfs_handle_struct *handle,
1250 struct files_struct *fsp,
1251 uint32 share_mode, uint32 access_mask)
1254 struct timespec ts1,ts2;
1257 clock_gettime_mono(&ts1);
1258 result = SMB_VFS_NEXT_KERNEL_FLOCK(handle, fsp, share_mode,
1260 clock_gettime_mono(&ts2);
1261 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1263 if (timediff > audit_timeout) {
1264 smb_time_audit_log_fsp("kernel_flock", timediff, fsp);
1270 static int smb_time_audit_linux_setlease(vfs_handle_struct *handle,
1275 struct timespec ts1,ts2;
1278 clock_gettime_mono(&ts1);
1279 result = SMB_VFS_NEXT_LINUX_SETLEASE(handle, fsp, leasetype);
1280 clock_gettime_mono(&ts2);
1281 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1283 if (timediff > audit_timeout) {
1284 smb_time_audit_log_fsp("linux_setlease", timediff, fsp);
1290 static bool smb_time_audit_getlock(vfs_handle_struct *handle,
1292 off_t *poffset, off_t *pcount,
1293 int *ptype, pid_t *ppid)
1296 struct timespec ts1,ts2;
1299 clock_gettime_mono(&ts1);
1300 result = SMB_VFS_NEXT_GETLOCK(handle, fsp, poffset, pcount, ptype,
1302 clock_gettime_mono(&ts2);
1303 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1305 if (timediff > audit_timeout) {
1306 smb_time_audit_log_fsp("getlock", timediff, fsp);
1312 static int smb_time_audit_symlink(vfs_handle_struct *handle,
1313 const char *oldpath, const char *newpath)
1316 struct timespec ts1,ts2;
1319 clock_gettime_mono(&ts1);
1320 result = SMB_VFS_NEXT_SYMLINK(handle, oldpath, newpath);
1321 clock_gettime_mono(&ts2);
1322 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1324 if (timediff > audit_timeout) {
1325 smb_time_audit_log_fname("symlink", timediff, newpath);
1331 static int smb_time_audit_readlink(vfs_handle_struct *handle,
1332 const char *path, char *buf, size_t bufsiz)
1335 struct timespec ts1,ts2;
1338 clock_gettime_mono(&ts1);
1339 result = SMB_VFS_NEXT_READLINK(handle, path, buf, bufsiz);
1340 clock_gettime_mono(&ts2);
1341 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1343 if (timediff > audit_timeout) {
1344 smb_time_audit_log_fname("readlink", timediff, path);
1350 static int smb_time_audit_link(vfs_handle_struct *handle,
1351 const char *oldpath, const char *newpath)
1354 struct timespec ts1,ts2;
1357 clock_gettime_mono(&ts1);
1358 result = SMB_VFS_NEXT_LINK(handle, oldpath, newpath);
1359 clock_gettime_mono(&ts2);
1360 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1362 if (timediff > audit_timeout) {
1363 smb_time_audit_log_fname("link", timediff, newpath);
1369 static int smb_time_audit_mknod(vfs_handle_struct *handle,
1370 const char *pathname, mode_t mode,
1374 struct timespec ts1,ts2;
1377 clock_gettime_mono(&ts1);
1378 result = SMB_VFS_NEXT_MKNOD(handle, pathname, mode, dev);
1379 clock_gettime_mono(&ts2);
1380 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1382 if (timediff > audit_timeout) {
1383 smb_time_audit_log_fname("mknod", timediff, pathname);
1389 static char *smb_time_audit_realpath(vfs_handle_struct *handle,
1393 struct timespec ts1,ts2;
1396 clock_gettime_mono(&ts1);
1397 result = SMB_VFS_NEXT_REALPATH(handle, path);
1398 clock_gettime_mono(&ts2);
1399 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1401 if (timediff > audit_timeout) {
1402 smb_time_audit_log_fname("realpath", timediff, path);
1408 static NTSTATUS smb_time_audit_notify_watch(struct vfs_handle_struct *handle,
1409 struct sys_notify_context *ctx,
1412 uint32_t *subdir_filter,
1413 void (*callback)(struct sys_notify_context *ctx,
1415 struct notify_event *ev),
1416 void *private_data, void *handle_p)
1419 struct timespec ts1,ts2;
1422 clock_gettime_mono(&ts1);
1423 result = SMB_VFS_NEXT_NOTIFY_WATCH(handle, ctx, path,
1424 filter, subdir_filter, callback,
1425 private_data, handle_p);
1426 clock_gettime_mono(&ts2);
1427 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1429 if (timediff > audit_timeout) {
1430 smb_time_audit_log_fname("notify_watch", timediff, path);
1436 static int smb_time_audit_chflags(vfs_handle_struct *handle,
1437 const char *path, unsigned int flags)
1440 struct timespec ts1,ts2;
1443 clock_gettime_mono(&ts1);
1444 result = SMB_VFS_NEXT_CHFLAGS(handle, path, flags);
1445 clock_gettime_mono(&ts2);
1446 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1448 if (timediff > audit_timeout) {
1449 smb_time_audit_log_fname("chflags", timediff, path);
1455 static struct file_id smb_time_audit_file_id_create(struct vfs_handle_struct *handle,
1456 const SMB_STRUCT_STAT *sbuf)
1458 struct file_id id_zero;
1459 struct file_id result;
1460 struct timespec ts1,ts2;
1463 ZERO_STRUCT(id_zero);
1465 clock_gettime_mono(&ts1);
1466 result = SMB_VFS_NEXT_FILE_ID_CREATE(handle, sbuf);
1467 clock_gettime_mono(&ts2);
1468 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1470 if (timediff > audit_timeout) {
1471 smb_time_audit_log("file_id_create", timediff);
1477 static NTSTATUS smb_time_audit_streaminfo(vfs_handle_struct *handle,
1478 struct files_struct *fsp,
1480 TALLOC_CTX *mem_ctx,
1481 unsigned int *pnum_streams,
1482 struct stream_struct **pstreams)
1485 struct timespec ts1,ts2;
1488 clock_gettime_mono(&ts1);
1489 result = SMB_VFS_NEXT_STREAMINFO(handle, fsp, fname, mem_ctx,
1490 pnum_streams, pstreams);
1491 clock_gettime_mono(&ts2);
1492 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1494 if (timediff > audit_timeout) {
1495 smb_time_audit_log_fsp("streaminfo", timediff, fsp);
1501 static int smb_time_audit_get_real_filename(struct vfs_handle_struct *handle,
1504 TALLOC_CTX *mem_ctx,
1508 struct timespec ts1,ts2;
1511 clock_gettime_mono(&ts1);
1512 result = SMB_VFS_NEXT_GET_REAL_FILENAME(handle, path, name, mem_ctx,
1514 clock_gettime_mono(&ts2);
1515 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1517 if (timediff > audit_timeout) {
1518 smb_time_audit_log_fname("get_real_filename", timediff, path);
1524 static const char *smb_time_audit_connectpath(vfs_handle_struct *handle,
1528 struct timespec ts1,ts2;
1531 clock_gettime_mono(&ts1);
1532 result = SMB_VFS_NEXT_CONNECTPATH(handle, fname);
1533 clock_gettime_mono(&ts2);
1534 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1536 if (timediff > audit_timeout) {
1537 smb_time_audit_log_fname("connectpath", timediff, fname);
1543 static NTSTATUS smb_time_audit_brl_lock_windows(struct vfs_handle_struct *handle,
1544 struct byte_range_lock *br_lck,
1545 struct lock_struct *plock,
1547 struct blocking_lock_record *blr)
1550 struct timespec ts1,ts2;
1553 clock_gettime_mono(&ts1);
1554 result = SMB_VFS_NEXT_BRL_LOCK_WINDOWS(handle, br_lck, plock,
1555 blocking_lock, blr);
1556 clock_gettime_mono(&ts2);
1557 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1559 if (timediff > audit_timeout) {
1560 smb_time_audit_log_fsp("brl_lock_windows", timediff,
1567 static bool smb_time_audit_brl_unlock_windows(struct vfs_handle_struct *handle,
1568 struct messaging_context *msg_ctx,
1569 struct byte_range_lock *br_lck,
1570 const struct lock_struct *plock)
1573 struct timespec ts1,ts2;
1576 clock_gettime_mono(&ts1);
1577 result = SMB_VFS_NEXT_BRL_UNLOCK_WINDOWS(handle, msg_ctx, br_lck,
1579 clock_gettime_mono(&ts2);
1580 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1582 if (timediff > audit_timeout) {
1583 smb_time_audit_log_fsp("brl_unlock_windows", timediff,
1590 static bool smb_time_audit_brl_cancel_windows(struct vfs_handle_struct *handle,
1591 struct byte_range_lock *br_lck,
1592 struct lock_struct *plock,
1593 struct blocking_lock_record *blr)
1596 struct timespec ts1,ts2;
1599 clock_gettime_mono(&ts1);
1600 result = SMB_VFS_NEXT_BRL_CANCEL_WINDOWS(handle, br_lck, plock, blr);
1601 clock_gettime_mono(&ts2);
1602 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1604 if (timediff > audit_timeout) {
1605 smb_time_audit_log_fsp("brl_cancel_windows", timediff,
1612 static bool smb_time_audit_strict_lock(struct vfs_handle_struct *handle,
1613 struct files_struct *fsp,
1614 struct lock_struct *plock)
1617 struct timespec ts1,ts2;
1620 clock_gettime_mono(&ts1);
1621 result = SMB_VFS_NEXT_STRICT_LOCK(handle, fsp, plock);
1622 clock_gettime_mono(&ts2);
1623 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1625 if (timediff > audit_timeout) {
1626 smb_time_audit_log_fsp("strict_lock", timediff, fsp);
1632 static void smb_time_audit_strict_unlock(struct vfs_handle_struct *handle,
1633 struct files_struct *fsp,
1634 struct lock_struct *plock)
1636 struct timespec ts1,ts2;
1639 clock_gettime_mono(&ts1);
1640 SMB_VFS_NEXT_STRICT_UNLOCK(handle, fsp, plock);
1641 clock_gettime_mono(&ts2);
1642 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1644 if (timediff > audit_timeout) {
1645 smb_time_audit_log_fsp("strict_unlock", timediff, fsp);
1649 static NTSTATUS smb_time_audit_translate_name(struct vfs_handle_struct *handle,
1651 enum vfs_translate_direction direction,
1652 TALLOC_CTX *mem_ctx,
1656 struct timespec ts1,ts2;
1659 clock_gettime_mono(&ts1);
1660 result = SMB_VFS_NEXT_TRANSLATE_NAME(handle, name, direction, mem_ctx,
1662 clock_gettime_mono(&ts2);
1663 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1665 if (timediff > audit_timeout) {
1666 smb_time_audit_log_fname("translate_name", timediff, name);
1672 struct time_audit_cc_state {
1673 struct timespec ts_send;
1674 struct vfs_handle_struct *handle;
1677 static void smb_time_audit_copy_chunk_done(struct tevent_req *subreq);
1679 static struct tevent_req *smb_time_audit_copy_chunk_send(struct vfs_handle_struct *handle,
1680 TALLOC_CTX *mem_ctx,
1681 struct tevent_context *ev,
1682 struct files_struct *src_fsp,
1684 struct files_struct *dest_fsp,
1688 struct tevent_req *req;
1689 struct tevent_req *subreq;
1690 struct time_audit_cc_state *cc_state;
1692 req = tevent_req_create(mem_ctx, &cc_state, struct time_audit_cc_state);
1697 cc_state->handle = handle;
1698 clock_gettime_mono(&cc_state->ts_send);
1699 subreq = SMB_VFS_NEXT_COPY_CHUNK_SEND(handle, cc_state, ev,
1701 dest_fsp, dest_off, num);
1702 if (tevent_req_nomem(subreq, req)) {
1703 return tevent_req_post(req, ev);
1706 tevent_req_set_callback(subreq, smb_time_audit_copy_chunk_done, req);
1710 static void smb_time_audit_copy_chunk_done(struct tevent_req *subreq)
1712 struct tevent_req *req = tevent_req_callback_data(
1713 subreq, struct tevent_req);
1714 struct time_audit_cc_state *cc_state
1715 = tevent_req_data(req, struct time_audit_cc_state);
1718 status = SMB_VFS_NEXT_COPY_CHUNK_RECV(cc_state->handle,
1721 TALLOC_FREE(subreq);
1722 if (tevent_req_nterror(req, status)) {
1725 tevent_req_done(req);
1728 static NTSTATUS smb_time_audit_copy_chunk_recv(struct vfs_handle_struct *handle,
1729 struct tevent_req *req,
1732 struct time_audit_cc_state *cc_state
1733 = tevent_req_data(req, struct time_audit_cc_state);
1734 struct timespec ts_recv;
1738 clock_gettime_mono(&ts_recv);
1739 timediff = nsec_time_diff(&ts_recv, &cc_state->ts_send)*1.0e-9;
1740 if (timediff > audit_timeout) {
1741 smb_time_audit_log("copy_chunk", timediff);
1744 *copied = cc_state->copied;
1745 if (tevent_req_is_nterror(req, &status)) {
1746 tevent_req_received(req);
1750 tevent_req_received(req);
1751 return NT_STATUS_OK;
1754 static NTSTATUS smb_time_audit_fget_nt_acl(vfs_handle_struct *handle,
1756 uint32 security_info,
1757 TALLOC_CTX *mem_ctx,
1758 struct security_descriptor **ppdesc)
1761 struct timespec ts1,ts2;
1764 clock_gettime_mono(&ts1);
1765 result = SMB_VFS_NEXT_FGET_NT_ACL(handle, fsp, security_info,
1767 clock_gettime_mono(&ts2);
1768 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1770 if (timediff > audit_timeout) {
1771 smb_time_audit_log_fsp("fget_nt_acl", timediff, fsp);
1777 static NTSTATUS smb_time_audit_get_nt_acl(vfs_handle_struct *handle,
1779 uint32 security_info,
1780 TALLOC_CTX *mem_ctx,
1781 struct security_descriptor **ppdesc)
1784 struct timespec ts1,ts2;
1787 clock_gettime_mono(&ts1);
1788 result = SMB_VFS_NEXT_GET_NT_ACL(handle, name, security_info,
1790 clock_gettime_mono(&ts2);
1791 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1793 if (timediff > audit_timeout) {
1794 smb_time_audit_log_fname("get_nt_acl", timediff, name);
1800 static NTSTATUS smb_time_audit_fset_nt_acl(vfs_handle_struct *handle,
1802 uint32 security_info_sent,
1803 const struct security_descriptor *psd)
1806 struct timespec ts1,ts2;
1809 clock_gettime_mono(&ts1);
1810 result = SMB_VFS_NEXT_FSET_NT_ACL(handle, fsp, security_info_sent,
1812 clock_gettime_mono(&ts2);
1813 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1815 if (timediff > audit_timeout) {
1816 smb_time_audit_log_fsp("fset_nt_acl", timediff, fsp);
1822 static int smb_time_audit_chmod_acl(vfs_handle_struct *handle,
1823 const char *path, mode_t mode)
1826 struct timespec ts1,ts2;
1829 clock_gettime_mono(&ts1);
1830 result = SMB_VFS_NEXT_CHMOD_ACL(handle, path, mode);
1831 clock_gettime_mono(&ts2);
1832 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1834 if (timediff > audit_timeout) {
1835 smb_time_audit_log_fname("chmod_acl", timediff, path);
1841 static int smb_time_audit_fchmod_acl(vfs_handle_struct *handle,
1842 files_struct *fsp, mode_t mode)
1845 struct timespec ts1,ts2;
1848 clock_gettime_mono(&ts1);
1849 result = SMB_VFS_NEXT_FCHMOD_ACL(handle, fsp, mode);
1850 clock_gettime_mono(&ts2);
1851 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1853 if (timediff > audit_timeout) {
1854 smb_time_audit_log_fsp("fchmod_acl", timediff, fsp);
1860 static SMB_ACL_T smb_time_audit_sys_acl_get_file(vfs_handle_struct *handle,
1862 SMB_ACL_TYPE_T type,
1863 TALLOC_CTX *mem_ctx)
1866 struct timespec ts1,ts2;
1869 clock_gettime_mono(&ts1);
1870 result = SMB_VFS_NEXT_SYS_ACL_GET_FILE(handle, path_p, type, mem_ctx);
1871 clock_gettime_mono(&ts2);
1872 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1874 if (timediff > audit_timeout) {
1875 smb_time_audit_log_fname("sys_acl_get_file", timediff, path_p);
1881 static SMB_ACL_T smb_time_audit_sys_acl_get_fd(vfs_handle_struct *handle,
1883 TALLOC_CTX *mem_ctx)
1886 struct timespec ts1,ts2;
1889 clock_gettime_mono(&ts1);
1890 result = SMB_VFS_NEXT_SYS_ACL_GET_FD(handle, fsp, mem_ctx);
1891 clock_gettime_mono(&ts2);
1892 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1894 if (timediff > audit_timeout) {
1895 smb_time_audit_log_fsp("sys_acl_get_fd", timediff, fsp);
1902 static int smb_time_audit_sys_acl_blob_get_file(vfs_handle_struct *handle,
1904 TALLOC_CTX *mem_ctx,
1905 char **blob_description,
1909 struct timespec ts1,ts2;
1912 clock_gettime_mono(&ts1);
1913 result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FILE(handle, path_p, mem_ctx, blob_description, blob);
1914 clock_gettime_mono(&ts2);
1915 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1917 if (timediff > audit_timeout) {
1918 smb_time_audit_log("sys_acl_blob_get_file", timediff);
1924 static int smb_time_audit_sys_acl_blob_get_fd(vfs_handle_struct *handle,
1926 TALLOC_CTX *mem_ctx,
1927 char **blob_description,
1931 struct timespec ts1,ts2;
1934 clock_gettime_mono(&ts1);
1935 result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FD(handle, fsp, mem_ctx, blob_description, blob);
1936 clock_gettime_mono(&ts2);
1937 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1939 if (timediff > audit_timeout) {
1940 smb_time_audit_log("sys_acl_blob_get_fd", timediff);
1946 static int smb_time_audit_sys_acl_set_file(vfs_handle_struct *handle,
1948 SMB_ACL_TYPE_T acltype,
1952 struct timespec ts1,ts2;
1955 clock_gettime_mono(&ts1);
1956 result = SMB_VFS_NEXT_SYS_ACL_SET_FILE(handle, name, acltype,
1958 clock_gettime_mono(&ts2);
1959 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1961 if (timediff > audit_timeout) {
1962 smb_time_audit_log_fname("sys_acl_set_file", timediff, name);
1968 static int smb_time_audit_sys_acl_set_fd(vfs_handle_struct *handle,
1973 struct timespec ts1,ts2;
1976 clock_gettime_mono(&ts1);
1977 result = SMB_VFS_NEXT_SYS_ACL_SET_FD(handle, fsp, theacl);
1978 clock_gettime_mono(&ts2);
1979 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1981 if (timediff > audit_timeout) {
1982 smb_time_audit_log_fsp("sys_acl_set_fd", timediff, fsp);
1988 static int smb_time_audit_sys_acl_delete_def_file(vfs_handle_struct *handle,
1992 struct timespec ts1,ts2;
1995 clock_gettime_mono(&ts1);
1996 result = SMB_VFS_NEXT_SYS_ACL_DELETE_DEF_FILE(handle, path);
1997 clock_gettime_mono(&ts2);
1998 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2000 if (timediff > audit_timeout) {
2001 smb_time_audit_log_fname("sys_acl_delete_def_file", timediff, path);
2007 static ssize_t smb_time_audit_getxattr(struct vfs_handle_struct *handle,
2008 const char *path, const char *name,
2009 void *value, size_t size)
2012 struct timespec ts1,ts2;
2015 clock_gettime_mono(&ts1);
2016 result = SMB_VFS_NEXT_GETXATTR(handle, path, name, value, size);
2017 clock_gettime_mono(&ts2);
2018 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2020 if (timediff > audit_timeout) {
2021 smb_time_audit_log_fname("getxattr", timediff, path);
2027 static ssize_t smb_time_audit_fgetxattr(struct vfs_handle_struct *handle,
2028 struct files_struct *fsp,
2029 const char *name, void *value,
2033 struct timespec ts1,ts2;
2036 clock_gettime_mono(&ts1);
2037 result = SMB_VFS_NEXT_FGETXATTR(handle, fsp, name, value, size);
2038 clock_gettime_mono(&ts2);
2039 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2041 if (timediff > audit_timeout) {
2042 smb_time_audit_log_fsp("fgetxattr", timediff, fsp);
2048 static ssize_t smb_time_audit_listxattr(struct vfs_handle_struct *handle,
2049 const char *path, char *list,
2053 struct timespec ts1,ts2;
2056 clock_gettime_mono(&ts1);
2057 result = SMB_VFS_NEXT_LISTXATTR(handle, path, list, size);
2058 clock_gettime_mono(&ts2);
2059 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2061 if (timediff > audit_timeout) {
2062 smb_time_audit_log_fname("listxattr", timediff, path);
2068 static ssize_t smb_time_audit_flistxattr(struct vfs_handle_struct *handle,
2069 struct files_struct *fsp, char *list,
2073 struct timespec ts1,ts2;
2076 clock_gettime_mono(&ts1);
2077 result = SMB_VFS_NEXT_FLISTXATTR(handle, fsp, list, size);
2078 clock_gettime_mono(&ts2);
2079 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2081 if (timediff > audit_timeout) {
2082 smb_time_audit_log_fsp("flistxattr", timediff, fsp);
2088 static int smb_time_audit_removexattr(struct vfs_handle_struct *handle,
2089 const char *path, const char *name)
2092 struct timespec ts1,ts2;
2095 clock_gettime_mono(&ts1);
2096 result = SMB_VFS_NEXT_REMOVEXATTR(handle, path, name);
2097 clock_gettime_mono(&ts2);
2098 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2100 if (timediff > audit_timeout) {
2101 smb_time_audit_log_fname("removexattr", timediff, path);
2107 static int smb_time_audit_fremovexattr(struct vfs_handle_struct *handle,
2108 struct files_struct *fsp,
2112 struct timespec ts1,ts2;
2115 clock_gettime_mono(&ts1);
2116 result = SMB_VFS_NEXT_FREMOVEXATTR(handle, fsp, name);
2117 clock_gettime_mono(&ts2);
2118 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2120 if (timediff > audit_timeout) {
2121 smb_time_audit_log_fsp("fremovexattr", timediff, fsp);
2127 static int smb_time_audit_setxattr(struct vfs_handle_struct *handle,
2128 const char *path, const char *name,
2129 const void *value, size_t size,
2133 struct timespec ts1,ts2;
2136 clock_gettime_mono(&ts1);
2137 result = SMB_VFS_NEXT_SETXATTR(handle, path, name, value, size,
2139 clock_gettime_mono(&ts2);
2140 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2142 if (timediff > audit_timeout) {
2143 smb_time_audit_log_fname("setxattr", timediff, path);
2149 static int smb_time_audit_fsetxattr(struct vfs_handle_struct *handle,
2150 struct files_struct *fsp, const char *name,
2151 const void *value, size_t size, int flags)
2154 struct timespec ts1,ts2;
2157 clock_gettime_mono(&ts1);
2158 result = SMB_VFS_NEXT_FSETXATTR(handle, fsp, name, value, size, flags);
2159 clock_gettime_mono(&ts2);
2160 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2162 if (timediff > audit_timeout) {
2163 smb_time_audit_log_fsp("fsetxattr", timediff, fsp);
2169 static bool smb_time_audit_aio_force(struct vfs_handle_struct *handle,
2170 struct files_struct *fsp)
2173 struct timespec ts1,ts2;
2176 clock_gettime_mono(&ts1);
2177 result = SMB_VFS_NEXT_AIO_FORCE(handle, fsp);
2178 clock_gettime_mono(&ts2);
2179 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2181 if (timediff > audit_timeout) {
2182 smb_time_audit_log_fsp("aio_force", timediff, fsp);
2188 static bool smb_time_audit_is_offline(struct vfs_handle_struct *handle,
2189 const struct smb_filename *fname,
2190 SMB_STRUCT_STAT *sbuf)
2193 struct timespec ts1,ts2;
2196 clock_gettime_mono(&ts1);
2197 result = SMB_VFS_NEXT_IS_OFFLINE(handle, fname, sbuf);
2198 clock_gettime_mono(&ts2);
2199 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2201 if (timediff > audit_timeout) {
2202 smb_time_audit_log_smb_fname("is_offline", timediff, fname);
2208 static int smb_time_audit_set_offline(struct vfs_handle_struct *handle,
2209 const struct smb_filename *fname)
2212 struct timespec ts1,ts2;
2215 clock_gettime_mono(&ts1);
2216 result = SMB_VFS_NEXT_SET_OFFLINE(handle, fname);
2217 clock_gettime_mono(&ts2);
2218 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2220 if (timediff > audit_timeout) {
2221 smb_time_audit_log_smb_fname("set_offline", timediff, fname);
2227 NTSTATUS smb_time_audit_durable_cookie(struct vfs_handle_struct *handle,
2228 struct files_struct *fsp,
2229 TALLOC_CTX *mem_ctx,
2233 struct timespec ts1,ts2;
2236 clock_gettime_mono(&ts1);
2237 result = SMB_VFS_NEXT_DURABLE_COOKIE(handle, fsp, mem_ctx, cookie);
2238 clock_gettime_mono(&ts2);
2239 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2241 if (timediff > audit_timeout) {
2242 smb_time_audit_log_fsp("durable_cookie", timediff, fsp);
2248 NTSTATUS smb_time_audit_durable_disconnect(struct vfs_handle_struct *handle,
2249 struct files_struct *fsp,
2250 const DATA_BLOB old_cookie,
2251 TALLOC_CTX *mem_ctx,
2252 DATA_BLOB *new_cookie)
2255 struct timespec ts1,ts2;
2258 clock_gettime_mono(&ts1);
2259 result = SMB_VFS_NEXT_DURABLE_DISCONNECT(handle, fsp, old_cookie,
2260 mem_ctx, new_cookie);
2261 clock_gettime_mono(&ts2);
2262 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2264 if (timediff > audit_timeout) {
2265 smb_time_audit_log_fsp("durable_disconnect", timediff, fsp);
2271 NTSTATUS smb_time_audit_durable_reconnect(struct vfs_handle_struct *handle,
2272 struct smb_request *smb1req,
2273 struct smbXsrv_open *op,
2274 const DATA_BLOB old_cookie,
2275 TALLOC_CTX *mem_ctx,
2276 struct files_struct **fsp,
2277 DATA_BLOB *new_cookie)
2280 struct timespec ts1,ts2;
2283 clock_gettime_mono(&ts1);
2284 result = SMB_VFS_NEXT_DURABLE_RECONNECT(handle, smb1req, op, old_cookie,
2285 mem_ctx, fsp, new_cookie);
2286 clock_gettime_mono(&ts2);
2287 timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2289 if (timediff > audit_timeout) {
2290 smb_time_audit_log("durable_reconnect", timediff);
2296 /* VFS operations */
2298 static struct vfs_fn_pointers vfs_time_audit_fns = {
2299 .connect_fn = smb_time_audit_connect,
2300 .disconnect_fn = smb_time_audit_disconnect,
2301 .disk_free_fn = smb_time_audit_disk_free,
2302 .get_quota_fn = smb_time_audit_get_quota,
2303 .set_quota_fn = smb_time_audit_set_quota,
2304 .get_shadow_copy_data_fn = smb_time_audit_get_shadow_copy_data,
2305 .statvfs_fn = smb_time_audit_statvfs,
2306 .fs_capabilities_fn = smb_time_audit_fs_capabilities,
2307 .opendir_fn = smb_time_audit_opendir,
2308 .fdopendir_fn = smb_time_audit_fdopendir,
2309 .readdir_fn = smb_time_audit_readdir,
2310 .seekdir_fn = smb_time_audit_seekdir,
2311 .telldir_fn = smb_time_audit_telldir,
2312 .rewind_dir_fn = smb_time_audit_rewinddir,
2313 .mkdir_fn = smb_time_audit_mkdir,
2314 .rmdir_fn = smb_time_audit_rmdir,
2315 .closedir_fn = smb_time_audit_closedir,
2316 .init_search_op_fn = smb_time_audit_init_search_op,
2317 .open_fn = smb_time_audit_open,
2318 .create_file_fn = smb_time_audit_create_file,
2319 .close_fn = smb_time_audit_close,
2320 .read_fn = smb_time_audit_read,
2321 .pread_fn = smb_time_audit_pread,
2322 .pread_send_fn = smb_time_audit_pread_send,
2323 .pread_recv_fn = smb_time_audit_pread_recv,
2324 .write_fn = smb_time_audit_write,
2325 .pwrite_fn = smb_time_audit_pwrite,
2326 .pwrite_send_fn = smb_time_audit_pwrite_send,
2327 .pwrite_recv_fn = smb_time_audit_pwrite_recv,
2328 .lseek_fn = smb_time_audit_lseek,
2329 .sendfile_fn = smb_time_audit_sendfile,
2330 .recvfile_fn = smb_time_audit_recvfile,
2331 .rename_fn = smb_time_audit_rename,
2332 .fsync_fn = smb_time_audit_fsync,
2333 .fsync_send_fn = smb_time_audit_fsync_send,
2334 .fsync_recv_fn = smb_time_audit_fsync_recv,
2335 .stat_fn = smb_time_audit_stat,
2336 .fstat_fn = smb_time_audit_fstat,
2337 .lstat_fn = smb_time_audit_lstat,
2338 .get_alloc_size_fn = smb_time_audit_get_alloc_size,
2339 .unlink_fn = smb_time_audit_unlink,
2340 .chmod_fn = smb_time_audit_chmod,
2341 .fchmod_fn = smb_time_audit_fchmod,
2342 .chown_fn = smb_time_audit_chown,
2343 .fchown_fn = smb_time_audit_fchown,
2344 .lchown_fn = smb_time_audit_lchown,
2345 .chdir_fn = smb_time_audit_chdir,
2346 .getwd_fn = smb_time_audit_getwd,
2347 .ntimes_fn = smb_time_audit_ntimes,
2348 .ftruncate_fn = smb_time_audit_ftruncate,
2349 .fallocate_fn = smb_time_audit_fallocate,
2350 .lock_fn = smb_time_audit_lock,
2351 .kernel_flock_fn = smb_time_audit_kernel_flock,
2352 .linux_setlease_fn = smb_time_audit_linux_setlease,
2353 .getlock_fn = smb_time_audit_getlock,
2354 .symlink_fn = smb_time_audit_symlink,
2355 .readlink_fn = smb_time_audit_readlink,
2356 .link_fn = smb_time_audit_link,
2357 .mknod_fn = smb_time_audit_mknod,
2358 .realpath_fn = smb_time_audit_realpath,
2359 .notify_watch_fn = smb_time_audit_notify_watch,
2360 .chflags_fn = smb_time_audit_chflags,
2361 .file_id_create_fn = smb_time_audit_file_id_create,
2362 .streaminfo_fn = smb_time_audit_streaminfo,
2363 .get_real_filename_fn = smb_time_audit_get_real_filename,
2364 .connectpath_fn = smb_time_audit_connectpath,
2365 .brl_lock_windows_fn = smb_time_audit_brl_lock_windows,
2366 .brl_unlock_windows_fn = smb_time_audit_brl_unlock_windows,
2367 .brl_cancel_windows_fn = smb_time_audit_brl_cancel_windows,
2368 .strict_lock_fn = smb_time_audit_strict_lock,
2369 .strict_unlock_fn = smb_time_audit_strict_unlock,
2370 .translate_name_fn = smb_time_audit_translate_name,
2371 .copy_chunk_send_fn = smb_time_audit_copy_chunk_send,
2372 .copy_chunk_recv_fn = smb_time_audit_copy_chunk_recv,
2373 .fget_nt_acl_fn = smb_time_audit_fget_nt_acl,
2374 .get_nt_acl_fn = smb_time_audit_get_nt_acl,
2375 .fset_nt_acl_fn = smb_time_audit_fset_nt_acl,
2376 .chmod_acl_fn = smb_time_audit_chmod_acl,
2377 .fchmod_acl_fn = smb_time_audit_fchmod_acl,
2378 .sys_acl_get_file_fn = smb_time_audit_sys_acl_get_file,
2379 .sys_acl_get_fd_fn = smb_time_audit_sys_acl_get_fd,
2380 .sys_acl_blob_get_file_fn = smb_time_audit_sys_acl_blob_get_file,
2381 .sys_acl_blob_get_fd_fn = smb_time_audit_sys_acl_blob_get_fd,
2382 .sys_acl_set_file_fn = smb_time_audit_sys_acl_set_file,
2383 .sys_acl_set_fd_fn = smb_time_audit_sys_acl_set_fd,
2384 .sys_acl_delete_def_file_fn = smb_time_audit_sys_acl_delete_def_file,
2385 .getxattr_fn = smb_time_audit_getxattr,
2386 .fgetxattr_fn = smb_time_audit_fgetxattr,
2387 .listxattr_fn = smb_time_audit_listxattr,
2388 .flistxattr_fn = smb_time_audit_flistxattr,
2389 .removexattr_fn = smb_time_audit_removexattr,
2390 .fremovexattr_fn = smb_time_audit_fremovexattr,
2391 .setxattr_fn = smb_time_audit_setxattr,
2392 .fsetxattr_fn = smb_time_audit_fsetxattr,
2393 .aio_force_fn = smb_time_audit_aio_force,
2394 .is_offline_fn = smb_time_audit_is_offline,
2395 .set_offline_fn = smb_time_audit_set_offline,
2396 .durable_cookie_fn = smb_time_audit_durable_cookie,
2397 .durable_disconnect_fn = smb_time_audit_durable_disconnect,
2398 .durable_reconnect_fn = smb_time_audit_durable_reconnect,
2402 NTSTATUS vfs_time_audit_init(void);
2403 NTSTATUS vfs_time_audit_init(void)
2405 audit_timeout = (double)lp_parm_int(-1, "time_audit", "timeout",
2407 return smb_register_vfs(SMB_VFS_INTERFACE_VERSION, "time_audit",
2408 &vfs_time_audit_fns);