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