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