source3/modules/vfs_time_audit.c

   1 /*
   2  * Time auditing VFS module for samba.  Log time taken for VFS call to syslog
   3  * facility.
   4  *
   5  * Copyright (C) Abhidnya Chirmule <achirmul@in.ibm.com> 2009
   6  *
   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.
  11  *
  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.
  16  *
  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/>.
  19  */
  20
  21 /*
  22  * This module implements logging for time taken for all Samba VFS operations.
  23  *
  24  * vfs objects = time_audit
  25  */
  26
  27
  28 #include "includes.h"
  29 #include "smbd/smbd.h"
  30 #include "ntioctl.h"
  31 #include "lib/util/tevent_unix.h"
  32
  33 #undef DBGC_CLASS
  34 #define DBGC_CLASS DBGC_VFS
  35
  36 static double audit_timeout;
  37
  38 static void smb_time_audit_log_msg(const char *syscallname, double elapsed,
  39                                     const char *msg)
  40 {
  41         DEBUG(0, ("WARNING: VFS call \"%s\" took unexpectedly long "
  42                   "(%.2f seconds) %s%s-- Validate that file and storage "
  43                   "subsystems are operating normally\n", syscallname,
  44                   elapsed, (msg != NULL) ? msg : "",
  45                   (msg != NULL) ? " " : ""));
  46 }
  47
  48 static void smb_time_audit_log(const char *syscallname, double elapsed)
  49 {
  50         smb_time_audit_log_msg(syscallname, elapsed, NULL);
  51 }
  52
  53 static void smb_time_audit_log_fsp(const char *syscallname, double elapsed,
  54                                    const struct files_struct *fsp)
  55 {
  56         char *base_name = NULL;
  57         char *connectpath = NULL;
  58         char *msg = NULL;
  59
  60         if (fsp == NULL) {
  61                 smb_time_audit_log(syscallname, elapsed);
  62                 return;
  63         }
  64         if (fsp->conn)
  65                 connectpath = fsp->conn->connectpath;
  66         if (fsp->fsp_name)
  67                 base_name = fsp->fsp_name->base_name;
  68
  69         if (connectpath != NULL && base_name != NULL) {
  70                 msg = talloc_asprintf(talloc_tos(), "filename = \"%s/%s\"",
  71                                       connectpath, base_name);
  72         } else if (connectpath != NULL && base_name == NULL) {
  73                 msg = talloc_asprintf(talloc_tos(), "connectpath = \"%s\", "
  74                                       "base_name = <NULL>",
  75                                       connectpath);
  76         } else if (connectpath == NULL && base_name != NULL) {
  77                 msg = talloc_asprintf(talloc_tos(), "connectpath = <NULL>, "
  78                                       "base_name = \"%s\"",
  79                                       base_name);
  80         } else { /* connectpath == NULL && base_name == NULL */
  81                 msg = talloc_asprintf(talloc_tos(), "connectpath = <NULL>, "
  82                                       "base_name = <NULL>");
  83         }
  84         smb_time_audit_log_msg(syscallname, elapsed, msg);
  85         TALLOC_FREE(msg);
  86 }
  87
  88 static void smb_time_audit_log_fname(const char *syscallname, double elapsed,
  89                                     const char *fname)
  90 {
  91         char cwd[PATH_MAX];
  92         char *msg = NULL;
  93
  94         if (getcwd(cwd, sizeof(cwd)) == NULL) {
  95                 snprintf(cwd, sizeof(cwd), "<getcwd() error %d>", errno);
  96         }
  97         if (fname != NULL) {
  98                 msg = talloc_asprintf(talloc_tos(),
  99                                       "cwd = \"%s\", filename = \"%s\"",
 100                                       cwd, fname);
 101         } else {
 102                 msg = talloc_asprintf(talloc_tos(),
 103                                       "cwd = \"%s\", filename = <NULL>",
 104                                       cwd);
 105         }
 106         smb_time_audit_log_msg(syscallname, elapsed, msg);
 107         TALLOC_FREE(msg);
 108 }
 109
 110 static void smb_time_audit_log_smb_fname(const char *syscallname, double elapsed,
 111                                        const struct smb_filename *smb_fname)
 112 {
 113         if (smb_fname != NULL) {
 114                 smb_time_audit_log_fname(syscallname, elapsed,
 115                                          smb_fname->base_name);
 116         } else {
 117                 smb_time_audit_log_fname(syscallname, elapsed,
 118                                          "smb_fname = <NULL>");
 119         }
 120 }
 121
 122 static int smb_time_audit_connect(vfs_handle_struct *handle,
 123                                   const char *svc, const char *user)
 124 {
 125         int result;
 126         struct timespec ts1,ts2;
 127         double timediff;
 128
 129         if (!handle) {
 130                 return -1;
 131         }
 132
 133         clock_gettime_mono(&ts1);
 134         result = SMB_VFS_NEXT_CONNECT(handle, svc, user);
 135         clock_gettime_mono(&ts2);
 136         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 137         if (timediff > audit_timeout) {
 138                 smb_time_audit_log_msg("connect", timediff, user);
 139         }
 140         return result;
 141 }
 142
 143 static void smb_time_audit_disconnect(vfs_handle_struct *handle)
 144 {
 145         struct timespec ts1,ts2;
 146         double timediff;
 147
 148         clock_gettime_mono(&ts1);
 149         SMB_VFS_NEXT_DISCONNECT(handle);
 150         clock_gettime_mono(&ts2);
 151         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 152
 153         if (timediff > audit_timeout) {
 154                 smb_time_audit_log("disconnect", timediff);
 155         }
 156 }
 157
 158 static uint64_t smb_time_audit_disk_free(vfs_handle_struct *handle,
 159                                          const char *path,
 160                                          bool small_query, uint64_t *bsize,
 161                                          uint64_t *dfree, uint64_t *dsize)
 162 {
 163         uint64_t result;
 164         struct timespec ts1,ts2;
 165         double timediff;
 166
 167         clock_gettime_mono(&ts1);
 168         result = SMB_VFS_NEXT_DISK_FREE(handle, path, small_query, bsize,
 169                                         dfree, dsize);
 170         clock_gettime_mono(&ts2);
 171         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 172
 173         /* Don't have a reasonable notion of failure here */
 174         if (timediff > audit_timeout) {
 175                 smb_time_audit_log_fname("disk_free", timediff, path);
 176         }
 177
 178         return result;
 179 }
 180
 181 static int smb_time_audit_get_quota(struct vfs_handle_struct *handle,
 182                                     enum SMB_QUOTA_TYPE qtype, unid_t id,
 183                                     SMB_DISK_QUOTA *qt)
 184 {
 185         int result;
 186         struct timespec ts1,ts2;
 187         double timediff;
 188
 189         clock_gettime_mono(&ts1);
 190         result = SMB_VFS_NEXT_GET_QUOTA(handle, qtype, id, qt);
 191         clock_gettime_mono(&ts2);
 192         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 193
 194         if (timediff > audit_timeout) {
 195                 smb_time_audit_log("get_quota", timediff);
 196         }
 197         return result;
 198 }
 199
 200 static int smb_time_audit_set_quota(struct vfs_handle_struct *handle,
 201                                     enum SMB_QUOTA_TYPE qtype, unid_t id,
 202                                     SMB_DISK_QUOTA *qt)
 203 {
 204         int result;
 205         struct timespec ts1,ts2;
 206         double timediff;
 207
 208         clock_gettime_mono(&ts1);
 209         result = SMB_VFS_NEXT_SET_QUOTA(handle, qtype, id, qt);
 210         clock_gettime_mono(&ts2);
 211         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 212
 213         if (timediff > audit_timeout) {
 214                 smb_time_audit_log("set_quota", timediff);
 215         }
 216
 217         return result;
 218 }
 219
 220 static int smb_time_audit_get_shadow_copy_data(struct vfs_handle_struct *handle,
 221                                                struct files_struct *fsp,
 222                                                struct shadow_copy_data *shadow_copy_data,
 223                                                bool labels)
 224 {
 225         int result;
 226         struct timespec ts1,ts2;
 227         double timediff;
 228
 229         clock_gettime_mono(&ts1);
 230         result = SMB_VFS_NEXT_GET_SHADOW_COPY_DATA(handle, fsp,
 231                                                    shadow_copy_data, labels);
 232         clock_gettime_mono(&ts2);
 233         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 234
 235         if (timediff > audit_timeout) {
 236                 smb_time_audit_log_fsp("get_shadow_copy_data", timediff, fsp);
 237         }
 238
 239         return result;
 240 }
 241
 242 static int smb_time_audit_statvfs(struct vfs_handle_struct *handle,
 243                                   const char *path,
 244                                   struct vfs_statvfs_struct *statbuf)
 245 {
 246         int result;
 247         struct timespec ts1,ts2;
 248         double timediff;
 249
 250         clock_gettime_mono(&ts1);
 251         result = SMB_VFS_NEXT_STATVFS(handle, path, statbuf);
 252         clock_gettime_mono(&ts2);
 253         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 254
 255         if (timediff > audit_timeout) {
 256                 smb_time_audit_log_fname("statvfs", timediff, path);
 257         }
 258
 259         return result;
 260 }
 261
 262 static uint32_t smb_time_audit_fs_capabilities(struct vfs_handle_struct *handle,
 263                                                enum timestamp_set_resolution *p_ts_res)
 264 {
 265         uint32_t result;
 266         struct timespec ts1,ts2;
 267         double timediff;
 268
 269         clock_gettime_mono(&ts1);
 270         result = SMB_VFS_NEXT_FS_CAPABILITIES(handle, p_ts_res);
 271         clock_gettime_mono(&ts2);
 272         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 273
 274         if (timediff > audit_timeout) {
 275                 smb_time_audit_log("fs_capabilities", timediff);
 276         }
 277
 278         return result;
 279 }
 280
 281 static DIR *smb_time_audit_opendir(vfs_handle_struct *handle,
 282                                               const char *fname,
 283                                               const char *mask, uint32 attr)
 284 {
 285         DIR *result;
 286         struct timespec ts1,ts2;
 287         double timediff;
 288
 289         clock_gettime_mono(&ts1);
 290         result = SMB_VFS_NEXT_OPENDIR(handle, fname, mask, attr);
 291         clock_gettime_mono(&ts2);
 292         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 293
 294         if (timediff > audit_timeout) {
 295                 smb_time_audit_log_fname("opendir", timediff, fname);
 296         }
 297
 298         return result;
 299 }
 300
 301 static DIR *smb_time_audit_fdopendir(vfs_handle_struct *handle,
 302                                               files_struct *fsp,
 303                                               const char *mask, uint32 attr)
 304 {
 305         DIR *result;
 306         struct timespec ts1,ts2;
 307         double timediff;
 308
 309         clock_gettime_mono(&ts1);
 310         result = SMB_VFS_NEXT_FDOPENDIR(handle, fsp, mask, attr);
 311         clock_gettime_mono(&ts2);
 312         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 313
 314         if (timediff > audit_timeout) {
 315                 smb_time_audit_log_fsp("fdopendir", timediff, fsp);
 316         }
 317
 318         return result;
 319 }
 320
 321 static struct dirent *smb_time_audit_readdir(vfs_handle_struct *handle,
 322                                                  DIR *dirp,
 323                                                  SMB_STRUCT_STAT *sbuf)
 324 {
 325         struct dirent *result;
 326         struct timespec ts1,ts2;
 327         double timediff;
 328
 329         clock_gettime_mono(&ts1);
 330         result = SMB_VFS_NEXT_READDIR(handle, dirp, sbuf);
 331         clock_gettime_mono(&ts2);
 332         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 333
 334         if (timediff > audit_timeout) {
 335                 smb_time_audit_log("readdir", timediff);
 336         }
 337
 338         return result;
 339 }
 340
 341 static void smb_time_audit_seekdir(vfs_handle_struct *handle,
 342                                    DIR *dirp, long offset)
 343 {
 344         struct timespec ts1,ts2;
 345         double timediff;
 346
 347         clock_gettime_mono(&ts1);
 348         SMB_VFS_NEXT_SEEKDIR(handle, dirp, offset);
 349         clock_gettime_mono(&ts2);
 350         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 351
 352         if (timediff > audit_timeout) {
 353                 smb_time_audit_log("seekdir", timediff);
 354         }
 355
 356 }
 357
 358 static long smb_time_audit_telldir(vfs_handle_struct *handle,
 359                                    DIR *dirp)
 360 {
 361         long result;
 362         struct timespec ts1,ts2;
 363         double timediff;
 364
 365         clock_gettime_mono(&ts1);
 366         result = SMB_VFS_NEXT_TELLDIR(handle, dirp);
 367         clock_gettime_mono(&ts2);
 368         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 369
 370         if (timediff > audit_timeout) {
 371                 smb_time_audit_log("telldir", timediff);
 372         }
 373
 374         return result;
 375 }
 376
 377 static void smb_time_audit_rewinddir(vfs_handle_struct *handle,
 378                                      DIR *dirp)
 379 {
 380         struct timespec ts1,ts2;
 381         double timediff;
 382
 383         clock_gettime_mono(&ts1);
 384         SMB_VFS_NEXT_REWINDDIR(handle, dirp);
 385         clock_gettime_mono(&ts2);
 386         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 387
 388         if (timediff > audit_timeout) {
 389                 smb_time_audit_log("rewinddir", timediff);
 390         }
 391
 392 }
 393
 394 static int smb_time_audit_mkdir(vfs_handle_struct *handle,
 395                                 const char *path, mode_t mode)
 396 {
 397         int result;
 398         struct timespec ts1,ts2;
 399         double timediff;
 400
 401         clock_gettime_mono(&ts1);
 402         result = SMB_VFS_NEXT_MKDIR(handle, path, mode);
 403         clock_gettime_mono(&ts2);
 404         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 405
 406         if (timediff > audit_timeout) {
 407                 smb_time_audit_log_fname("mkdir", timediff, path);
 408         }
 409
 410         return result;
 411 }
 412
 413 static int smb_time_audit_rmdir(vfs_handle_struct *handle,
 414                                 const char *path)
 415 {
 416         int result;
 417         struct timespec ts1,ts2;
 418         double timediff;
 419
 420         clock_gettime_mono(&ts1);
 421         result = SMB_VFS_NEXT_RMDIR(handle, path);
 422         clock_gettime_mono(&ts2);
 423         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 424
 425         if (timediff > audit_timeout) {
 426                 smb_time_audit_log_fname("rmdir", timediff, path);
 427         }
 428
 429         return result;
 430 }
 431
 432 static int smb_time_audit_closedir(vfs_handle_struct *handle,
 433                                    DIR *dirp)
 434 {
 435         int result;
 436         struct timespec ts1,ts2;
 437         double timediff;
 438
 439         clock_gettime_mono(&ts1);
 440         result = SMB_VFS_NEXT_CLOSEDIR(handle, dirp);
 441         clock_gettime_mono(&ts2);
 442         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 443
 444         if (timediff > audit_timeout) {
 445                 smb_time_audit_log("closedir", timediff);
 446         }
 447
 448         return result;
 449 }
 450
 451 static void smb_time_audit_init_search_op(vfs_handle_struct *handle,
 452                                           DIR *dirp)
 453 {
 454         struct timespec ts1,ts2;
 455         double timediff;
 456
 457         clock_gettime_mono(&ts1);
 458         SMB_VFS_NEXT_INIT_SEARCH_OP(handle, dirp);
 459         clock_gettime_mono(&ts2);
 460         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 461
 462         if (timediff > audit_timeout) {
 463                 smb_time_audit_log("init_search_op", timediff);
 464         }
 465 }
 466
 467 static int smb_time_audit_open(vfs_handle_struct *handle,
 468                                struct smb_filename *fname,
 469                                files_struct *fsp,
 470                                int flags, mode_t mode)
 471 {
 472         int result;
 473         struct timespec ts1,ts2;
 474         double timediff;
 475
 476         clock_gettime_mono(&ts1);
 477         result = SMB_VFS_NEXT_OPEN(handle, fname, fsp, flags, mode);
 478         clock_gettime_mono(&ts2);
 479         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 480
 481         if (timediff > audit_timeout) {
 482                 smb_time_audit_log_fsp("open", timediff, fsp);
 483         }
 484
 485         return result;
 486 }
 487
 488 static NTSTATUS smb_time_audit_create_file(vfs_handle_struct *handle,
 489                                            struct smb_request *req,
 490                                            uint16_t root_dir_fid,
 491                                            struct smb_filename *fname,
 492                                            uint32_t access_mask,
 493                                            uint32_t share_access,
 494                                            uint32_t create_disposition,
 495                                            uint32_t create_options,
 496                                            uint32_t file_attributes,
 497                                            uint32_t oplock_request,
 498                                            uint64_t allocation_size,
 499                                            uint32_t private_flags,
 500                                            struct security_descriptor *sd,
 501                                            struct ea_list *ea_list,
 502                                            files_struct **result_fsp,
 503                                            int *pinfo)
 504 {
 505         NTSTATUS result;
 506         struct timespec ts1,ts2;
 507         double timediff;
 508
 509         clock_gettime_mono(&ts1);
 510         result = SMB_VFS_NEXT_CREATE_FILE(
 511                 handle,                                 /* handle */
 512                 req,                                    /* req */
 513                 root_dir_fid,                           /* root_dir_fid */
 514                 fname,                                  /* fname */
 515                 access_mask,                            /* access_mask */
 516                 share_access,                           /* share_access */
 517                 create_disposition,                     /* create_disposition*/
 518                 create_options,                         /* create_options */
 519                 file_attributes,                        /* file_attributes */
 520                 oplock_request,                         /* oplock_request */
 521                 allocation_size,                        /* allocation_size */
 522                 private_flags,
 523                 sd,                                     /* sd */
 524                 ea_list,                                /* ea_list */
 525                 result_fsp,                             /* result */
 526                 pinfo);
 527         clock_gettime_mono(&ts2);
 528         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 529
 530         if (timediff > audit_timeout) {
 531                 /*
 532                  * can't use result_fsp this time, may have
 533                  * invalid content causing smbd crash
 534                  */
 535                 smb_time_audit_log_smb_fname("create_file", timediff,
 536                                            fname);
 537         }
 538
 539         return result;
 540 }
 541
 542 static int smb_time_audit_close(vfs_handle_struct *handle, files_struct *fsp)
 543 {
 544         int result;
 545         struct timespec ts1,ts2;
 546         double timediff;
 547
 548         clock_gettime_mono(&ts1);
 549         result = SMB_VFS_NEXT_CLOSE(handle, fsp);
 550         clock_gettime_mono(&ts2);
 551         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 552
 553         if (timediff > audit_timeout) {
 554                 smb_time_audit_log_fsp("close", timediff, fsp);
 555         }
 556
 557         return result;
 558 }
 559
 560 static ssize_t smb_time_audit_read(vfs_handle_struct *handle,
 561                                    files_struct *fsp, void *data, size_t n)
 562 {
 563         ssize_t result;
 564         struct timespec ts1,ts2;
 565         double timediff;
 566
 567         clock_gettime_mono(&ts1);
 568         result = SMB_VFS_NEXT_READ(handle, fsp, data, n);
 569         clock_gettime_mono(&ts2);
 570         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 571
 572         if (timediff > audit_timeout) {
 573                 smb_time_audit_log_fsp("read", timediff, fsp);
 574         }
 575
 576         return result;
 577 }
 578
 579 static ssize_t smb_time_audit_pread(vfs_handle_struct *handle,
 580                                     files_struct *fsp,
 581                                     void *data, size_t n, off_t offset)
 582 {
 583         ssize_t result;
 584         struct timespec ts1,ts2;
 585         double timediff;
 586
 587         clock_gettime_mono(&ts1);
 588         result = SMB_VFS_NEXT_PREAD(handle, fsp, data, n, offset);
 589         clock_gettime_mono(&ts2);
 590         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 591
 592         if (timediff > audit_timeout) {
 593                 smb_time_audit_log_fsp("pread", timediff, fsp);
 594         }
 595
 596         return result;
 597 }
 598
 599 struct smb_time_audit_pread_state {
 600         struct files_struct *fsp;
 601         struct timespec ts1;
 602         ssize_t ret;
 603         int err;
 604 };
 605
 606 static void smb_time_audit_pread_done(struct tevent_req *subreq);
 607
 608 static struct tevent_req *smb_time_audit_pread_send(
 609         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 610         struct tevent_context *ev, struct files_struct *fsp,
 611         void *data, size_t n, off_t offset)
 612 {
 613         struct tevent_req *req, *subreq;
 614         struct smb_time_audit_pread_state *state;
 615
 616         req = tevent_req_create(mem_ctx, &state,
 617                                 struct smb_time_audit_pread_state);
 618         if (req == NULL) {
 619                 return NULL;
 620         }
 621         clock_gettime_mono(&state->ts1);
 622         state->fsp = fsp;
 623
 624         subreq = SMB_VFS_NEXT_PREAD_SEND(state, ev, handle, fsp, data,
 625                                          n, offset);
 626         if (tevent_req_nomem(subreq, req)) {
 627                 return tevent_req_post(req, ev);
 628         }
 629         tevent_req_set_callback(subreq, smb_time_audit_pread_done, req);
 630         return req;
 631 }
 632
 633 static void smb_time_audit_pread_done(struct tevent_req *subreq)
 634 {
 635         struct tevent_req *req = tevent_req_callback_data(
 636                 subreq, struct tevent_req);
 637         struct smb_time_audit_pread_state *state = tevent_req_data(
 638                 req, struct smb_time_audit_pread_state);
 639
 640         state->ret = SMB_VFS_PREAD_RECV(subreq, &state->err);
 641         TALLOC_FREE(subreq);
 642         tevent_req_done(req);
 643 }
 644
 645 static ssize_t smb_time_audit_pread_recv(struct tevent_req *req, int *err)
 646 {
 647         struct smb_time_audit_pread_state *state = tevent_req_data(
 648                 req, struct smb_time_audit_pread_state);
 649         struct timespec ts2;
 650         double timediff;
 651
 652         clock_gettime_mono(&ts2);
 653         timediff = nsec_time_diff(&ts2,&state->ts1)*1.0e-9;
 654
 655         if (timediff > audit_timeout) {
 656                 smb_time_audit_log_fsp("pread", timediff, state->fsp);
 657         }
 658
 659         if (tevent_req_is_unix_error(req, err)) {
 660                 return -1;
 661         }
 662         *err = state->err;
 663         return state->ret;
 664 }
 665
 666 static ssize_t smb_time_audit_write(vfs_handle_struct *handle,
 667                                     files_struct *fsp,
 668                                     const void *data, size_t n)
 669 {
 670         ssize_t result;
 671         struct timespec ts1,ts2;
 672         double timediff;
 673
 674         clock_gettime_mono(&ts1);
 675         result = SMB_VFS_NEXT_WRITE(handle, fsp, data, n);
 676         clock_gettime_mono(&ts2);
 677         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 678
 679         if (timediff > audit_timeout) {
 680                 smb_time_audit_log_fsp("write", timediff, fsp);
 681         }
 682
 683         return result;
 684 }
 685
 686 static ssize_t smb_time_audit_pwrite(vfs_handle_struct *handle,
 687                                      files_struct *fsp,
 688                                      const void *data, size_t n,
 689                                      off_t offset)
 690 {
 691         ssize_t result;
 692         struct timespec ts1,ts2;
 693         double timediff;
 694
 695         clock_gettime_mono(&ts1);
 696         result = SMB_VFS_NEXT_PWRITE(handle, fsp, data, n, offset);
 697         clock_gettime_mono(&ts2);
 698         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 699
 700         if (timediff > audit_timeout) {
 701                 smb_time_audit_log_fsp("pwrite", timediff, fsp);
 702         }
 703
 704         return result;
 705 }
 706
 707 struct smb_time_audit_pwrite_state {
 708         struct files_struct *fsp;
 709         struct timespec ts1;
 710         ssize_t ret;
 711         int err;
 712 };
 713
 714 static void smb_time_audit_pwrite_done(struct tevent_req *subreq);
 715
 716 static struct tevent_req *smb_time_audit_pwrite_send(
 717         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 718         struct tevent_context *ev, struct files_struct *fsp,
 719         const void *data, size_t n, off_t offset)
 720 {
 721         struct tevent_req *req, *subreq;
 722         struct smb_time_audit_pwrite_state *state;
 723
 724         req = tevent_req_create(mem_ctx, &state,
 725                                 struct smb_time_audit_pwrite_state);
 726         if (req == NULL) {
 727                 return NULL;
 728         }
 729         clock_gettime_mono(&state->ts1);
 730         state->fsp = fsp;
 731
 732         subreq = SMB_VFS_NEXT_PWRITE_SEND(state, ev, handle, fsp, data,
 733                                          n, offset);
 734         if (tevent_req_nomem(subreq, req)) {
 735                 return tevent_req_post(req, ev);
 736         }
 737         tevent_req_set_callback(subreq, smb_time_audit_pwrite_done, req);
 738         return req;
 739 }
 740
 741 static void smb_time_audit_pwrite_done(struct tevent_req *subreq)
 742 {
 743         struct tevent_req *req = tevent_req_callback_data(
 744                 subreq, struct tevent_req);
 745         struct smb_time_audit_pwrite_state *state = tevent_req_data(
 746                 req, struct smb_time_audit_pwrite_state);
 747
 748         state->ret = SMB_VFS_PWRITE_RECV(subreq, &state->err);
 749         TALLOC_FREE(subreq);
 750         tevent_req_done(req);
 751 }
 752
 753 static ssize_t smb_time_audit_pwrite_recv(struct tevent_req *req, int *err)
 754 {
 755         struct smb_time_audit_pwrite_state *state = tevent_req_data(
 756                 req, struct smb_time_audit_pwrite_state);
 757         struct timespec ts2;
 758         double timediff;
 759
 760         clock_gettime_mono(&ts2);
 761         timediff = nsec_time_diff(&ts2,&state->ts1)*1.0e-9;
 762
 763         if (timediff > audit_timeout) {
 764                 smb_time_audit_log_fsp("pwrite", timediff, state->fsp);
 765         }
 766
 767         if (tevent_req_is_unix_error(req, err)) {
 768                 return -1;
 769         }
 770         *err = state->err;
 771         return state->ret;
 772 }
 773
 774 static off_t smb_time_audit_lseek(vfs_handle_struct *handle,
 775                                       files_struct *fsp,
 776                                       off_t offset, int whence)
 777 {
 778         off_t result;
 779         struct timespec ts1,ts2;
 780         double timediff;
 781
 782         clock_gettime_mono(&ts1);
 783         result = SMB_VFS_NEXT_LSEEK(handle, fsp, offset, whence);
 784         clock_gettime_mono(&ts2);
 785         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 786
 787         if (timediff > audit_timeout) {
 788                 smb_time_audit_log_fsp("lseek", timediff, fsp);
 789         }
 790
 791         return result;
 792 }
 793
 794 static ssize_t smb_time_audit_sendfile(vfs_handle_struct *handle, int tofd,
 795                                        files_struct *fromfsp,
 796                                        const DATA_BLOB *hdr, off_t offset,
 797                                        size_t n)
 798 {
 799         ssize_t result;
 800         struct timespec ts1,ts2;
 801         double timediff;
 802
 803         clock_gettime_mono(&ts1);
 804         result = SMB_VFS_NEXT_SENDFILE(handle, tofd, fromfsp, hdr, offset, n);
 805         clock_gettime_mono(&ts2);
 806         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 807
 808         if (timediff > audit_timeout) {
 809                 smb_time_audit_log_fsp("sendfile", timediff, fromfsp);
 810         }
 811
 812         return result;
 813 }
 814
 815 static ssize_t smb_time_audit_recvfile(vfs_handle_struct *handle, int fromfd,
 816                                        files_struct *tofsp,
 817                                        off_t offset,
 818                                        size_t n)
 819 {
 820         ssize_t result;
 821         struct timespec ts1,ts2;
 822         double timediff;
 823
 824         clock_gettime_mono(&ts1);
 825         result = SMB_VFS_NEXT_RECVFILE(handle, fromfd, tofsp, offset, n);
 826         clock_gettime_mono(&ts2);
 827         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 828
 829         if (timediff > audit_timeout) {
 830                 smb_time_audit_log_fsp("recvfile", timediff, tofsp);
 831         }
 832
 833         return result;
 834 }
 835
 836 static int smb_time_audit_rename(vfs_handle_struct *handle,
 837                                  const struct smb_filename *oldname,
 838                                  const struct smb_filename *newname)
 839 {
 840         int result;
 841         struct timespec ts1,ts2;
 842         double timediff;
 843
 844         clock_gettime_mono(&ts1);
 845         result = SMB_VFS_NEXT_RENAME(handle, oldname, newname);
 846         clock_gettime_mono(&ts2);
 847         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 848
 849         if (timediff > audit_timeout) {
 850                 smb_time_audit_log_smb_fname("rename", timediff, newname);
 851         }
 852
 853         return result;
 854 }
 855
 856 static int smb_time_audit_fsync(vfs_handle_struct *handle, files_struct *fsp)
 857 {
 858         int result;
 859         struct timespec ts1,ts2;
 860         double timediff;
 861
 862         clock_gettime_mono(&ts1);
 863         result = SMB_VFS_NEXT_FSYNC(handle, fsp);
 864         clock_gettime_mono(&ts2);
 865         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 866
 867         if (timediff > audit_timeout) {
 868                 smb_time_audit_log_fsp("fsync", timediff, fsp);
 869         }
 870
 871         return result;
 872 }
 873
 874 struct smb_time_audit_fsync_state {
 875         struct files_struct *fsp;
 876         struct timespec ts1;
 877         int ret;
 878         int err;
 879 };
 880
 881 static void smb_time_audit_fsync_done(struct tevent_req *subreq);
 882
 883 static struct tevent_req *smb_time_audit_fsync_send(
 884         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 885         struct tevent_context *ev, struct files_struct *fsp)
 886 {
 887         struct tevent_req *req, *subreq;
 888         struct smb_time_audit_fsync_state *state;
 889
 890         req = tevent_req_create(mem_ctx, &state,
 891                                 struct smb_time_audit_fsync_state);
 892         if (req == NULL) {
 893                 return NULL;
 894         }
 895         clock_gettime_mono(&state->ts1);
 896         state->fsp = fsp;
 897
 898         subreq = SMB_VFS_NEXT_FSYNC_SEND(state, ev, handle, fsp);
 899         if (tevent_req_nomem(subreq, req)) {
 900                 return tevent_req_post(req, ev);
 901         }
 902         tevent_req_set_callback(subreq, smb_time_audit_fsync_done, req);
 903         return req;
 904 }
 905
 906 static void smb_time_audit_fsync_done(struct tevent_req *subreq)
 907 {
 908         struct tevent_req *req = tevent_req_callback_data(
 909                 subreq, struct tevent_req);
 910         struct smb_time_audit_fsync_state *state = tevent_req_data(
 911                 req, struct smb_time_audit_fsync_state);
 912
 913         state->ret = SMB_VFS_FSYNC_RECV(subreq, &state->err);
 914         TALLOC_FREE(subreq);
 915         tevent_req_done(req);
 916 }
 917
 918 static int smb_time_audit_fsync_recv(struct tevent_req *req, int *err)
 919 {
 920         struct smb_time_audit_fsync_state *state = tevent_req_data(
 921                 req, struct smb_time_audit_fsync_state);
 922         struct timespec ts2;
 923         double timediff;
 924
 925         clock_gettime_mono(&ts2);
 926         timediff = nsec_time_diff(&ts2,&state->ts1)*1.0e-9;
 927
 928         if (timediff > audit_timeout) {
 929                 smb_time_audit_log_fsp("fsync", timediff, state->fsp);
 930         }
 931
 932         if (tevent_req_is_unix_error(req, err)) {
 933                 return -1;
 934         }
 935         *err = state->err;
 936         return state->ret;
 937 }
 938
 939 static int smb_time_audit_stat(vfs_handle_struct *handle,
 940                                struct smb_filename *fname)
 941 {
 942         int result;
 943         struct timespec ts1,ts2;
 944         double timediff;
 945
 946         clock_gettime_mono(&ts1);
 947         result = SMB_VFS_NEXT_STAT(handle, fname);
 948         clock_gettime_mono(&ts2);
 949         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 950
 951         if (timediff > audit_timeout) {
 952                 smb_time_audit_log_smb_fname("stat", timediff, fname);
 953         }
 954
 955         return result;
 956 }
 957
 958 static int smb_time_audit_fstat(vfs_handle_struct *handle, files_struct *fsp,
 959                                 SMB_STRUCT_STAT *sbuf)
 960 {
 961         int result;
 962         struct timespec ts1,ts2;
 963         double timediff;
 964
 965         clock_gettime_mono(&ts1);
 966         result = SMB_VFS_NEXT_FSTAT(handle, fsp, sbuf);
 967         clock_gettime_mono(&ts2);
 968         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 969
 970         if (timediff > audit_timeout) {
 971                 smb_time_audit_log_fsp("fstat", timediff, fsp);
 972         }
 973
 974         return result;
 975 }
 976
 977 static int smb_time_audit_lstat(vfs_handle_struct *handle,
 978                                 struct smb_filename *path)
 979 {
 980         int result;
 981         struct timespec ts1,ts2;
 982         double timediff;
 983
 984         clock_gettime_mono(&ts1);
 985         result = SMB_VFS_NEXT_LSTAT(handle, path);
 986         clock_gettime_mono(&ts2);
 987         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 988
 989         if (timediff > audit_timeout) {
 990                 smb_time_audit_log_smb_fname("lstat", timediff, path);
 991         }
 992
 993         return result;
 994 }
 995
 996 static uint64_t smb_time_audit_get_alloc_size(vfs_handle_struct *handle,
 997                                               files_struct *fsp,
 998                                               const SMB_STRUCT_STAT *sbuf)
 999 {
1000         uint64_t result;
1001         struct timespec ts1,ts2;
1002         double timediff;
1003
1004         clock_gettime_mono(&ts1);
1005         result = SMB_VFS_NEXT_GET_ALLOC_SIZE(handle, fsp, sbuf);
1006         clock_gettime_mono(&ts2);
1007         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1008
1009         if (timediff > audit_timeout) {
1010                 smb_time_audit_log_fsp("get_alloc_size", timediff, fsp);
1011         }
1012
1013         return result;
1014 }
1015
1016 static int smb_time_audit_unlink(vfs_handle_struct *handle,
1017                                  const struct smb_filename *path)
1018 {
1019         int result;
1020         struct timespec ts1,ts2;
1021         double timediff;
1022
1023         clock_gettime_mono(&ts1);
1024         result = SMB_VFS_NEXT_UNLINK(handle, path);
1025         clock_gettime_mono(&ts2);
1026         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1027
1028         if (timediff > audit_timeout) {
1029                 smb_time_audit_log_smb_fname("unlink", timediff, path);
1030         }
1031
1032         return result;
1033 }
1034
1035 static int smb_time_audit_chmod(vfs_handle_struct *handle,
1036                                 const char *path, mode_t mode)
1037 {
1038         int result;
1039         struct timespec ts1,ts2;
1040         double timediff;
1041
1042         clock_gettime_mono(&ts1);
1043         result = SMB_VFS_NEXT_CHMOD(handle, path, mode);
1044         clock_gettime_mono(&ts2);
1045         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1046
1047         if (timediff > audit_timeout) {
1048                 smb_time_audit_log_fname("chmod", timediff, path);
1049         }
1050
1051         return result;
1052 }
1053
1054 static int smb_time_audit_fchmod(vfs_handle_struct *handle, files_struct *fsp,
1055                                  mode_t mode)
1056 {
1057         int result;
1058         struct timespec ts1,ts2;
1059         double timediff;
1060
1061         clock_gettime_mono(&ts1);
1062         result = SMB_VFS_NEXT_FCHMOD(handle, fsp, mode);
1063         clock_gettime_mono(&ts2);
1064         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1065
1066         if (timediff > audit_timeout) {
1067                 smb_time_audit_log_fsp("fchmod", timediff, fsp);
1068         }
1069
1070         return result;
1071 }
1072
1073 static int smb_time_audit_chown(vfs_handle_struct *handle,
1074                                 const char *path, uid_t uid, gid_t gid)
1075 {
1076         int result;
1077         struct timespec ts1,ts2;
1078         double timediff;
1079
1080         clock_gettime_mono(&ts1);
1081         result = SMB_VFS_NEXT_CHOWN(handle, path, uid, gid);
1082         clock_gettime_mono(&ts2);
1083         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1084
1085         if (timediff > audit_timeout) {
1086                 smb_time_audit_log_fname("chown", timediff, path);
1087         }
1088
1089         return result;
1090 }
1091
1092 static int smb_time_audit_fchown(vfs_handle_struct *handle, files_struct *fsp,
1093                                  uid_t uid, gid_t gid)
1094 {
1095         int result;
1096         struct timespec ts1,ts2;
1097         double timediff;
1098
1099         clock_gettime_mono(&ts1);
1100         result = SMB_VFS_NEXT_FCHOWN(handle, fsp, uid, gid);
1101         clock_gettime_mono(&ts2);
1102         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1103
1104         if (timediff > audit_timeout) {
1105                 smb_time_audit_log_fsp("fchown", timediff, fsp);
1106         }
1107
1108         return result;
1109 }
1110
1111 static int smb_time_audit_lchown(vfs_handle_struct *handle,
1112                                  const char *path, uid_t uid, gid_t gid)
1113 {
1114         int result;
1115         struct timespec ts1,ts2;
1116         double timediff;
1117
1118         clock_gettime_mono(&ts1);
1119         result = SMB_VFS_NEXT_LCHOWN(handle, path, uid, gid);
1120         clock_gettime_mono(&ts2);
1121         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1122
1123         if (timediff > audit_timeout) {
1124                 smb_time_audit_log_fname("lchown", timediff, path);
1125         }
1126
1127         return result;
1128 }
1129
1130 static int smb_time_audit_chdir(vfs_handle_struct *handle, const char *path)
1131 {
1132         int result;
1133         struct timespec ts1,ts2;
1134         double timediff;
1135
1136         clock_gettime_mono(&ts1);
1137         result = SMB_VFS_NEXT_CHDIR(handle, path);
1138         clock_gettime_mono(&ts2);
1139         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1140
1141         if (timediff > audit_timeout) {
1142                 smb_time_audit_log_fname("chdir", timediff, path);
1143         }
1144
1145         return result;
1146 }
1147
1148 static char *smb_time_audit_getwd(vfs_handle_struct *handle)
1149 {
1150         char *result;
1151         struct timespec ts1,ts2;
1152         double timediff;
1153
1154         clock_gettime_mono(&ts1);
1155         result = SMB_VFS_NEXT_GETWD(handle);
1156         clock_gettime_mono(&ts2);
1157         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1158
1159         if (timediff > audit_timeout) {
1160                 smb_time_audit_log("getwd", timediff);
1161         }
1162
1163         return result;
1164 }
1165
1166 static int smb_time_audit_ntimes(vfs_handle_struct *handle,
1167                                  const struct smb_filename *path,
1168                                  struct smb_file_time *ft)
1169 {
1170         int result;
1171         struct timespec ts1,ts2;
1172         double timediff;
1173
1174         clock_gettime_mono(&ts1);
1175         result = SMB_VFS_NEXT_NTIMES(handle, path, ft);
1176         clock_gettime_mono(&ts2);
1177         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1178
1179         if (timediff > audit_timeout) {
1180                 smb_time_audit_log_smb_fname("ntimes", timediff, path);
1181         }
1182
1183         return result;
1184 }
1185
1186 static int smb_time_audit_ftruncate(vfs_handle_struct *handle,
1187                                     files_struct *fsp,
1188                                     off_t len)
1189 {
1190         int result;
1191         struct timespec ts1,ts2;
1192         double timediff;
1193
1194         clock_gettime_mono(&ts1);
1195         result = SMB_VFS_NEXT_FTRUNCATE(handle, fsp, len);
1196         clock_gettime_mono(&ts2);
1197         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1198
1199         if (timediff > audit_timeout) {
1200                 smb_time_audit_log_fsp("ftruncate", timediff, fsp);
1201         }
1202
1203         return result;
1204 }
1205
1206 static int smb_time_audit_fallocate(vfs_handle_struct *handle,
1207                                     files_struct *fsp,
1208                                     enum vfs_fallocate_mode mode,
1209                                     off_t offset,
1210                                     off_t len)
1211 {
1212         int result;
1213         int saved_errno = 0;
1214         struct timespec ts1,ts2;
1215         double timediff;
1216
1217         clock_gettime_mono(&ts1);
1218         result = SMB_VFS_NEXT_FALLOCATE(handle, fsp, mode, offset, len);
1219         if (result == -1) {
1220                 saved_errno = errno;
1221         }
1222         clock_gettime_mono(&ts2);
1223         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1224
1225         if (timediff > audit_timeout) {
1226                 smb_time_audit_log_fsp("fallocate", timediff, fsp);
1227         }
1228         if (result == -1) {
1229                 errno = saved_errno;
1230         }
1231         return result;
1232 }
1233
1234 static bool smb_time_audit_lock(vfs_handle_struct *handle, files_struct *fsp,
1235                                 int op, off_t offset, off_t count,
1236                                 int type)
1237 {
1238         bool result;
1239         struct timespec ts1,ts2;
1240         double timediff;
1241
1242         clock_gettime_mono(&ts1);
1243         result = SMB_VFS_NEXT_LOCK(handle, fsp, op, offset, count, type);
1244         clock_gettime_mono(&ts2);
1245         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1246
1247         if (timediff > audit_timeout) {
1248                 smb_time_audit_log_fsp("lock", timediff, fsp);
1249         }
1250
1251         return result;
1252 }
1253
1254 static int smb_time_audit_kernel_flock(struct vfs_handle_struct *handle,
1255                                        struct files_struct *fsp,
1256                                        uint32 share_mode, uint32 access_mask)
1257 {
1258         int result;
1259         struct timespec ts1,ts2;
1260         double timediff;
1261
1262         clock_gettime_mono(&ts1);
1263         result = SMB_VFS_NEXT_KERNEL_FLOCK(handle, fsp, share_mode,
1264                                            access_mask);
1265         clock_gettime_mono(&ts2);
1266         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1267
1268         if (timediff > audit_timeout) {
1269                 smb_time_audit_log_fsp("kernel_flock", timediff, fsp);
1270         }
1271
1272         return result;
1273 }
1274
1275 static int smb_time_audit_linux_setlease(vfs_handle_struct *handle,
1276                                          files_struct *fsp,
1277                                          int leasetype)
1278 {
1279         int result;
1280         struct timespec ts1,ts2;
1281         double timediff;
1282
1283         clock_gettime_mono(&ts1);
1284         result = SMB_VFS_NEXT_LINUX_SETLEASE(handle, fsp, leasetype);
1285         clock_gettime_mono(&ts2);
1286         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1287
1288         if (timediff > audit_timeout) {
1289                 smb_time_audit_log_fsp("linux_setlease", timediff, fsp);
1290         }
1291
1292         return result;
1293 }
1294
1295 static bool smb_time_audit_getlock(vfs_handle_struct *handle,
1296                                    files_struct *fsp,
1297                                    off_t *poffset, off_t *pcount,
1298                                    int *ptype, pid_t *ppid)
1299 {
1300         bool result;
1301         struct timespec ts1,ts2;
1302         double timediff;
1303
1304         clock_gettime_mono(&ts1);
1305         result = SMB_VFS_NEXT_GETLOCK(handle, fsp, poffset, pcount, ptype,
1306                                       ppid);
1307         clock_gettime_mono(&ts2);
1308         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1309
1310         if (timediff > audit_timeout) {
1311                 smb_time_audit_log_fsp("getlock", timediff, fsp);
1312         }
1313
1314         return result;
1315 }
1316
1317 static int smb_time_audit_symlink(vfs_handle_struct *handle,
1318                                   const char *oldpath, const char *newpath)
1319 {
1320         int result;
1321         struct timespec ts1,ts2;
1322         double timediff;
1323
1324         clock_gettime_mono(&ts1);
1325         result = SMB_VFS_NEXT_SYMLINK(handle, oldpath, newpath);
1326         clock_gettime_mono(&ts2);
1327         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1328
1329         if (timediff > audit_timeout) {
1330                 smb_time_audit_log_fname("symlink", timediff, newpath);
1331         }
1332
1333         return result;
1334 }
1335
1336 static int smb_time_audit_readlink(vfs_handle_struct *handle,
1337                           const char *path, char *buf, size_t bufsiz)
1338 {
1339         int result;
1340         struct timespec ts1,ts2;
1341         double timediff;
1342
1343         clock_gettime_mono(&ts1);
1344         result = SMB_VFS_NEXT_READLINK(handle, path, buf, bufsiz);
1345         clock_gettime_mono(&ts2);
1346         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1347
1348         if (timediff > audit_timeout) {
1349                 smb_time_audit_log_fname("readlink", timediff, path);
1350         }
1351
1352         return result;
1353 }
1354
1355 static int smb_time_audit_link(vfs_handle_struct *handle,
1356                                const char *oldpath, const char *newpath)
1357 {
1358         int result;
1359         struct timespec ts1,ts2;
1360         double timediff;
1361
1362         clock_gettime_mono(&ts1);
1363         result = SMB_VFS_NEXT_LINK(handle, oldpath, newpath);
1364         clock_gettime_mono(&ts2);
1365         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1366
1367         if (timediff > audit_timeout) {
1368                 smb_time_audit_log_fname("link", timediff, newpath);
1369         }
1370
1371         return result;
1372 }
1373
1374 static int smb_time_audit_mknod(vfs_handle_struct *handle,
1375                                 const char *pathname, mode_t mode,
1376                                 SMB_DEV_T dev)
1377 {
1378         int result;
1379         struct timespec ts1,ts2;
1380         double timediff;
1381
1382         clock_gettime_mono(&ts1);
1383         result = SMB_VFS_NEXT_MKNOD(handle, pathname, mode, dev);
1384         clock_gettime_mono(&ts2);
1385         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1386
1387         if (timediff > audit_timeout) {
1388                 smb_time_audit_log_fname("mknod", timediff, pathname);
1389         }
1390
1391         return result;
1392 }
1393
1394 static char *smb_time_audit_realpath(vfs_handle_struct *handle,
1395                                      const char *path)
1396 {
1397         char *result;
1398         struct timespec ts1,ts2;
1399         double timediff;
1400
1401         clock_gettime_mono(&ts1);
1402         result = SMB_VFS_NEXT_REALPATH(handle, path);
1403         clock_gettime_mono(&ts2);
1404         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1405
1406         if (timediff > audit_timeout) {
1407                 smb_time_audit_log_fname("realpath", timediff, path);
1408         }
1409
1410         return result;
1411 }
1412
1413 static NTSTATUS smb_time_audit_notify_watch(struct vfs_handle_struct *handle,
1414                         struct sys_notify_context *ctx,
1415                         const char *path,
1416                         uint32_t *filter,
1417                         uint32_t *subdir_filter,
1418                         void (*callback)(struct sys_notify_context *ctx,
1419                                         void *private_data,
1420                                         struct notify_event *ev),
1421                         void *private_data, void *handle_p)
1422 {
1423         NTSTATUS result;
1424         struct timespec ts1,ts2;
1425         double timediff;
1426
1427         clock_gettime_mono(&ts1);
1428         result = SMB_VFS_NEXT_NOTIFY_WATCH(handle, ctx, path,
1429                                            filter, subdir_filter, callback,
1430                                            private_data, handle_p);
1431         clock_gettime_mono(&ts2);
1432         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1433
1434         if (timediff > audit_timeout) {
1435                 smb_time_audit_log_fname("notify_watch", timediff, path);
1436         }
1437
1438         return result;
1439 }
1440
1441 static int smb_time_audit_chflags(vfs_handle_struct *handle,
1442                                   const char *path, unsigned int flags)
1443 {
1444         int result;
1445         struct timespec ts1,ts2;
1446         double timediff;
1447
1448         clock_gettime_mono(&ts1);
1449         result = SMB_VFS_NEXT_CHFLAGS(handle, path, flags);
1450         clock_gettime_mono(&ts2);
1451         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1452
1453         if (timediff > audit_timeout) {
1454                 smb_time_audit_log_fname("chflags", timediff, path);
1455         }
1456
1457         return result;
1458 }
1459
1460 static struct file_id smb_time_audit_file_id_create(struct vfs_handle_struct *handle,
1461                                                     const SMB_STRUCT_STAT *sbuf)
1462 {
1463         struct file_id id_zero;
1464         struct file_id result;
1465         struct timespec ts1,ts2;
1466         double timediff;
1467
1468         ZERO_STRUCT(id_zero);
1469
1470         clock_gettime_mono(&ts1);
1471         result = SMB_VFS_NEXT_FILE_ID_CREATE(handle, sbuf);
1472         clock_gettime_mono(&ts2);
1473         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1474
1475         if (timediff > audit_timeout) {
1476                 smb_time_audit_log("file_id_create", timediff);
1477         }
1478
1479         return result;
1480 }
1481
1482 static NTSTATUS smb_time_audit_streaminfo(vfs_handle_struct *handle,
1483                                           struct files_struct *fsp,
1484                                           const char *fname,
1485                                           TALLOC_CTX *mem_ctx,
1486                                           unsigned int *pnum_streams,
1487                                           struct stream_struct **pstreams)
1488 {
1489         NTSTATUS result;
1490         struct timespec ts1,ts2;
1491         double timediff;
1492
1493         clock_gettime_mono(&ts1);
1494         result = SMB_VFS_NEXT_STREAMINFO(handle, fsp, fname, mem_ctx,
1495                                          pnum_streams, pstreams);
1496         clock_gettime_mono(&ts2);
1497         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1498
1499         if (timediff > audit_timeout) {
1500                 smb_time_audit_log_fsp("streaminfo", timediff, fsp);
1501         }
1502
1503         return result;
1504 }
1505
1506 static int smb_time_audit_get_real_filename(struct vfs_handle_struct *handle,
1507                                             const char *path,
1508                                             const char *name,
1509                                             TALLOC_CTX *mem_ctx,
1510                                             char **found_name)
1511 {
1512         int result;
1513         struct timespec ts1,ts2;
1514         double timediff;
1515
1516         clock_gettime_mono(&ts1);
1517         result = SMB_VFS_NEXT_GET_REAL_FILENAME(handle, path, name, mem_ctx,
1518                                                 found_name);
1519         clock_gettime_mono(&ts2);
1520         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1521
1522         if (timediff > audit_timeout) {
1523                 smb_time_audit_log_fname("get_real_filename", timediff, path);
1524         }
1525
1526         return result;
1527 }
1528
1529 static const char *smb_time_audit_connectpath(vfs_handle_struct *handle,
1530                                               const char *fname)
1531 {
1532         const char *result;
1533         struct timespec ts1,ts2;
1534         double timediff;
1535
1536         clock_gettime_mono(&ts1);
1537         result = SMB_VFS_NEXT_CONNECTPATH(handle, fname);
1538         clock_gettime_mono(&ts2);
1539         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1540
1541         if (timediff > audit_timeout) {
1542                 smb_time_audit_log_fname("connectpath", timediff, fname);
1543         }
1544
1545         return result;
1546 }
1547
1548 static NTSTATUS smb_time_audit_brl_lock_windows(struct vfs_handle_struct *handle,
1549                                                 struct byte_range_lock *br_lck,
1550                                                 struct lock_struct *plock,
1551                                                 bool blocking_lock,
1552                                                 struct blocking_lock_record *blr)
1553 {
1554         NTSTATUS result;
1555         struct timespec ts1,ts2;
1556         double timediff;
1557
1558         clock_gettime_mono(&ts1);
1559         result = SMB_VFS_NEXT_BRL_LOCK_WINDOWS(handle, br_lck, plock,
1560                                                blocking_lock, blr);
1561         clock_gettime_mono(&ts2);
1562         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1563
1564         if (timediff > audit_timeout) {
1565                 smb_time_audit_log_fsp("brl_lock_windows", timediff,
1566                                        br_lck->fsp);
1567         }
1568
1569         return result;
1570 }
1571
1572 static bool smb_time_audit_brl_unlock_windows(struct vfs_handle_struct *handle,
1573                                               struct messaging_context *msg_ctx,
1574                                               struct byte_range_lock *br_lck,
1575                                               const struct lock_struct *plock)
1576 {
1577         bool result;
1578         struct timespec ts1,ts2;
1579         double timediff;
1580
1581         clock_gettime_mono(&ts1);
1582         result = SMB_VFS_NEXT_BRL_UNLOCK_WINDOWS(handle, msg_ctx, br_lck,
1583                                                  plock);
1584         clock_gettime_mono(&ts2);
1585         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1586
1587         if (timediff > audit_timeout) {
1588                 smb_time_audit_log_fsp("brl_unlock_windows", timediff,
1589                                        br_lck->fsp);
1590         }
1591
1592         return result;
1593 }
1594
1595 static bool smb_time_audit_brl_cancel_windows(struct vfs_handle_struct *handle,
1596                                               struct byte_range_lock *br_lck,
1597                                               struct lock_struct *plock,
1598                                               struct blocking_lock_record *blr)
1599 {
1600         bool result;
1601         struct timespec ts1,ts2;
1602         double timediff;
1603
1604         clock_gettime_mono(&ts1);
1605         result = SMB_VFS_NEXT_BRL_CANCEL_WINDOWS(handle, br_lck, plock, blr);
1606         clock_gettime_mono(&ts2);
1607         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1608
1609         if (timediff > audit_timeout) {
1610                 smb_time_audit_log_fsp("brl_cancel_windows", timediff,
1611                                        br_lck->fsp);
1612         }
1613
1614         return result;
1615 }
1616
1617 static bool smb_time_audit_strict_lock(struct vfs_handle_struct *handle,
1618                                        struct files_struct *fsp,
1619                                        struct lock_struct *plock)
1620 {
1621         bool result;
1622         struct timespec ts1,ts2;
1623         double timediff;
1624
1625         clock_gettime_mono(&ts1);
1626         result = SMB_VFS_NEXT_STRICT_LOCK(handle, fsp, plock);
1627         clock_gettime_mono(&ts2);
1628         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1629
1630         if (timediff > audit_timeout) {
1631                 smb_time_audit_log_fsp("strict_lock", timediff, fsp);
1632         }
1633
1634         return result;
1635 }
1636
1637 static void smb_time_audit_strict_unlock(struct vfs_handle_struct *handle,
1638                                          struct files_struct *fsp,
1639                                          struct lock_struct *plock)
1640 {
1641         struct timespec ts1,ts2;
1642         double timediff;
1643
1644         clock_gettime_mono(&ts1);
1645         SMB_VFS_NEXT_STRICT_UNLOCK(handle, fsp, plock);
1646         clock_gettime_mono(&ts2);
1647         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1648
1649         if (timediff > audit_timeout) {
1650                 smb_time_audit_log_fsp("strict_unlock", timediff, fsp);
1651         }
1652 }
1653
1654 static NTSTATUS smb_time_audit_translate_name(struct vfs_handle_struct *handle,
1655                                               const char *name,
1656                                               enum vfs_translate_direction direction,
1657                                               TALLOC_CTX *mem_ctx,
1658                                               char **mapped_name)
1659 {
1660         NTSTATUS result;
1661         struct timespec ts1,ts2;
1662         double timediff;
1663
1664         clock_gettime_mono(&ts1);
1665         result = SMB_VFS_NEXT_TRANSLATE_NAME(handle, name, direction, mem_ctx,
1666                                              mapped_name);
1667         clock_gettime_mono(&ts2);
1668         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1669
1670         if (timediff > audit_timeout) {
1671                 smb_time_audit_log_fname("translate_name", timediff, name);
1672         }
1673
1674         return result;
1675 }
1676
1677 static NTSTATUS smb_time_audit_fget_nt_acl(vfs_handle_struct *handle,
1678                                            files_struct *fsp,
1679                                            uint32 security_info,
1680                                            TALLOC_CTX *mem_ctx,
1681                                            struct security_descriptor **ppdesc)
1682 {
1683         NTSTATUS result;
1684         struct timespec ts1,ts2;
1685         double timediff;
1686
1687         clock_gettime_mono(&ts1);
1688         result = SMB_VFS_NEXT_FGET_NT_ACL(handle, fsp, security_info,
1689                                           mem_ctx, ppdesc);
1690         clock_gettime_mono(&ts2);
1691         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1692
1693         if (timediff > audit_timeout) {
1694                 smb_time_audit_log_fsp("fget_nt_acl", timediff, fsp);
1695         }
1696
1697         return result;
1698 }
1699
1700 static NTSTATUS smb_time_audit_get_nt_acl(vfs_handle_struct *handle,
1701                                           const char *name,
1702                                           uint32 security_info,
1703                                           TALLOC_CTX *mem_ctx,
1704                                           struct security_descriptor **ppdesc)
1705 {
1706         NTSTATUS result;
1707         struct timespec ts1,ts2;
1708         double timediff;
1709
1710         clock_gettime_mono(&ts1);
1711         result = SMB_VFS_NEXT_GET_NT_ACL(handle, name, security_info,
1712                                          mem_ctx, ppdesc);
1713         clock_gettime_mono(&ts2);
1714         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1715
1716         if (timediff > audit_timeout) {
1717                 smb_time_audit_log_fname("get_nt_acl", timediff, name);
1718         }
1719
1720         return result;
1721 }
1722
1723 static NTSTATUS smb_time_audit_fset_nt_acl(vfs_handle_struct *handle,
1724                                            files_struct *fsp,
1725                                            uint32 security_info_sent,
1726                                            const struct security_descriptor *psd)
1727 {
1728         NTSTATUS result;
1729         struct timespec ts1,ts2;
1730         double timediff;
1731
1732         clock_gettime_mono(&ts1);
1733         result = SMB_VFS_NEXT_FSET_NT_ACL(handle, fsp, security_info_sent,
1734                                           psd);
1735         clock_gettime_mono(&ts2);
1736         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1737
1738         if (timediff > audit_timeout) {
1739                 smb_time_audit_log_fsp("fset_nt_acl", timediff, fsp);
1740         }
1741
1742         return result;
1743 }
1744
1745 static int smb_time_audit_chmod_acl(vfs_handle_struct *handle,
1746                                     const char *path, mode_t mode)
1747 {
1748         int result;
1749         struct timespec ts1,ts2;
1750         double timediff;
1751
1752         clock_gettime_mono(&ts1);
1753         result = SMB_VFS_NEXT_CHMOD_ACL(handle, path, mode);
1754         clock_gettime_mono(&ts2);
1755         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1756
1757         if (timediff > audit_timeout) {
1758                 smb_time_audit_log_fname("chmod_acl", timediff, path);
1759         }
1760
1761         return result;
1762 }
1763
1764 static int smb_time_audit_fchmod_acl(vfs_handle_struct *handle,
1765                                      files_struct *fsp, mode_t mode)
1766 {
1767         int result;
1768         struct timespec ts1,ts2;
1769         double timediff;
1770
1771         clock_gettime_mono(&ts1);
1772         result = SMB_VFS_NEXT_FCHMOD_ACL(handle, fsp, mode);
1773         clock_gettime_mono(&ts2);
1774         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1775
1776         if (timediff > audit_timeout) {
1777                 smb_time_audit_log_fsp("fchmod_acl", timediff, fsp);
1778         }
1779
1780         return result;
1781 }
1782
1783 static SMB_ACL_T smb_time_audit_sys_acl_get_file(vfs_handle_struct *handle,
1784                                                  const char *path_p,
1785                                                  SMB_ACL_TYPE_T type,
1786                                                  TALLOC_CTX *mem_ctx)
1787 {
1788         SMB_ACL_T result;
1789         struct timespec ts1,ts2;
1790         double timediff;
1791
1792         clock_gettime_mono(&ts1);
1793         result = SMB_VFS_NEXT_SYS_ACL_GET_FILE(handle, path_p, type, mem_ctx);
1794         clock_gettime_mono(&ts2);
1795         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1796
1797         if (timediff > audit_timeout) {
1798                 smb_time_audit_log_fname("sys_acl_get_file", timediff, path_p);
1799         }
1800
1801         return result;
1802 }
1803
1804 static SMB_ACL_T smb_time_audit_sys_acl_get_fd(vfs_handle_struct *handle,
1805                                                files_struct *fsp,
1806                                                TALLOC_CTX *mem_ctx)
1807 {
1808         SMB_ACL_T result;
1809         struct timespec ts1,ts2;
1810         double timediff;
1811
1812         clock_gettime_mono(&ts1);
1813         result = SMB_VFS_NEXT_SYS_ACL_GET_FD(handle, fsp, mem_ctx);
1814         clock_gettime_mono(&ts2);
1815         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1816
1817         if (timediff > audit_timeout) {
1818                 smb_time_audit_log_fsp("sys_acl_get_fd", timediff, fsp);
1819         }
1820
1821         return result;
1822 }
1823
1824
1825 static int smb_time_audit_sys_acl_blob_get_file(vfs_handle_struct *handle,
1826                                                 const char *path_p,
1827                                                 TALLOC_CTX *mem_ctx,
1828                                                 char **blob_description,
1829                                                 DATA_BLOB *blob)
1830 {
1831         int result;
1832         struct timespec ts1,ts2;
1833         double timediff;
1834
1835         clock_gettime_mono(&ts1);
1836         result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FILE(handle, path_p, mem_ctx, blob_description, blob);
1837         clock_gettime_mono(&ts2);
1838         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1839
1840         if (timediff > audit_timeout) {
1841                 smb_time_audit_log("sys_acl_blob_get_file", timediff);
1842         }
1843
1844         return result;
1845 }
1846
1847 static int smb_time_audit_sys_acl_blob_get_fd(vfs_handle_struct *handle,
1848                                               files_struct *fsp,
1849                                               TALLOC_CTX *mem_ctx,
1850                                               char **blob_description,
1851                                               DATA_BLOB *blob)
1852 {
1853         int result;
1854         struct timespec ts1,ts2;
1855         double timediff;
1856
1857         clock_gettime_mono(&ts1);
1858         result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FD(handle, fsp, mem_ctx, blob_description, blob);
1859         clock_gettime_mono(&ts2);
1860         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1861
1862         if (timediff > audit_timeout) {
1863                 smb_time_audit_log("sys_acl_blob_get_fd", timediff);
1864         }
1865
1866         return result;
1867 }
1868
1869 static int smb_time_audit_sys_acl_set_file(vfs_handle_struct *handle,
1870                                            const char *name,
1871                                            SMB_ACL_TYPE_T acltype,
1872                                            SMB_ACL_T theacl)
1873 {
1874         int result;
1875         struct timespec ts1,ts2;
1876         double timediff;
1877
1878         clock_gettime_mono(&ts1);
1879         result = SMB_VFS_NEXT_SYS_ACL_SET_FILE(handle, name, acltype,
1880                                                theacl);
1881         clock_gettime_mono(&ts2);
1882         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1883
1884         if (timediff > audit_timeout) {
1885                 smb_time_audit_log_fname("sys_acl_set_file", timediff, name);
1886         }
1887
1888         return result;
1889 }
1890
1891 static int smb_time_audit_sys_acl_set_fd(vfs_handle_struct *handle,
1892                                          files_struct *fsp,
1893                                          SMB_ACL_T theacl)
1894 {
1895         int result;
1896         struct timespec ts1,ts2;
1897         double timediff;
1898
1899         clock_gettime_mono(&ts1);
1900         result = SMB_VFS_NEXT_SYS_ACL_SET_FD(handle, fsp, theacl);
1901         clock_gettime_mono(&ts2);
1902         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1903
1904         if (timediff > audit_timeout) {
1905                 smb_time_audit_log_fsp("sys_acl_set_fd", timediff, fsp);
1906         }
1907
1908         return result;
1909 }
1910
1911 static int smb_time_audit_sys_acl_delete_def_file(vfs_handle_struct *handle,
1912                                                   const char *path)
1913 {
1914         int result;
1915         struct timespec ts1,ts2;
1916         double timediff;
1917
1918         clock_gettime_mono(&ts1);
1919         result = SMB_VFS_NEXT_SYS_ACL_DELETE_DEF_FILE(handle, path);
1920         clock_gettime_mono(&ts2);
1921         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1922
1923         if (timediff > audit_timeout) {
1924                 smb_time_audit_log_fname("sys_acl_delete_def_file", timediff, path);
1925         }
1926
1927         return result;
1928 }
1929
1930 static ssize_t smb_time_audit_getxattr(struct vfs_handle_struct *handle,
1931                                        const char *path, const char *name,
1932                                        void *value, size_t size)
1933 {
1934         ssize_t result;
1935         struct timespec ts1,ts2;
1936         double timediff;
1937
1938         clock_gettime_mono(&ts1);
1939         result = SMB_VFS_NEXT_GETXATTR(handle, path, name, value, size);
1940         clock_gettime_mono(&ts2);
1941         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1942
1943         if (timediff > audit_timeout) {
1944                 smb_time_audit_log_fname("getxattr", timediff, path);
1945         }
1946
1947         return result;
1948 }
1949
1950 static ssize_t smb_time_audit_fgetxattr(struct vfs_handle_struct *handle,
1951                                         struct files_struct *fsp,
1952                                         const char *name, void *value,
1953                                         size_t size)
1954 {
1955         ssize_t result;
1956         struct timespec ts1,ts2;
1957         double timediff;
1958
1959         clock_gettime_mono(&ts1);
1960         result = SMB_VFS_NEXT_FGETXATTR(handle, fsp, name, value, size);
1961         clock_gettime_mono(&ts2);
1962         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1963
1964         if (timediff > audit_timeout) {
1965                 smb_time_audit_log_fsp("fgetxattr", timediff, fsp);
1966         }
1967
1968         return result;
1969 }
1970
1971 static ssize_t smb_time_audit_listxattr(struct vfs_handle_struct *handle,
1972                                         const char *path, char *list,
1973                                         size_t size)
1974 {
1975         ssize_t result;
1976         struct timespec ts1,ts2;
1977         double timediff;
1978
1979         clock_gettime_mono(&ts1);
1980         result = SMB_VFS_NEXT_LISTXATTR(handle, path, list, size);
1981         clock_gettime_mono(&ts2);
1982         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1983
1984         if (timediff > audit_timeout) {
1985                 smb_time_audit_log_fname("listxattr", timediff, path);
1986         }
1987
1988         return result;
1989 }
1990
1991 static ssize_t smb_time_audit_flistxattr(struct vfs_handle_struct *handle,
1992                                          struct files_struct *fsp, char *list,
1993                                          size_t size)
1994 {
1995         ssize_t result;
1996         struct timespec ts1,ts2;
1997         double timediff;
1998
1999         clock_gettime_mono(&ts1);
2000         result = SMB_VFS_NEXT_FLISTXATTR(handle, fsp, list, size);
2001         clock_gettime_mono(&ts2);
2002         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2003
2004         if (timediff > audit_timeout) {
2005                 smb_time_audit_log_fsp("flistxattr", timediff, fsp);
2006         }
2007
2008         return result;
2009 }
2010
2011 static int smb_time_audit_removexattr(struct vfs_handle_struct *handle,
2012                                       const char *path, const char *name)
2013 {
2014         int result;
2015         struct timespec ts1,ts2;
2016         double timediff;
2017
2018         clock_gettime_mono(&ts1);
2019         result = SMB_VFS_NEXT_REMOVEXATTR(handle, path, name);
2020         clock_gettime_mono(&ts2);
2021         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2022
2023         if (timediff > audit_timeout) {
2024                 smb_time_audit_log_fname("removexattr", timediff, path);
2025         }
2026
2027         return result;
2028 }
2029
2030 static int smb_time_audit_fremovexattr(struct vfs_handle_struct *handle,
2031                                        struct files_struct *fsp,
2032                                        const char *name)
2033 {
2034         int result;
2035         struct timespec ts1,ts2;
2036         double timediff;
2037
2038         clock_gettime_mono(&ts1);
2039         result = SMB_VFS_NEXT_FREMOVEXATTR(handle, fsp, name);
2040         clock_gettime_mono(&ts2);
2041         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2042
2043         if (timediff > audit_timeout) {
2044                 smb_time_audit_log_fsp("fremovexattr", timediff, fsp);
2045         }
2046
2047         return result;
2048 }
2049
2050 static int smb_time_audit_setxattr(struct vfs_handle_struct *handle,
2051                                    const char *path, const char *name,
2052                                    const void *value, size_t size,
2053                                    int flags)
2054 {
2055         int result;
2056         struct timespec ts1,ts2;
2057         double timediff;
2058
2059         clock_gettime_mono(&ts1);
2060         result = SMB_VFS_NEXT_SETXATTR(handle, path, name, value, size,
2061                                        flags);
2062         clock_gettime_mono(&ts2);
2063         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2064
2065         if (timediff > audit_timeout) {
2066                 smb_time_audit_log_fname("setxattr", timediff, path);
2067         }
2068
2069         return result;
2070 }
2071
2072 static int smb_time_audit_fsetxattr(struct vfs_handle_struct *handle,
2073                                     struct files_struct *fsp, const char *name,
2074                                     const void *value, size_t size, int flags)
2075 {
2076         int result;
2077         struct timespec ts1,ts2;
2078         double timediff;
2079
2080         clock_gettime_mono(&ts1);
2081         result = SMB_VFS_NEXT_FSETXATTR(handle, fsp, name, value, size, flags);
2082         clock_gettime_mono(&ts2);
2083         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2084
2085         if (timediff > audit_timeout) {
2086                 smb_time_audit_log_fsp("fsetxattr", timediff, fsp);
2087         }
2088
2089         return result;
2090 }
2091
2092 static bool smb_time_audit_aio_force(struct vfs_handle_struct *handle,
2093                                      struct files_struct *fsp)
2094 {
2095         bool result;
2096         struct timespec ts1,ts2;
2097         double timediff;
2098
2099         clock_gettime_mono(&ts1);
2100         result = SMB_VFS_NEXT_AIO_FORCE(handle, fsp);
2101         clock_gettime_mono(&ts2);
2102         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2103
2104         if (timediff > audit_timeout) {
2105                 smb_time_audit_log_fsp("aio_force", timediff, fsp);
2106         }
2107
2108         return result;
2109 }
2110
2111
2112
2113 /* VFS operations */
2114
2115 static struct vfs_fn_pointers vfs_time_audit_fns = {
2116         .connect_fn = smb_time_audit_connect,
2117         .disconnect_fn = smb_time_audit_disconnect,
2118         .disk_free_fn = smb_time_audit_disk_free,
2119         .get_quota_fn = smb_time_audit_get_quota,
2120         .set_quota_fn = smb_time_audit_set_quota,
2121         .get_shadow_copy_data_fn = smb_time_audit_get_shadow_copy_data,
2122         .statvfs_fn = smb_time_audit_statvfs,
2123         .fs_capabilities_fn = smb_time_audit_fs_capabilities,
2124         .opendir_fn = smb_time_audit_opendir,
2125         .fdopendir_fn = smb_time_audit_fdopendir,
2126         .readdir_fn = smb_time_audit_readdir,
2127         .seekdir_fn = smb_time_audit_seekdir,
2128         .telldir_fn = smb_time_audit_telldir,
2129         .rewind_dir_fn = smb_time_audit_rewinddir,
2130         .mkdir_fn = smb_time_audit_mkdir,
2131         .rmdir_fn = smb_time_audit_rmdir,
2132         .closedir_fn = smb_time_audit_closedir,
2133         .init_search_op_fn = smb_time_audit_init_search_op,
2134         .open_fn = smb_time_audit_open,
2135         .create_file_fn = smb_time_audit_create_file,
2136         .close_fn = smb_time_audit_close,
2137         .read_fn = smb_time_audit_read,
2138         .pread_fn = smb_time_audit_pread,
2139         .pread_send_fn = smb_time_audit_pread_send,
2140         .pread_recv_fn = smb_time_audit_pread_recv,
2141         .write_fn = smb_time_audit_write,
2142         .pwrite_fn = smb_time_audit_pwrite,
2143         .pwrite_send_fn = smb_time_audit_pwrite_send,
2144         .pwrite_recv_fn = smb_time_audit_pwrite_recv,
2145         .lseek_fn = smb_time_audit_lseek,
2146         .sendfile_fn = smb_time_audit_sendfile,
2147         .recvfile_fn = smb_time_audit_recvfile,
2148         .rename_fn = smb_time_audit_rename,
2149         .fsync_fn = smb_time_audit_fsync,
2150         .fsync_send_fn = smb_time_audit_fsync_send,
2151         .fsync_recv_fn = smb_time_audit_fsync_recv,
2152         .stat_fn = smb_time_audit_stat,
2153         .fstat_fn = smb_time_audit_fstat,
2154         .lstat_fn = smb_time_audit_lstat,
2155         .get_alloc_size_fn = smb_time_audit_get_alloc_size,
2156         .unlink_fn = smb_time_audit_unlink,
2157         .chmod_fn = smb_time_audit_chmod,
2158         .fchmod_fn = smb_time_audit_fchmod,
2159         .chown_fn = smb_time_audit_chown,
2160         .fchown_fn = smb_time_audit_fchown,
2161         .lchown_fn = smb_time_audit_lchown,
2162         .chdir_fn = smb_time_audit_chdir,
2163         .getwd_fn = smb_time_audit_getwd,
2164         .ntimes_fn = smb_time_audit_ntimes,
2165         .ftruncate_fn = smb_time_audit_ftruncate,
2166         .fallocate_fn = smb_time_audit_fallocate,
2167         .lock_fn = smb_time_audit_lock,
2168         .kernel_flock_fn = smb_time_audit_kernel_flock,
2169         .linux_setlease_fn = smb_time_audit_linux_setlease,
2170         .getlock_fn = smb_time_audit_getlock,
2171         .symlink_fn = smb_time_audit_symlink,
2172         .readlink_fn = smb_time_audit_readlink,
2173         .link_fn = smb_time_audit_link,
2174         .mknod_fn = smb_time_audit_mknod,
2175         .realpath_fn = smb_time_audit_realpath,
2176         .notify_watch_fn = smb_time_audit_notify_watch,
2177         .chflags_fn = smb_time_audit_chflags,
2178         .file_id_create_fn = smb_time_audit_file_id_create,
2179         .streaminfo_fn = smb_time_audit_streaminfo,
2180         .get_real_filename_fn = smb_time_audit_get_real_filename,
2181         .connectpath_fn = smb_time_audit_connectpath,
2182         .brl_lock_windows_fn = smb_time_audit_brl_lock_windows,
2183         .brl_unlock_windows_fn = smb_time_audit_brl_unlock_windows,
2184         .brl_cancel_windows_fn = smb_time_audit_brl_cancel_windows,
2185         .strict_lock_fn = smb_time_audit_strict_lock,
2186         .strict_unlock_fn = smb_time_audit_strict_unlock,
2187         .translate_name_fn = smb_time_audit_translate_name,
2188         .fget_nt_acl_fn = smb_time_audit_fget_nt_acl,
2189         .get_nt_acl_fn = smb_time_audit_get_nt_acl,
2190         .fset_nt_acl_fn = smb_time_audit_fset_nt_acl,
2191         .chmod_acl_fn = smb_time_audit_chmod_acl,
2192         .fchmod_acl_fn = smb_time_audit_fchmod_acl,
2193         .sys_acl_get_file_fn = smb_time_audit_sys_acl_get_file,
2194         .sys_acl_get_fd_fn = smb_time_audit_sys_acl_get_fd,
2195         .sys_acl_blob_get_file_fn = smb_time_audit_sys_acl_blob_get_file,
2196         .sys_acl_blob_get_fd_fn = smb_time_audit_sys_acl_blob_get_fd,
2197         .sys_acl_set_file_fn = smb_time_audit_sys_acl_set_file,
2198         .sys_acl_set_fd_fn = smb_time_audit_sys_acl_set_fd,
2199         .sys_acl_delete_def_file_fn = smb_time_audit_sys_acl_delete_def_file,
2200         .getxattr_fn = smb_time_audit_getxattr,
2201         .fgetxattr_fn = smb_time_audit_fgetxattr,
2202         .listxattr_fn = smb_time_audit_listxattr,
2203         .flistxattr_fn = smb_time_audit_flistxattr,
2204         .removexattr_fn = smb_time_audit_removexattr,
2205         .fremovexattr_fn = smb_time_audit_fremovexattr,
2206         .setxattr_fn = smb_time_audit_setxattr,
2207         .fsetxattr_fn = smb_time_audit_fsetxattr,
2208         .aio_force_fn = smb_time_audit_aio_force,
2209 };
2210
2211
2212 NTSTATUS vfs_time_audit_init(void);
2213 NTSTATUS vfs_time_audit_init(void)
2214 {
2215         audit_timeout = (double)lp_parm_int(-1, "time_audit", "timeout",
2216                                             10000) / 1000.0;
2217         return smb_register_vfs(SMB_VFS_INTERFACE_VERSION, "time_audit",
2218                                 &vfs_time_audit_fns);
2219 }