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                                            const 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_renameat(vfs_handle_struct *handle,
 900                                 files_struct *srcfsp,
 901                                 const struct smb_filename *oldname,
 902                                 files_struct *dstfsp,
 903                                 const struct smb_filename *newname)
 904 {
 905         int result;
 906         struct timespec ts1,ts2;
 907         double timediff;
 908
 909         clock_gettime_mono(&ts1);
 910         result = SMB_VFS_NEXT_RENAMEAT(handle,
 911                         srcfsp,
 912                         oldname,
 913                         dstfsp,
 914                         newname);
 915         clock_gettime_mono(&ts2);
 916         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 917
 918         if (timediff > audit_timeout) {
 919                 smb_time_audit_log_smb_fname("renameat", timediff, newname);
 920         }
 921
 922         return result;
 923 }
 924
 925 struct smb_time_audit_fsync_state {
 926         struct files_struct *fsp;
 927         int ret;
 928         struct vfs_aio_state vfs_aio_state;
 929 };
 930
 931 static void smb_time_audit_fsync_done(struct tevent_req *subreq);
 932
 933 static struct tevent_req *smb_time_audit_fsync_send(
 934         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 935         struct tevent_context *ev, struct files_struct *fsp)
 936 {
 937         struct tevent_req *req, *subreq;
 938         struct smb_time_audit_fsync_state *state;
 939
 940         req = tevent_req_create(mem_ctx, &state,
 941                                 struct smb_time_audit_fsync_state);
 942         if (req == NULL) {
 943                 return NULL;
 944         }
 945         state->fsp = fsp;
 946
 947         subreq = SMB_VFS_NEXT_FSYNC_SEND(state, ev, handle, fsp);
 948         if (tevent_req_nomem(subreq, req)) {
 949                 return tevent_req_post(req, ev);
 950         }
 951         tevent_req_set_callback(subreq, smb_time_audit_fsync_done, req);
 952         return req;
 953 }
 954
 955 static void smb_time_audit_fsync_done(struct tevent_req *subreq)
 956 {
 957         struct tevent_req *req = tevent_req_callback_data(
 958                 subreq, struct tevent_req);
 959         struct smb_time_audit_fsync_state *state = tevent_req_data(
 960                 req, struct smb_time_audit_fsync_state);
 961
 962         state->ret = SMB_VFS_FSYNC_RECV(subreq, &state->vfs_aio_state);
 963         TALLOC_FREE(subreq);
 964         tevent_req_done(req);
 965 }
 966
 967 static int smb_time_audit_fsync_recv(struct tevent_req *req,
 968                                      struct vfs_aio_state *vfs_aio_state)
 969 {
 970         struct smb_time_audit_fsync_state *state = tevent_req_data(
 971                 req, struct smb_time_audit_fsync_state);
 972         double timediff;
 973
 974         timediff = state->vfs_aio_state.duration * 1.0e-9;
 975
 976         if (timediff > audit_timeout) {
 977                 smb_time_audit_log_fsp("async fsync", timediff, state->fsp);
 978         }
 979
 980         if (tevent_req_is_unix_error(req, &vfs_aio_state->error)) {
 981                 return -1;
 982         }
 983         *vfs_aio_state = state->vfs_aio_state;
 984         return state->ret;
 985 }
 986
 987 static int smb_time_audit_stat(vfs_handle_struct *handle,
 988                                struct smb_filename *fname)
 989 {
 990         int result;
 991         struct timespec ts1,ts2;
 992         double timediff;
 993
 994         clock_gettime_mono(&ts1);
 995         result = SMB_VFS_NEXT_STAT(handle, fname);
 996         clock_gettime_mono(&ts2);
 997         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 998
 999         if (timediff > audit_timeout) {
1000                 smb_time_audit_log_smb_fname("stat", timediff, fname);
1001         }
1002
1003         return result;
1004 }
1005
1006 static int smb_time_audit_fstat(vfs_handle_struct *handle, files_struct *fsp,
1007                                 SMB_STRUCT_STAT *sbuf)
1008 {
1009         int result;
1010         struct timespec ts1,ts2;
1011         double timediff;
1012
1013         clock_gettime_mono(&ts1);
1014         result = SMB_VFS_NEXT_FSTAT(handle, fsp, sbuf);
1015         clock_gettime_mono(&ts2);
1016         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1017
1018         if (timediff > audit_timeout) {
1019                 smb_time_audit_log_fsp("fstat", timediff, fsp);
1020         }
1021
1022         return result;
1023 }
1024
1025 static int smb_time_audit_lstat(vfs_handle_struct *handle,
1026                                 struct smb_filename *path)
1027 {
1028         int result;
1029         struct timespec ts1,ts2;
1030         double timediff;
1031
1032         clock_gettime_mono(&ts1);
1033         result = SMB_VFS_NEXT_LSTAT(handle, path);
1034         clock_gettime_mono(&ts2);
1035         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1036
1037         if (timediff > audit_timeout) {
1038                 smb_time_audit_log_smb_fname("lstat", timediff, path);
1039         }
1040
1041         return result;
1042 }
1043
1044 static uint64_t smb_time_audit_get_alloc_size(vfs_handle_struct *handle,
1045                                               files_struct *fsp,
1046                                               const SMB_STRUCT_STAT *sbuf)
1047 {
1048         uint64_t result;
1049         struct timespec ts1,ts2;
1050         double timediff;
1051
1052         clock_gettime_mono(&ts1);
1053         result = SMB_VFS_NEXT_GET_ALLOC_SIZE(handle, fsp, sbuf);
1054         clock_gettime_mono(&ts2);
1055         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1056
1057         if (timediff > audit_timeout) {
1058                 smb_time_audit_log_fsp("get_alloc_size", timediff, fsp);
1059         }
1060
1061         return result;
1062 }
1063
1064 static int smb_time_audit_unlink(vfs_handle_struct *handle,
1065                                  const struct smb_filename *path)
1066 {
1067         int result;
1068         struct timespec ts1,ts2;
1069         double timediff;
1070
1071         clock_gettime_mono(&ts1);
1072         result = SMB_VFS_NEXT_UNLINK(handle, path);
1073         clock_gettime_mono(&ts2);
1074         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1075
1076         if (timediff > audit_timeout) {
1077                 smb_time_audit_log_smb_fname("unlink", timediff, path);
1078         }
1079
1080         return result;
1081 }
1082
1083 static int smb_time_audit_chmod(vfs_handle_struct *handle,
1084                         const struct smb_filename *smb_fname,
1085                         mode_t mode)
1086 {
1087         int result;
1088         struct timespec ts1,ts2;
1089         double timediff;
1090
1091         clock_gettime_mono(&ts1);
1092         result = SMB_VFS_NEXT_CHMOD(handle, smb_fname, mode);
1093         clock_gettime_mono(&ts2);
1094         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1095
1096         if (timediff > audit_timeout) {
1097                 smb_time_audit_log_fname("chmod",
1098                         timediff,
1099                         smb_fname->base_name);
1100         }
1101
1102         return result;
1103 }
1104
1105 static int smb_time_audit_fchmod(vfs_handle_struct *handle, files_struct *fsp,
1106                                  mode_t mode)
1107 {
1108         int result;
1109         struct timespec ts1,ts2;
1110         double timediff;
1111
1112         clock_gettime_mono(&ts1);
1113         result = SMB_VFS_NEXT_FCHMOD(handle, fsp, mode);
1114         clock_gettime_mono(&ts2);
1115         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1116
1117         if (timediff > audit_timeout) {
1118                 smb_time_audit_log_fsp("fchmod", timediff, fsp);
1119         }
1120
1121         return result;
1122 }
1123
1124 static int smb_time_audit_chown(vfs_handle_struct *handle,
1125                         const struct smb_filename *smb_fname,
1126                         uid_t uid,
1127                         gid_t gid)
1128 {
1129         int result;
1130         struct timespec ts1,ts2;
1131         double timediff;
1132
1133         clock_gettime_mono(&ts1);
1134         result = SMB_VFS_NEXT_CHOWN(handle, smb_fname, uid, gid);
1135         clock_gettime_mono(&ts2);
1136         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1137
1138         if (timediff > audit_timeout) {
1139                 smb_time_audit_log_fname("chown",
1140                         timediff,
1141                         smb_fname->base_name);
1142         }
1143
1144         return result;
1145 }
1146
1147 static int smb_time_audit_fchown(vfs_handle_struct *handle, files_struct *fsp,
1148                                  uid_t uid, gid_t gid)
1149 {
1150         int result;
1151         struct timespec ts1,ts2;
1152         double timediff;
1153
1154         clock_gettime_mono(&ts1);
1155         result = SMB_VFS_NEXT_FCHOWN(handle, fsp, uid, gid);
1156         clock_gettime_mono(&ts2);
1157         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1158
1159         if (timediff > audit_timeout) {
1160                 smb_time_audit_log_fsp("fchown", timediff, fsp);
1161         }
1162
1163         return result;
1164 }
1165
1166 static int smb_time_audit_lchown(vfs_handle_struct *handle,
1167                         const struct smb_filename *smb_fname,
1168                         uid_t uid,
1169                         gid_t gid)
1170 {
1171         int result;
1172         struct timespec ts1,ts2;
1173         double timediff;
1174
1175         clock_gettime_mono(&ts1);
1176         result = SMB_VFS_NEXT_LCHOWN(handle, smb_fname, uid, gid);
1177         clock_gettime_mono(&ts2);
1178         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1179
1180         if (timediff > audit_timeout) {
1181                 smb_time_audit_log_fname("lchown",
1182                         timediff,
1183                         smb_fname->base_name);
1184         }
1185
1186         return result;
1187 }
1188
1189 static int smb_time_audit_chdir(vfs_handle_struct *handle,
1190                         const struct smb_filename *smb_fname)
1191 {
1192         int result;
1193         struct timespec ts1,ts2;
1194         double timediff;
1195
1196         clock_gettime_mono(&ts1);
1197         result = SMB_VFS_NEXT_CHDIR(handle, smb_fname);
1198         clock_gettime_mono(&ts2);
1199         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1200
1201         if (timediff > audit_timeout) {
1202                 smb_time_audit_log_fname("chdir",
1203                         timediff,
1204                         smb_fname->base_name);
1205         }
1206
1207         return result;
1208 }
1209
1210 static struct smb_filename *smb_time_audit_getwd(vfs_handle_struct *handle,
1211                                         TALLOC_CTX *mem_ctx)
1212 {
1213         struct smb_filename *result;
1214         struct timespec ts1,ts2;
1215         double timediff;
1216
1217         clock_gettime_mono(&ts1);
1218         result = SMB_VFS_NEXT_GETWD(handle, mem_ctx);
1219         clock_gettime_mono(&ts2);
1220         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1221
1222         if (timediff > audit_timeout) {
1223                 smb_time_audit_log("getwd", timediff);
1224         }
1225
1226         return result;
1227 }
1228
1229 static int smb_time_audit_ntimes(vfs_handle_struct *handle,
1230                                  const struct smb_filename *path,
1231                                  struct smb_file_time *ft)
1232 {
1233         int result;
1234         struct timespec ts1,ts2;
1235         double timediff;
1236
1237         clock_gettime_mono(&ts1);
1238         result = SMB_VFS_NEXT_NTIMES(handle, path, ft);
1239         clock_gettime_mono(&ts2);
1240         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1241
1242         if (timediff > audit_timeout) {
1243                 smb_time_audit_log_smb_fname("ntimes", timediff, path);
1244         }
1245
1246         return result;
1247 }
1248
1249 static int smb_time_audit_ftruncate(vfs_handle_struct *handle,
1250                                     files_struct *fsp,
1251                                     off_t len)
1252 {
1253         int result;
1254         struct timespec ts1,ts2;
1255         double timediff;
1256
1257         clock_gettime_mono(&ts1);
1258         result = SMB_VFS_NEXT_FTRUNCATE(handle, fsp, len);
1259         clock_gettime_mono(&ts2);
1260         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1261
1262         if (timediff > audit_timeout) {
1263                 smb_time_audit_log_fsp("ftruncate", timediff, fsp);
1264         }
1265
1266         return result;
1267 }
1268
1269 static int smb_time_audit_fallocate(vfs_handle_struct *handle,
1270                                     files_struct *fsp,
1271                                     uint32_t mode,
1272                                     off_t offset,
1273                                     off_t len)
1274 {
1275         int result;
1276         int saved_errno = 0;
1277         struct timespec ts1,ts2;
1278         double timediff;
1279
1280         clock_gettime_mono(&ts1);
1281         result = SMB_VFS_NEXT_FALLOCATE(handle, fsp, mode, offset, len);
1282         if (result == -1) {
1283                 saved_errno = errno;
1284         }
1285         clock_gettime_mono(&ts2);
1286         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1287
1288         if (timediff > audit_timeout) {
1289                 smb_time_audit_log_fsp("fallocate", timediff, fsp);
1290         }
1291         if (result == -1) {
1292                 errno = saved_errno;
1293         }
1294         return result;
1295 }
1296
1297 static bool smb_time_audit_lock(vfs_handle_struct *handle, files_struct *fsp,
1298                                 int op, off_t offset, off_t count,
1299                                 int type)
1300 {
1301         bool result;
1302         struct timespec ts1,ts2;
1303         double timediff;
1304
1305         clock_gettime_mono(&ts1);
1306         result = SMB_VFS_NEXT_LOCK(handle, fsp, op, offset, count, type);
1307         clock_gettime_mono(&ts2);
1308         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1309
1310         if (timediff > audit_timeout) {
1311                 smb_time_audit_log_fsp("lock", timediff, fsp);
1312         }
1313
1314         return result;
1315 }
1316
1317 static int smb_time_audit_kernel_flock(struct vfs_handle_struct *handle,
1318                                        struct files_struct *fsp,
1319                                        uint32_t share_mode, uint32_t access_mask)
1320 {
1321         int result;
1322         struct timespec ts1,ts2;
1323         double timediff;
1324
1325         clock_gettime_mono(&ts1);
1326         result = SMB_VFS_NEXT_KERNEL_FLOCK(handle, fsp, share_mode,
1327                                            access_mask);
1328         clock_gettime_mono(&ts2);
1329         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1330
1331         if (timediff > audit_timeout) {
1332                 smb_time_audit_log_fsp("kernel_flock", timediff, fsp);
1333         }
1334
1335         return result;
1336 }
1337
1338 static int smb_time_audit_linux_setlease(vfs_handle_struct *handle,
1339                                          files_struct *fsp,
1340                                          int leasetype)
1341 {
1342         int result;
1343         struct timespec ts1,ts2;
1344         double timediff;
1345
1346         clock_gettime_mono(&ts1);
1347         result = SMB_VFS_NEXT_LINUX_SETLEASE(handle, fsp, leasetype);
1348         clock_gettime_mono(&ts2);
1349         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1350
1351         if (timediff > audit_timeout) {
1352                 smb_time_audit_log_fsp("linux_setlease", timediff, fsp);
1353         }
1354
1355         return result;
1356 }
1357
1358 static bool smb_time_audit_getlock(vfs_handle_struct *handle,
1359                                    files_struct *fsp,
1360                                    off_t *poffset, off_t *pcount,
1361                                    int *ptype, pid_t *ppid)
1362 {
1363         bool result;
1364         struct timespec ts1,ts2;
1365         double timediff;
1366
1367         clock_gettime_mono(&ts1);
1368         result = SMB_VFS_NEXT_GETLOCK(handle, fsp, poffset, pcount, ptype,
1369                                       ppid);
1370         clock_gettime_mono(&ts2);
1371         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1372
1373         if (timediff > audit_timeout) {
1374                 smb_time_audit_log_fsp("getlock", timediff, fsp);
1375         }
1376
1377         return result;
1378 }
1379
1380 static int smb_time_audit_symlink(vfs_handle_struct *handle,
1381                                 const char *link_contents,
1382                                 const struct smb_filename *new_smb_fname)
1383 {
1384         int result;
1385         struct timespec ts1,ts2;
1386         double timediff;
1387
1388         clock_gettime_mono(&ts1);
1389         result = SMB_VFS_NEXT_SYMLINK(handle, link_contents, new_smb_fname);
1390         clock_gettime_mono(&ts2);
1391         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1392
1393         if (timediff > audit_timeout) {
1394                 smb_time_audit_log_fname("symlink", timediff,
1395                         new_smb_fname->base_name);
1396         }
1397
1398         return result;
1399 }
1400
1401 static int smb_time_audit_readlink(vfs_handle_struct *handle,
1402                                 const struct smb_filename *smb_fname,
1403                                 char *buf,
1404                                 size_t bufsiz)
1405 {
1406         int result;
1407         struct timespec ts1,ts2;
1408         double timediff;
1409
1410         clock_gettime_mono(&ts1);
1411         result = SMB_VFS_NEXT_READLINK(handle, smb_fname,
1412                                 buf, bufsiz);
1413         clock_gettime_mono(&ts2);
1414         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1415
1416         if (timediff > audit_timeout) {
1417                 smb_time_audit_log_fname("readlink", timediff,
1418                                 smb_fname->base_name);
1419         }
1420
1421         return result;
1422 }
1423
1424 static int smb_time_audit_linkat(vfs_handle_struct *handle,
1425                                 files_struct *srcfsp,
1426                                 const struct smb_filename *old_smb_fname,
1427                                 files_struct *dstfsp,
1428                                 const struct smb_filename *new_smb_fname,
1429                                 int flags)
1430 {
1431         int result;
1432         struct timespec ts1,ts2;
1433         double timediff;
1434
1435         clock_gettime_mono(&ts1);
1436         result = SMB_VFS_NEXT_LINKAT(handle,
1437                         srcfsp,
1438                         old_smb_fname,
1439                         dstfsp,
1440                         new_smb_fname,
1441                         flags);
1442         clock_gettime_mono(&ts2);
1443         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1444
1445         if (timediff > audit_timeout) {
1446                 smb_time_audit_log_fname("linkat", timediff,
1447                         new_smb_fname->base_name);
1448         }
1449
1450         return result;
1451 }
1452
1453 static int smb_time_audit_mknod(vfs_handle_struct *handle,
1454                                 const struct smb_filename *smb_fname,
1455                                 mode_t mode,
1456                                 SMB_DEV_T dev)
1457 {
1458         int result;
1459         struct timespec ts1,ts2;
1460         double timediff;
1461
1462         clock_gettime_mono(&ts1);
1463         result = SMB_VFS_NEXT_MKNOD(handle, smb_fname, mode, dev);
1464         clock_gettime_mono(&ts2);
1465         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1466
1467         if (timediff > audit_timeout) {
1468                 smb_time_audit_log_smb_fname("mknod", timediff, smb_fname);
1469         }
1470
1471         return result;
1472 }
1473
1474 static int smb_time_audit_mknodat(vfs_handle_struct *handle,
1475                                 files_struct *dirfsp,
1476                                 const struct smb_filename *smb_fname,
1477                                 mode_t mode,
1478                                 SMB_DEV_T dev)
1479 {
1480         int result;
1481         struct timespec ts1,ts2;
1482         double timediff;
1483
1484         clock_gettime_mono(&ts1);
1485         result = SMB_VFS_NEXT_MKNODAT(handle,
1486                                 dirfsp,
1487                                 smb_fname,
1488                                 mode,
1489                                 dev);
1490         clock_gettime_mono(&ts2);
1491         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1492
1493         if (timediff > audit_timeout) {
1494                 smb_time_audit_log_smb_fname("mknodat", timediff, smb_fname);
1495         }
1496
1497         return result;
1498 }
1499
1500 static struct smb_filename *smb_time_audit_realpath(vfs_handle_struct *handle,
1501                                 TALLOC_CTX *ctx,
1502                                 const struct smb_filename *smb_fname)
1503 {
1504         struct smb_filename *result_fname;
1505         struct timespec ts1,ts2;
1506         double timediff;
1507
1508         clock_gettime_mono(&ts1);
1509         result_fname = SMB_VFS_NEXT_REALPATH(handle, ctx, smb_fname);
1510         clock_gettime_mono(&ts2);
1511         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1512
1513         if (timediff > audit_timeout) {
1514                 smb_time_audit_log_fname("realpath", timediff,
1515                                 smb_fname->base_name);
1516         }
1517
1518         return result_fname;
1519 }
1520
1521 static int smb_time_audit_chflags(vfs_handle_struct *handle,
1522                                 const struct smb_filename *smb_fname,
1523                                 unsigned int flags)
1524 {
1525         int result;
1526         struct timespec ts1,ts2;
1527         double timediff;
1528
1529         clock_gettime_mono(&ts1);
1530         result = SMB_VFS_NEXT_CHFLAGS(handle, smb_fname, flags);
1531         clock_gettime_mono(&ts2);
1532         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1533
1534         if (timediff > audit_timeout) {
1535                 smb_time_audit_log_smb_fname("chflags", timediff, smb_fname);
1536         }
1537
1538         return result;
1539 }
1540
1541 static struct file_id smb_time_audit_file_id_create(struct vfs_handle_struct *handle,
1542                                                     const SMB_STRUCT_STAT *sbuf)
1543 {
1544         struct file_id id_zero;
1545         struct file_id result;
1546         struct timespec ts1,ts2;
1547         double timediff;
1548
1549         ZERO_STRUCT(id_zero);
1550
1551         clock_gettime_mono(&ts1);
1552         result = SMB_VFS_NEXT_FILE_ID_CREATE(handle, sbuf);
1553         clock_gettime_mono(&ts2);
1554         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1555
1556         if (timediff > audit_timeout) {
1557                 smb_time_audit_log("file_id_create", timediff);
1558         }
1559
1560         return result;
1561 }
1562
1563 static uint64_t smb_time_audit_fs_file_id(struct vfs_handle_struct *handle,
1564                                           const SMB_STRUCT_STAT *sbuf)
1565 {
1566         uint64_t result;
1567         struct timespec ts1,ts2;
1568         double timediff;
1569
1570         clock_gettime_mono(&ts1);
1571         result = SMB_VFS_NEXT_FS_FILE_ID(handle, sbuf);
1572         clock_gettime_mono(&ts2);
1573         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1574
1575         if (timediff > audit_timeout) {
1576                 smb_time_audit_log("fs_file_id", timediff);
1577         }
1578
1579         return result;
1580 }
1581
1582 static NTSTATUS smb_time_audit_streaminfo(vfs_handle_struct *handle,
1583                                           struct files_struct *fsp,
1584                                           const struct smb_filename *smb_fname,
1585                                           TALLOC_CTX *mem_ctx,
1586                                           unsigned int *pnum_streams,
1587                                           struct stream_struct **pstreams)
1588 {
1589         NTSTATUS result;
1590         struct timespec ts1,ts2;
1591         double timediff;
1592
1593         clock_gettime_mono(&ts1);
1594         result = SMB_VFS_NEXT_STREAMINFO(handle, fsp, smb_fname, mem_ctx,
1595                                          pnum_streams, pstreams);
1596         clock_gettime_mono(&ts2);
1597         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1598
1599         if (timediff > audit_timeout) {
1600                 smb_time_audit_log_fsp("streaminfo", timediff, fsp);
1601         }
1602
1603         return result;
1604 }
1605
1606 static int smb_time_audit_get_real_filename(struct vfs_handle_struct *handle,
1607                                             const char *path,
1608                                             const char *name,
1609                                             TALLOC_CTX *mem_ctx,
1610                                             char **found_name)
1611 {
1612         int result;
1613         struct timespec ts1,ts2;
1614         double timediff;
1615
1616         clock_gettime_mono(&ts1);
1617         result = SMB_VFS_NEXT_GET_REAL_FILENAME(handle, path, name, mem_ctx,
1618                                                 found_name);
1619         clock_gettime_mono(&ts2);
1620         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1621
1622         if (timediff > audit_timeout) {
1623                 smb_time_audit_log_fname("get_real_filename", timediff, path);
1624         }
1625
1626         return result;
1627 }
1628
1629 static const char *smb_time_audit_connectpath(vfs_handle_struct *handle,
1630                                         const struct smb_filename *smb_fname)
1631 {
1632         const char *result;
1633         struct timespec ts1,ts2;
1634         double timediff;
1635
1636         clock_gettime_mono(&ts1);
1637         result = SMB_VFS_NEXT_CONNECTPATH(handle, smb_fname);
1638         clock_gettime_mono(&ts2);
1639         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1640
1641         if (timediff > audit_timeout) {
1642                 smb_time_audit_log_fname("connectpath", timediff,
1643                         smb_fname->base_name);
1644         }
1645
1646         return result;
1647 }
1648
1649 static NTSTATUS smb_time_audit_brl_lock_windows(struct vfs_handle_struct *handle,
1650                                                 struct byte_range_lock *br_lck,
1651                                                 struct lock_struct *plock)
1652 {
1653         NTSTATUS result;
1654         struct timespec ts1,ts2;
1655         double timediff;
1656
1657         clock_gettime_mono(&ts1);
1658         result = SMB_VFS_NEXT_BRL_LOCK_WINDOWS(handle, br_lck, plock);
1659         clock_gettime_mono(&ts2);
1660         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1661
1662         if (timediff > audit_timeout) {
1663                 smb_time_audit_log_fsp("brl_lock_windows", timediff,
1664                                        brl_fsp(br_lck));
1665         }
1666
1667         return result;
1668 }
1669
1670 static bool smb_time_audit_brl_unlock_windows(struct vfs_handle_struct *handle,
1671                                               struct byte_range_lock *br_lck,
1672                                               const struct lock_struct *plock)
1673 {
1674         bool result;
1675         struct timespec ts1,ts2;
1676         double timediff;
1677
1678         clock_gettime_mono(&ts1);
1679         result = SMB_VFS_NEXT_BRL_UNLOCK_WINDOWS(handle, br_lck, plock);
1680         clock_gettime_mono(&ts2);
1681         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1682
1683         if (timediff > audit_timeout) {
1684                 smb_time_audit_log_fsp("brl_unlock_windows", timediff,
1685                                        brl_fsp(br_lck));
1686         }
1687
1688         return result;
1689 }
1690
1691 static bool smb_time_audit_strict_lock_check(struct vfs_handle_struct *handle,
1692                                              struct files_struct *fsp,
1693                                              struct lock_struct *plock)
1694 {
1695         bool result;
1696         struct timespec ts1,ts2;
1697         double timediff;
1698
1699         clock_gettime_mono(&ts1);
1700         result = SMB_VFS_NEXT_STRICT_LOCK_CHECK(handle, fsp, plock);
1701         clock_gettime_mono(&ts2);
1702         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1703
1704         if (timediff > audit_timeout) {
1705                 smb_time_audit_log_fsp("strict_lock_check", timediff, fsp);
1706         }
1707
1708         return result;
1709 }
1710
1711 static NTSTATUS smb_time_audit_translate_name(struct vfs_handle_struct *handle,
1712                                               const char *name,
1713                                               enum vfs_translate_direction direction,
1714                                               TALLOC_CTX *mem_ctx,
1715                                               char **mapped_name)
1716 {
1717         NTSTATUS result;
1718         struct timespec ts1,ts2;
1719         double timediff;
1720
1721         clock_gettime_mono(&ts1);
1722         result = SMB_VFS_NEXT_TRANSLATE_NAME(handle, name, direction, mem_ctx,
1723                                              mapped_name);
1724         clock_gettime_mono(&ts2);
1725         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1726
1727         if (timediff > audit_timeout) {
1728                 smb_time_audit_log_fname("translate_name", timediff, name);
1729         }
1730
1731         return result;
1732 }
1733
1734 static NTSTATUS smb_time_audit_fsctl(struct vfs_handle_struct *handle,
1735                                 struct files_struct *fsp,
1736                                 TALLOC_CTX *ctx,
1737                                 uint32_t function,
1738                                 uint16_t req_flags,
1739                                 const uint8_t *_in_data,
1740                                 uint32_t in_len,
1741                                 uint8_t **_out_data,
1742                                 uint32_t max_out_len,
1743                                 uint32_t *out_len)
1744 {
1745         NTSTATUS result;
1746         struct timespec ts1,ts2;
1747         double timediff;
1748
1749         clock_gettime_mono(&ts1);
1750         result = SMB_VFS_NEXT_FSCTL(handle,
1751                                 fsp,
1752                                 ctx,
1753                                 function,
1754                                 req_flags,
1755                                 _in_data,
1756                                 in_len,
1757                                 _out_data,
1758                                 max_out_len,
1759                                 out_len);
1760         clock_gettime_mono(&ts2);
1761         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1762
1763         if (timediff > audit_timeout) {
1764                 smb_time_audit_log_fsp("fsctl", timediff, fsp);
1765         }
1766
1767         return result;
1768 }
1769
1770 static NTSTATUS smb_time_get_dos_attributes(struct vfs_handle_struct *handle,
1771                                         struct smb_filename *smb_fname,
1772                                         uint32_t *dosmode)
1773 {
1774         NTSTATUS result;
1775         struct timespec ts1,ts2;
1776         double timediff;
1777
1778         clock_gettime_mono(&ts1);
1779         result = SMB_VFS_NEXT_GET_DOS_ATTRIBUTES(handle,
1780                                 smb_fname,
1781                                 dosmode);
1782         clock_gettime_mono(&ts2);
1783         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1784
1785         if (timediff > audit_timeout) {
1786                 smb_time_audit_log_fname("get_dos_attributes",
1787                                 timediff,
1788                                 smb_fname->base_name);
1789         }
1790
1791         return result;
1792 }
1793
1794 struct smb_time_audit_get_dos_attributes_state {
1795         struct vfs_aio_state aio_state;
1796         files_struct *dir_fsp;
1797         const struct smb_filename *smb_fname;
1798         uint32_t dosmode;
1799 };
1800
1801 static void smb_time_audit_get_dos_attributes_done(struct tevent_req *subreq);
1802
1803 static struct tevent_req *smb_time_audit_get_dos_attributes_send(
1804                         TALLOC_CTX *mem_ctx,
1805                         struct tevent_context *ev,
1806                         struct vfs_handle_struct *handle,
1807                         files_struct *dir_fsp,
1808                         struct smb_filename *smb_fname)
1809 {
1810         struct tevent_req *req = NULL;
1811         struct smb_time_audit_get_dos_attributes_state *state = NULL;
1812         struct tevent_req *subreq = NULL;
1813
1814         req = tevent_req_create(mem_ctx, &state,
1815                                 struct smb_time_audit_get_dos_attributes_state);
1816         if (req == NULL) {
1817                 return NULL;
1818         }
1819         *state = (struct smb_time_audit_get_dos_attributes_state) {
1820                 .dir_fsp = dir_fsp,
1821                 .smb_fname = smb_fname,
1822         };
1823
1824         subreq = SMB_VFS_NEXT_GET_DOS_ATTRIBUTES_SEND(mem_ctx,
1825                                                       ev,
1826                                                       handle,
1827                                                       dir_fsp,
1828                                                       smb_fname);
1829         if (tevent_req_nomem(subreq, req)) {
1830                 return tevent_req_post(req, ev);
1831         }
1832         tevent_req_set_callback(subreq,
1833                                 smb_time_audit_get_dos_attributes_done,
1834                                 req);
1835
1836         return req;
1837 }
1838
1839 static void smb_time_audit_get_dos_attributes_done(struct tevent_req *subreq)
1840 {
1841         struct tevent_req *req =
1842                 tevent_req_callback_data(subreq,
1843                 struct tevent_req);
1844         struct smb_time_audit_get_dos_attributes_state *state =
1845                 tevent_req_data(req,
1846                 struct smb_time_audit_get_dos_attributes_state);
1847         NTSTATUS status;
1848
1849         status = SMB_VFS_NEXT_GET_DOS_ATTRIBUTES_RECV(subreq,
1850                                                       &state->aio_state,
1851                                                       &state->dosmode);
1852         TALLOC_FREE(subreq);
1853         if (tevent_req_nterror(req, status)) {
1854                 return;
1855         }
1856
1857         tevent_req_done(req);
1858         return;
1859 }
1860
1861 static NTSTATUS smb_time_audit_get_dos_attributes_recv(struct tevent_req *req,
1862                                                 struct vfs_aio_state *aio_state,
1863                                                 uint32_t *dosmode)
1864 {
1865         struct smb_time_audit_get_dos_attributes_state *state =
1866                 tevent_req_data(req,
1867                 struct smb_time_audit_get_dos_attributes_state);
1868         NTSTATUS status;
1869         double timediff;
1870
1871         timediff = state->aio_state.duration * 1.0e-9;
1872
1873         if (timediff > audit_timeout) {
1874                 smb_time_audit_log_at("async get_dos_attributes",
1875                                       timediff,
1876                                       state->dir_fsp,
1877                                       state->smb_fname);
1878         }
1879
1880         if (tevent_req_is_nterror(req, &status)) {
1881                 tevent_req_received(req);
1882                 return status;
1883         }
1884
1885         *aio_state = state->aio_state;
1886         *dosmode = state->dosmode;
1887         tevent_req_received(req);
1888         return NT_STATUS_OK;
1889 }
1890
1891 static NTSTATUS smb_time_fget_dos_attributes(struct vfs_handle_struct *handle,
1892                                         struct files_struct *fsp,
1893                                         uint32_t *dosmode)
1894 {
1895         NTSTATUS result;
1896         struct timespec ts1,ts2;
1897         double timediff;
1898
1899         clock_gettime_mono(&ts1);
1900         result = SMB_VFS_NEXT_FGET_DOS_ATTRIBUTES(handle,
1901                                 fsp,
1902                                 dosmode);
1903         clock_gettime_mono(&ts2);
1904         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1905
1906         if (timediff > audit_timeout) {
1907                 smb_time_audit_log_fsp("fget_dos_attributes", timediff, fsp);
1908         }
1909
1910         return result;
1911 }
1912
1913 static NTSTATUS smb_time_set_dos_attributes(struct vfs_handle_struct *handle,
1914                                         const struct smb_filename *smb_fname,
1915                                         uint32_t dosmode)
1916 {
1917         NTSTATUS result;
1918         struct timespec ts1,ts2;
1919         double timediff;
1920
1921         clock_gettime_mono(&ts1);
1922         result = SMB_VFS_NEXT_SET_DOS_ATTRIBUTES(handle,
1923                                 smb_fname,
1924                                 dosmode);
1925         clock_gettime_mono(&ts2);
1926         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1927
1928         if (timediff > audit_timeout) {
1929                 smb_time_audit_log_fname("set_dos_attributes",
1930                                 timediff,
1931                                 smb_fname->base_name);
1932         }
1933
1934         return result;
1935 }
1936
1937 static NTSTATUS smb_time_fset_dos_attributes(struct vfs_handle_struct *handle,
1938                                         struct files_struct *fsp,
1939                                         uint32_t dosmode)
1940 {
1941         NTSTATUS result;
1942         struct timespec ts1,ts2;
1943         double timediff;
1944
1945         clock_gettime_mono(&ts1);
1946         result = SMB_VFS_NEXT_FSET_DOS_ATTRIBUTES(handle,
1947                                 fsp,
1948                                 dosmode);
1949         clock_gettime_mono(&ts2);
1950         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1951
1952         if (timediff > audit_timeout) {
1953                 smb_time_audit_log_fsp("fset_dos_attributes", timediff, fsp);
1954         }
1955
1956         return result;
1957 }
1958
1959 struct time_audit_offload_read_state {
1960         struct vfs_handle_struct *handle;
1961         struct timespec ts_send;
1962         DATA_BLOB token_blob;
1963 };
1964
1965 static void smb_time_audit_offload_read_done(struct tevent_req *subreq);
1966
1967 static struct tevent_req *smb_time_audit_offload_read_send(
1968         TALLOC_CTX *mem_ctx,
1969         struct tevent_context *ev,
1970         struct vfs_handle_struct *handle,
1971         struct files_struct *fsp,
1972         uint32_t fsctl,
1973         uint32_t ttl,
1974         off_t offset,
1975         size_t to_copy)
1976 {
1977         struct tevent_req *req = NULL;
1978         struct tevent_req *subreq = NULL;
1979         struct time_audit_offload_read_state *state = NULL;
1980
1981         req = tevent_req_create(mem_ctx, &state,
1982                                 struct time_audit_offload_read_state);
1983         if (req == NULL) {
1984                 return NULL;
1985         }
1986         state->handle = handle;
1987         clock_gettime_mono(&state->ts_send);
1988
1989         subreq = SMB_VFS_NEXT_OFFLOAD_READ_SEND(mem_ctx, ev,
1990                                                 handle, fsp,
1991                                                 fsctl, ttl,
1992                                                 offset, to_copy);
1993         if (tevent_req_nomem(subreq, req)) {
1994                 return tevent_req_post(req, ev);
1995         }
1996
1997         tevent_req_set_callback(subreq, smb_time_audit_offload_read_done, req);
1998         return req;
1999 }
2000
2001 static void smb_time_audit_offload_read_done(struct tevent_req *subreq)
2002 {
2003         struct tevent_req *req = tevent_req_callback_data(
2004                 subreq, struct tevent_req);
2005         struct time_audit_offload_read_state *state = tevent_req_data(
2006                 req, struct time_audit_offload_read_state);
2007         NTSTATUS status;
2008
2009         status = SMB_VFS_NEXT_OFFLOAD_READ_RECV(subreq,
2010                                                 state->handle,
2011                                                 state,
2012                                                 &state->token_blob);
2013         TALLOC_FREE(subreq);
2014         if (tevent_req_nterror(req, status)) {
2015                 return;
2016         }
2017         tevent_req_done(req);
2018 }
2019
2020 static NTSTATUS smb_time_audit_offload_read_recv(
2021         struct tevent_req *req,
2022         struct vfs_handle_struct *handle,
2023         TALLOC_CTX *mem_ctx,
2024         DATA_BLOB *token_blob)
2025 {
2026         struct time_audit_offload_read_state *state = tevent_req_data(
2027                 req, struct time_audit_offload_read_state);
2028         struct timespec ts_recv;
2029         double timediff;
2030         NTSTATUS status;
2031
2032         clock_gettime_mono(&ts_recv);
2033         timediff = nsec_time_diff(&ts_recv, &state->ts_send) * 1.0e-9;
2034         if (timediff > audit_timeout) {
2035                 smb_time_audit_log("offload_read", timediff);
2036         }
2037
2038         if (tevent_req_is_nterror(req, &status)) {
2039                 tevent_req_received(req);
2040                 return status;
2041         }
2042
2043         token_blob->length = state->token_blob.length;
2044         token_blob->data = talloc_move(mem_ctx, &state->token_blob.data);
2045
2046         tevent_req_received(req);
2047         return NT_STATUS_OK;
2048 }
2049
2050 struct time_audit_offload_write_state {
2051         struct timespec ts_send;
2052         struct vfs_handle_struct *handle;
2053         off_t copied;
2054 };
2055 static void smb_time_audit_offload_write_done(struct tevent_req *subreq);
2056
2057 static struct tevent_req *smb_time_audit_offload_write_send(struct vfs_handle_struct *handle,
2058                                                          TALLOC_CTX *mem_ctx,
2059                                                          struct tevent_context *ev,
2060                                                          uint32_t fsctl,
2061                                                          DATA_BLOB *token,
2062                                                          off_t transfer_offset,
2063                                                          struct files_struct *dest_fsp,
2064                                                          off_t dest_off,
2065                                                          off_t num)
2066 {
2067         struct tevent_req *req;
2068         struct tevent_req *subreq;
2069         struct time_audit_offload_write_state *state;
2070
2071         req = tevent_req_create(mem_ctx, &state,
2072                                 struct time_audit_offload_write_state);
2073         if (req == NULL) {
2074                 return NULL;
2075         }
2076
2077         state->handle = handle;
2078         clock_gettime_mono(&state->ts_send);
2079         subreq = SMB_VFS_NEXT_OFFLOAD_WRITE_SEND(handle, state, ev,
2080                                               fsctl, token, transfer_offset,
2081                                               dest_fsp, dest_off, num);
2082         if (tevent_req_nomem(subreq, req)) {
2083                 return tevent_req_post(req, ev);
2084         }
2085
2086         tevent_req_set_callback(subreq, smb_time_audit_offload_write_done, req);
2087         return req;
2088 }
2089
2090 static void smb_time_audit_offload_write_done(struct tevent_req *subreq)
2091 {
2092         struct tevent_req *req = tevent_req_callback_data(
2093                 subreq, struct tevent_req);
2094         struct time_audit_offload_write_state *state = tevent_req_data(
2095                 req, struct time_audit_offload_write_state);
2096         NTSTATUS status;
2097
2098         status = SMB_VFS_NEXT_OFFLOAD_WRITE_RECV(state->handle,
2099                                               subreq,
2100                                               &state->copied);
2101         TALLOC_FREE(subreq);
2102         if (tevent_req_nterror(req, status)) {
2103                 return;
2104         }
2105         tevent_req_done(req);
2106 }
2107
2108 static NTSTATUS smb_time_audit_offload_write_recv(struct vfs_handle_struct *handle,
2109                                                struct tevent_req *req,
2110                                                off_t *copied)
2111 {
2112         struct time_audit_offload_write_state *state = tevent_req_data(
2113                 req, struct time_audit_offload_write_state);
2114         struct timespec ts_recv;
2115         double timediff;
2116         NTSTATUS status;
2117
2118         clock_gettime_mono(&ts_recv);
2119         timediff = nsec_time_diff(&ts_recv, &state->ts_send)*1.0e-9;
2120         if (timediff > audit_timeout) {
2121                 smb_time_audit_log("offload_write", timediff);
2122         }
2123
2124         *copied = state->copied;
2125         if (tevent_req_is_nterror(req, &status)) {
2126                 tevent_req_received(req);
2127                 return status;
2128         }
2129
2130         tevent_req_received(req);
2131         return NT_STATUS_OK;
2132 }
2133
2134 static NTSTATUS smb_time_audit_get_compression(vfs_handle_struct *handle,
2135                                                TALLOC_CTX *mem_ctx,
2136                                                struct files_struct *fsp,
2137                                                struct smb_filename *smb_fname,
2138                                                uint16_t *_compression_fmt)
2139 {
2140         NTSTATUS result;
2141         struct timespec ts1,ts2;
2142         double timediff;
2143
2144         clock_gettime_mono(&ts1);
2145         result = SMB_VFS_NEXT_GET_COMPRESSION(handle, mem_ctx, fsp, smb_fname,
2146                                               _compression_fmt);
2147         clock_gettime_mono(&ts2);
2148         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2149
2150         if (timediff > audit_timeout) {
2151                 if (fsp !=  NULL) {
2152                         smb_time_audit_log_fsp("get_compression",
2153                                                timediff, fsp);
2154                 } else {
2155                         smb_time_audit_log_smb_fname("get_compression",
2156                                                      timediff, smb_fname);
2157                 }
2158         }
2159
2160         return result;
2161 }
2162
2163 static NTSTATUS smb_time_audit_set_compression(vfs_handle_struct *handle,
2164                                                TALLOC_CTX *mem_ctx,
2165                                                struct files_struct *fsp,
2166                                                uint16_t compression_fmt)
2167 {
2168         NTSTATUS result;
2169         struct timespec ts1,ts2;
2170         double timediff;
2171
2172         clock_gettime_mono(&ts1);
2173         result = SMB_VFS_NEXT_SET_COMPRESSION(handle, mem_ctx, fsp,
2174                                               compression_fmt);
2175         clock_gettime_mono(&ts2);
2176         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2177
2178         if (timediff > audit_timeout) {
2179                 smb_time_audit_log_fsp("set_compression", timediff, fsp);
2180         }
2181
2182         return result;
2183 }
2184
2185 static NTSTATUS smb_time_audit_readdir_attr(struct vfs_handle_struct *handle,
2186                                             const struct smb_filename *fname,
2187                                             TALLOC_CTX *mem_ctx,
2188                                             struct readdir_attr_data **pattr_data)
2189 {
2190         NTSTATUS status;
2191         struct timespec ts1,ts2;
2192         double timediff;
2193
2194         clock_gettime_mono(&ts1);
2195         status = SMB_VFS_NEXT_READDIR_ATTR(handle, fname, mem_ctx, pattr_data);
2196         clock_gettime_mono(&ts2);
2197         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2198
2199         if (timediff > audit_timeout) {
2200                 smb_time_audit_log_smb_fname("readdir_attr", timediff, fname);
2201         }
2202
2203         return status;
2204 }
2205
2206 static NTSTATUS smb_time_audit_fget_nt_acl(vfs_handle_struct *handle,
2207                                            files_struct *fsp,
2208                                            uint32_t security_info,
2209                                            TALLOC_CTX *mem_ctx,
2210                                            struct security_descriptor **ppdesc)
2211 {
2212         NTSTATUS result;
2213         struct timespec ts1,ts2;
2214         double timediff;
2215
2216         clock_gettime_mono(&ts1);
2217         result = SMB_VFS_NEXT_FGET_NT_ACL(handle, fsp, security_info,
2218                                           mem_ctx, ppdesc);
2219         clock_gettime_mono(&ts2);
2220         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2221
2222         if (timediff > audit_timeout) {
2223                 smb_time_audit_log_fsp("fget_nt_acl", timediff, fsp);
2224         }
2225
2226         return result;
2227 }
2228
2229 static NTSTATUS smb_time_audit_get_nt_acl(vfs_handle_struct *handle,
2230                                           const struct smb_filename *smb_fname,
2231                                           uint32_t security_info,
2232                                           TALLOC_CTX *mem_ctx,
2233                                           struct security_descriptor **ppdesc)
2234 {
2235         NTSTATUS result;
2236         struct timespec ts1,ts2;
2237         double timediff;
2238
2239         clock_gettime_mono(&ts1);
2240         result = SMB_VFS_NEXT_GET_NT_ACL(handle, smb_fname, security_info,
2241                                          mem_ctx, ppdesc);
2242         clock_gettime_mono(&ts2);
2243         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2244
2245         if (timediff > audit_timeout) {
2246                 smb_time_audit_log_fname("get_nt_acl",
2247                         timediff,
2248                         smb_fname->base_name);
2249         }
2250
2251         return result;
2252 }
2253
2254 static NTSTATUS smb_time_audit_fset_nt_acl(vfs_handle_struct *handle,
2255                                            files_struct *fsp,
2256                                            uint32_t security_info_sent,
2257                                            const struct security_descriptor *psd)
2258 {
2259         NTSTATUS result;
2260         struct timespec ts1,ts2;
2261         double timediff;
2262
2263         clock_gettime_mono(&ts1);
2264         result = SMB_VFS_NEXT_FSET_NT_ACL(handle, fsp, security_info_sent,
2265                                           psd);
2266         clock_gettime_mono(&ts2);
2267         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2268
2269         if (timediff > audit_timeout) {
2270                 smb_time_audit_log_fsp("fset_nt_acl", timediff, fsp);
2271         }
2272
2273         return result;
2274 }
2275
2276 static NTSTATUS smb_time_audit_audit_file(struct vfs_handle_struct *handle,
2277                                 struct smb_filename *smb_fname,
2278                                 struct security_acl *sacl,
2279                                 uint32_t access_requested,
2280                                 uint32_t access_denied)
2281 {
2282         NTSTATUS result;
2283         struct timespec ts1,ts2;
2284         double timediff;
2285
2286         clock_gettime_mono(&ts1);
2287         result = SMB_VFS_NEXT_AUDIT_FILE(handle,
2288                                         smb_fname,
2289                                         sacl,
2290                                         access_requested,
2291                                         access_denied);
2292         clock_gettime_mono(&ts2);
2293         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2294
2295         if (timediff > audit_timeout) {
2296                 smb_time_audit_log_fname("audit_file",
2297                         timediff,
2298                         smb_fname->base_name);
2299         }
2300
2301         return result;
2302 }
2303
2304 static SMB_ACL_T smb_time_audit_sys_acl_get_file(vfs_handle_struct *handle,
2305                                         const struct smb_filename *smb_fname,
2306                                         SMB_ACL_TYPE_T type,
2307                                         TALLOC_CTX *mem_ctx)
2308 {
2309         SMB_ACL_T result;
2310         struct timespec ts1,ts2;
2311         double timediff;
2312
2313         clock_gettime_mono(&ts1);
2314         result = SMB_VFS_NEXT_SYS_ACL_GET_FILE(handle, smb_fname,
2315                                 type, mem_ctx);
2316         clock_gettime_mono(&ts2);
2317         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2318
2319         if (timediff > audit_timeout) {
2320                 smb_time_audit_log_fname("sys_acl_get_file", timediff,
2321                         smb_fname->base_name);
2322         }
2323
2324         return result;
2325 }
2326
2327 static SMB_ACL_T smb_time_audit_sys_acl_get_fd(vfs_handle_struct *handle,
2328                                                files_struct *fsp,
2329                                                TALLOC_CTX *mem_ctx)
2330 {
2331         SMB_ACL_T result;
2332         struct timespec ts1,ts2;
2333         double timediff;
2334
2335         clock_gettime_mono(&ts1);
2336         result = SMB_VFS_NEXT_SYS_ACL_GET_FD(handle, fsp, mem_ctx);
2337         clock_gettime_mono(&ts2);
2338         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2339
2340         if (timediff > audit_timeout) {
2341                 smb_time_audit_log_fsp("sys_acl_get_fd", timediff, fsp);
2342         }
2343
2344         return result;
2345 }
2346
2347
2348 static int smb_time_audit_sys_acl_blob_get_file(vfs_handle_struct *handle,
2349                                 const struct smb_filename *smb_fname,
2350                                 TALLOC_CTX *mem_ctx,
2351                                 char **blob_description,
2352                                 DATA_BLOB *blob)
2353 {
2354         int result;
2355         struct timespec ts1,ts2;
2356         double timediff;
2357
2358         clock_gettime_mono(&ts1);
2359         result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FILE(handle, smb_fname,
2360                                 mem_ctx, blob_description, blob);
2361         clock_gettime_mono(&ts2);
2362         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2363
2364         if (timediff > audit_timeout) {
2365                 smb_time_audit_log("sys_acl_blob_get_file", timediff);
2366         }
2367
2368         return result;
2369 }
2370
2371 static int smb_time_audit_sys_acl_blob_get_fd(vfs_handle_struct *handle,
2372                                               files_struct *fsp,
2373                                               TALLOC_CTX *mem_ctx,
2374                                               char **blob_description,
2375                                               DATA_BLOB *blob)
2376 {
2377         int result;
2378         struct timespec ts1,ts2;
2379         double timediff;
2380
2381         clock_gettime_mono(&ts1);
2382         result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FD(handle, fsp, mem_ctx, blob_description, blob);
2383         clock_gettime_mono(&ts2);
2384         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2385
2386         if (timediff > audit_timeout) {
2387                 smb_time_audit_log("sys_acl_blob_get_fd", timediff);
2388         }
2389
2390         return result;
2391 }
2392
2393 static int smb_time_audit_sys_acl_set_file(vfs_handle_struct *handle,
2394                                            const struct smb_filename *smb_fname,
2395                                            SMB_ACL_TYPE_T acltype,
2396                                            SMB_ACL_T theacl)
2397 {
2398         int result;
2399         struct timespec ts1,ts2;
2400         double timediff;
2401
2402         clock_gettime_mono(&ts1);
2403         result = SMB_VFS_NEXT_SYS_ACL_SET_FILE(handle, smb_fname, acltype,
2404                                                theacl);
2405         clock_gettime_mono(&ts2);
2406         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2407
2408         if (timediff > audit_timeout) {
2409                 smb_time_audit_log_fname("sys_acl_set_file", timediff,
2410                         smb_fname->base_name);
2411         }
2412
2413         return result;
2414 }
2415
2416 static int smb_time_audit_sys_acl_set_fd(vfs_handle_struct *handle,
2417                                          files_struct *fsp,
2418                                          SMB_ACL_T theacl)
2419 {
2420         int result;
2421         struct timespec ts1,ts2;
2422         double timediff;
2423
2424         clock_gettime_mono(&ts1);
2425         result = SMB_VFS_NEXT_SYS_ACL_SET_FD(handle, fsp, theacl);
2426         clock_gettime_mono(&ts2);
2427         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2428
2429         if (timediff > audit_timeout) {
2430                 smb_time_audit_log_fsp("sys_acl_set_fd", timediff, fsp);
2431         }
2432
2433         return result;
2434 }
2435
2436 static int smb_time_audit_sys_acl_delete_def_file(vfs_handle_struct *handle,
2437                                 const struct smb_filename *smb_fname)
2438 {
2439         int result;
2440         struct timespec ts1,ts2;
2441         double timediff;
2442
2443         clock_gettime_mono(&ts1);
2444         result = SMB_VFS_NEXT_SYS_ACL_DELETE_DEF_FILE(handle, smb_fname);
2445         clock_gettime_mono(&ts2);
2446         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2447
2448         if (timediff > audit_timeout) {
2449                 smb_time_audit_log_fname("sys_acl_delete_def_file", timediff,
2450                         smb_fname->base_name);
2451         }
2452
2453         return result;
2454 }
2455
2456 static ssize_t smb_time_audit_getxattr(struct vfs_handle_struct *handle,
2457                                 const struct smb_filename *smb_fname,
2458                                 const char *name,
2459                                 void *value,
2460                                 size_t size)
2461 {
2462         ssize_t result;
2463         struct timespec ts1,ts2;
2464         double timediff;
2465
2466         clock_gettime_mono(&ts1);
2467         result = SMB_VFS_NEXT_GETXATTR(handle, smb_fname, name, value, size);
2468         clock_gettime_mono(&ts2);
2469         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2470
2471         if (timediff > audit_timeout) {
2472                 smb_time_audit_log_fname("getxattr", timediff,
2473                         smb_fname->base_name);
2474         }
2475
2476         return result;
2477 }
2478
2479 struct smb_time_audit_getxattrat_state {
2480         struct vfs_aio_state aio_state;
2481         files_struct *dir_fsp;
2482         const struct smb_filename *smb_fname;
2483         const char *xattr_name;
2484         ssize_t xattr_size;
2485         uint8_t *xattr_value;
2486 };
2487
2488 static void smb_time_audit_getxattrat_done(struct tevent_req *subreq);
2489
2490 static struct tevent_req *smb_time_audit_getxattrat_send(
2491                         TALLOC_CTX *mem_ctx,
2492                         struct tevent_context *ev,
2493                         struct vfs_handle_struct *handle,
2494                         files_struct *dir_fsp,
2495                         const struct smb_filename *smb_fname,
2496                         const char *xattr_name,
2497                         size_t alloc_hint)
2498 {
2499         struct tevent_req *req = NULL;
2500         struct tevent_req *subreq = NULL;
2501         struct smb_time_audit_getxattrat_state *state = NULL;
2502
2503         req = tevent_req_create(mem_ctx, &state,
2504                                 struct smb_time_audit_getxattrat_state);
2505         if (req == NULL) {
2506                 return NULL;
2507         }
2508         *state = (struct smb_time_audit_getxattrat_state) {
2509                 .dir_fsp = dir_fsp,
2510                 .smb_fname = smb_fname,
2511                 .xattr_name = xattr_name,
2512         };
2513
2514         subreq = SMB_VFS_NEXT_GETXATTRAT_SEND(state,
2515                                               ev,
2516                                               handle,
2517                                               dir_fsp,
2518                                               smb_fname,
2519                                               xattr_name,
2520                                               alloc_hint);
2521         if (tevent_req_nomem(subreq, req)) {
2522                 return tevent_req_post(req, ev);
2523         }
2524         tevent_req_set_callback(subreq, smb_time_audit_getxattrat_done, req);
2525
2526         return req;
2527 }
2528
2529 static void smb_time_audit_getxattrat_done(struct tevent_req *subreq)
2530 {
2531         struct tevent_req *req = tevent_req_callback_data(
2532                 subreq, struct tevent_req);
2533         struct smb_time_audit_getxattrat_state *state = tevent_req_data(
2534                 req, struct smb_time_audit_getxattrat_state);
2535
2536         state->xattr_size = SMB_VFS_NEXT_GETXATTRAT_RECV(subreq,
2537                                                          &state->aio_state,
2538                                                          state,
2539                                                          &state->xattr_value);
2540         TALLOC_FREE(subreq);
2541         if (state->xattr_size == -1) {
2542                 tevent_req_error(req, state->aio_state.error);
2543                 return;
2544         }
2545
2546         tevent_req_done(req);
2547 }
2548
2549 static ssize_t smb_time_audit_getxattrat_recv(struct tevent_req *req,
2550                                               struct vfs_aio_state *aio_state,
2551                                               TALLOC_CTX *mem_ctx,
2552                                               uint8_t **xattr_value)
2553 {
2554         struct smb_time_audit_getxattrat_state *state = tevent_req_data(
2555                 req, struct smb_time_audit_getxattrat_state);
2556         ssize_t xattr_size;
2557         double timediff;
2558
2559         timediff = state->aio_state.duration * 1.0e-9;
2560
2561         if (timediff > audit_timeout) {
2562                 smb_time_audit_log_at("async getxattrat",
2563                                       timediff,
2564                                       state->dir_fsp,
2565                                       state->smb_fname);
2566         }
2567
2568         if (tevent_req_is_unix_error(req, &aio_state->error)) {
2569                 tevent_req_received(req);
2570                 return -1;
2571         }
2572
2573         *aio_state = state->aio_state;
2574         xattr_size = state->xattr_size;
2575         if (xattr_value != NULL) {
2576                 *xattr_value = talloc_move(mem_ctx, &state->xattr_value);
2577         }
2578
2579         tevent_req_received(req);
2580         return xattr_size;
2581 }
2582
2583 static ssize_t smb_time_audit_fgetxattr(struct vfs_handle_struct *handle,
2584                                         struct files_struct *fsp,
2585                                         const char *name, void *value,
2586                                         size_t size)
2587 {
2588         ssize_t result;
2589         struct timespec ts1,ts2;
2590         double timediff;
2591
2592         clock_gettime_mono(&ts1);
2593         result = SMB_VFS_NEXT_FGETXATTR(handle, fsp, name, value, size);
2594         clock_gettime_mono(&ts2);
2595         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2596
2597         if (timediff > audit_timeout) {
2598                 smb_time_audit_log_fsp("fgetxattr", timediff, fsp);
2599         }
2600
2601         return result;
2602 }
2603
2604 static ssize_t smb_time_audit_listxattr(struct vfs_handle_struct *handle,
2605                                         const struct smb_filename *smb_fname,
2606                                         char *list,
2607                                         size_t size)
2608 {
2609         ssize_t result;
2610         struct timespec ts1,ts2;
2611         double timediff;
2612
2613         clock_gettime_mono(&ts1);
2614         result = SMB_VFS_NEXT_LISTXATTR(handle, smb_fname, list, size);
2615         clock_gettime_mono(&ts2);
2616         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2617
2618         if (timediff > audit_timeout) {
2619                 smb_time_audit_log_fname("listxattr", timediff,
2620                                 smb_fname->base_name);
2621         }
2622
2623         return result;
2624 }
2625
2626 static ssize_t smb_time_audit_flistxattr(struct vfs_handle_struct *handle,
2627                                          struct files_struct *fsp, char *list,
2628                                          size_t size)
2629 {
2630         ssize_t result;
2631         struct timespec ts1,ts2;
2632         double timediff;
2633
2634         clock_gettime_mono(&ts1);
2635         result = SMB_VFS_NEXT_FLISTXATTR(handle, fsp, list, size);
2636         clock_gettime_mono(&ts2);
2637         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2638
2639         if (timediff > audit_timeout) {
2640                 smb_time_audit_log_fsp("flistxattr", timediff, fsp);
2641         }
2642
2643         return result;
2644 }
2645
2646 static int smb_time_audit_removexattr(struct vfs_handle_struct *handle,
2647                                 const struct smb_filename *smb_fname,
2648                                 const char *name)
2649 {
2650         int result;
2651         struct timespec ts1,ts2;
2652         double timediff;
2653
2654         clock_gettime_mono(&ts1);
2655         result = SMB_VFS_NEXT_REMOVEXATTR(handle, smb_fname, name);
2656         clock_gettime_mono(&ts2);
2657         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2658
2659         if (timediff > audit_timeout) {
2660                 smb_time_audit_log_fname("removexattr", timediff,
2661                         smb_fname->base_name);
2662         }
2663
2664         return result;
2665 }
2666
2667 static int smb_time_audit_fremovexattr(struct vfs_handle_struct *handle,
2668                                        struct files_struct *fsp,
2669                                        const char *name)
2670 {
2671         int result;
2672         struct timespec ts1,ts2;
2673         double timediff;
2674
2675         clock_gettime_mono(&ts1);
2676         result = SMB_VFS_NEXT_FREMOVEXATTR(handle, fsp, name);
2677         clock_gettime_mono(&ts2);
2678         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2679
2680         if (timediff > audit_timeout) {
2681                 smb_time_audit_log_fsp("fremovexattr", timediff, fsp);
2682         }
2683
2684         return result;
2685 }
2686
2687 static int smb_time_audit_setxattr(struct vfs_handle_struct *handle,
2688                                 const struct smb_filename *smb_fname,
2689                                 const char *name,
2690                                 const void *value,
2691                                 size_t size,
2692                                 int flags)
2693 {
2694         int result;
2695         struct timespec ts1,ts2;
2696         double timediff;
2697
2698         clock_gettime_mono(&ts1);
2699         result = SMB_VFS_NEXT_SETXATTR(handle, smb_fname, name, value, size,
2700                                        flags);
2701         clock_gettime_mono(&ts2);
2702         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2703
2704         if (timediff > audit_timeout) {
2705                 smb_time_audit_log_fname("setxattr", timediff,
2706                                 smb_fname->base_name);
2707         }
2708
2709         return result;
2710 }
2711
2712 static int smb_time_audit_fsetxattr(struct vfs_handle_struct *handle,
2713                                     struct files_struct *fsp, const char *name,
2714                                     const void *value, size_t size, int flags)
2715 {
2716         int result;
2717         struct timespec ts1,ts2;
2718         double timediff;
2719
2720         clock_gettime_mono(&ts1);
2721         result = SMB_VFS_NEXT_FSETXATTR(handle, fsp, name, value, size, flags);
2722         clock_gettime_mono(&ts2);
2723         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2724
2725         if (timediff > audit_timeout) {
2726                 smb_time_audit_log_fsp("fsetxattr", timediff, fsp);
2727         }
2728
2729         return result;
2730 }
2731
2732 static bool smb_time_audit_aio_force(struct vfs_handle_struct *handle,
2733                                      struct files_struct *fsp)
2734 {
2735         bool result;
2736         struct timespec ts1,ts2;
2737         double timediff;
2738
2739         clock_gettime_mono(&ts1);
2740         result = SMB_VFS_NEXT_AIO_FORCE(handle, fsp);
2741         clock_gettime_mono(&ts2);
2742         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2743
2744         if (timediff > audit_timeout) {
2745                 smb_time_audit_log_fsp("aio_force", timediff, fsp);
2746         }
2747
2748         return result;
2749 }
2750
2751 static NTSTATUS smb_time_audit_durable_cookie(struct vfs_handle_struct *handle,
2752                                               struct files_struct *fsp,
2753                                               TALLOC_CTX *mem_ctx,
2754                                               DATA_BLOB *cookie)
2755 {
2756         NTSTATUS result;
2757         struct timespec ts1,ts2;
2758         double timediff;
2759
2760         clock_gettime_mono(&ts1);
2761         result = SMB_VFS_NEXT_DURABLE_COOKIE(handle, fsp, mem_ctx, cookie);
2762         clock_gettime_mono(&ts2);
2763         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2764
2765         if (timediff > audit_timeout) {
2766                 smb_time_audit_log_fsp("durable_cookie", timediff, fsp);
2767         }
2768
2769         return result;
2770 }
2771
2772 static NTSTATUS smb_time_audit_durable_disconnect(struct vfs_handle_struct *handle,
2773                                                   struct files_struct *fsp,
2774                                                   const DATA_BLOB old_cookie,
2775                                                   TALLOC_CTX *mem_ctx,
2776                                                   DATA_BLOB *new_cookie)
2777 {
2778         NTSTATUS result;
2779         struct timespec ts1,ts2;
2780         double timediff;
2781
2782         clock_gettime_mono(&ts1);
2783         result = SMB_VFS_NEXT_DURABLE_DISCONNECT(handle, fsp, old_cookie,
2784                                                  mem_ctx, new_cookie);
2785         clock_gettime_mono(&ts2);
2786         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2787
2788         if (timediff > audit_timeout) {
2789                 smb_time_audit_log_fsp("durable_disconnect", timediff, fsp);
2790         }
2791
2792         return result;
2793 }
2794
2795 static NTSTATUS smb_time_audit_durable_reconnect(struct vfs_handle_struct *handle,
2796                                                  struct smb_request *smb1req,
2797                                                  struct smbXsrv_open *op,
2798                                                  const DATA_BLOB old_cookie,
2799                                                  TALLOC_CTX *mem_ctx,
2800                                                  struct files_struct **fsp,
2801                                                  DATA_BLOB *new_cookie)
2802 {
2803         NTSTATUS result;
2804         struct timespec ts1,ts2;
2805         double timediff;
2806
2807         clock_gettime_mono(&ts1);
2808         result = SMB_VFS_NEXT_DURABLE_RECONNECT(handle, smb1req, op, old_cookie,
2809                                                 mem_ctx, fsp, new_cookie);
2810         clock_gettime_mono(&ts2);
2811         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2812
2813         if (timediff > audit_timeout) {
2814                 smb_time_audit_log("durable_reconnect", timediff);
2815         }
2816
2817         return result;
2818 }
2819
2820 /* VFS operations */
2821
2822 static struct vfs_fn_pointers vfs_time_audit_fns = {
2823         .connect_fn = smb_time_audit_connect,
2824         .disconnect_fn = smb_time_audit_disconnect,
2825         .disk_free_fn = smb_time_audit_disk_free,
2826         .get_quota_fn = smb_time_audit_get_quota,
2827         .set_quota_fn = smb_time_audit_set_quota,
2828         .get_shadow_copy_data_fn = smb_time_audit_get_shadow_copy_data,
2829         .statvfs_fn = smb_time_audit_statvfs,
2830         .fs_capabilities_fn = smb_time_audit_fs_capabilities,
2831         .get_dfs_referrals_fn = smb_time_audit_get_dfs_referrals,
2832         .opendir_fn = smb_time_audit_opendir,
2833         .fdopendir_fn = smb_time_audit_fdopendir,
2834         .readdir_fn = smb_time_audit_readdir,
2835         .seekdir_fn = smb_time_audit_seekdir,
2836         .telldir_fn = smb_time_audit_telldir,
2837         .rewind_dir_fn = smb_time_audit_rewinddir,
2838         .mkdir_fn = smb_time_audit_mkdir,
2839         .rmdir_fn = smb_time_audit_rmdir,
2840         .closedir_fn = smb_time_audit_closedir,
2841         .open_fn = smb_time_audit_open,
2842         .create_file_fn = smb_time_audit_create_file,
2843         .close_fn = smb_time_audit_close,
2844         .pread_fn = smb_time_audit_pread,
2845         .pread_send_fn = smb_time_audit_pread_send,
2846         .pread_recv_fn = smb_time_audit_pread_recv,
2847         .pwrite_fn = smb_time_audit_pwrite,
2848         .pwrite_send_fn = smb_time_audit_pwrite_send,
2849         .pwrite_recv_fn = smb_time_audit_pwrite_recv,
2850         .lseek_fn = smb_time_audit_lseek,
2851         .sendfile_fn = smb_time_audit_sendfile,
2852         .recvfile_fn = smb_time_audit_recvfile,
2853         .renameat_fn = smb_time_audit_renameat,
2854         .fsync_send_fn = smb_time_audit_fsync_send,
2855         .fsync_recv_fn = smb_time_audit_fsync_recv,
2856         .stat_fn = smb_time_audit_stat,
2857         .fstat_fn = smb_time_audit_fstat,
2858         .lstat_fn = smb_time_audit_lstat,
2859         .get_alloc_size_fn = smb_time_audit_get_alloc_size,
2860         .unlink_fn = smb_time_audit_unlink,
2861         .chmod_fn = smb_time_audit_chmod,
2862         .fchmod_fn = smb_time_audit_fchmod,
2863         .chown_fn = smb_time_audit_chown,
2864         .fchown_fn = smb_time_audit_fchown,
2865         .lchown_fn = smb_time_audit_lchown,
2866         .chdir_fn = smb_time_audit_chdir,
2867         .getwd_fn = smb_time_audit_getwd,
2868         .ntimes_fn = smb_time_audit_ntimes,
2869         .ftruncate_fn = smb_time_audit_ftruncate,
2870         .fallocate_fn = smb_time_audit_fallocate,
2871         .lock_fn = smb_time_audit_lock,
2872         .kernel_flock_fn = smb_time_audit_kernel_flock,
2873         .linux_setlease_fn = smb_time_audit_linux_setlease,
2874         .getlock_fn = smb_time_audit_getlock,
2875         .symlink_fn = smb_time_audit_symlink,
2876         .readlink_fn = smb_time_audit_readlink,
2877         .linkat_fn = smb_time_audit_linkat,
2878         .mknod_fn = smb_time_audit_mknod,
2879         .mknodat_fn = smb_time_audit_mknodat,
2880         .realpath_fn = smb_time_audit_realpath,
2881         .chflags_fn = smb_time_audit_chflags,
2882         .file_id_create_fn = smb_time_audit_file_id_create,
2883         .fs_file_id_fn = smb_time_audit_fs_file_id,
2884         .offload_read_send_fn = smb_time_audit_offload_read_send,
2885         .offload_read_recv_fn = smb_time_audit_offload_read_recv,
2886         .offload_write_send_fn = smb_time_audit_offload_write_send,
2887         .offload_write_recv_fn = smb_time_audit_offload_write_recv,
2888         .get_compression_fn = smb_time_audit_get_compression,
2889         .set_compression_fn = smb_time_audit_set_compression,
2890         .snap_check_path_fn = smb_time_audit_snap_check_path,
2891         .snap_create_fn = smb_time_audit_snap_create,
2892         .snap_delete_fn = smb_time_audit_snap_delete,
2893         .streaminfo_fn = smb_time_audit_streaminfo,
2894         .get_real_filename_fn = smb_time_audit_get_real_filename,
2895         .connectpath_fn = smb_time_audit_connectpath,
2896         .brl_lock_windows_fn = smb_time_audit_brl_lock_windows,
2897         .brl_unlock_windows_fn = smb_time_audit_brl_unlock_windows,
2898         .strict_lock_check_fn = smb_time_audit_strict_lock_check,
2899         .translate_name_fn = smb_time_audit_translate_name,
2900         .fsctl_fn = smb_time_audit_fsctl,
2901         .get_dos_attributes_fn = smb_time_get_dos_attributes,
2902         .get_dos_attributes_send_fn = smb_time_audit_get_dos_attributes_send,
2903         .get_dos_attributes_recv_fn = smb_time_audit_get_dos_attributes_recv,
2904         .fget_dos_attributes_fn = smb_time_fget_dos_attributes,
2905         .set_dos_attributes_fn = smb_time_set_dos_attributes,
2906         .fset_dos_attributes_fn = smb_time_fset_dos_attributes,
2907         .fget_nt_acl_fn = smb_time_audit_fget_nt_acl,
2908         .get_nt_acl_fn = smb_time_audit_get_nt_acl,
2909         .fset_nt_acl_fn = smb_time_audit_fset_nt_acl,
2910         .audit_file_fn = smb_time_audit_audit_file,
2911         .sys_acl_get_file_fn = smb_time_audit_sys_acl_get_file,
2912         .sys_acl_get_fd_fn = smb_time_audit_sys_acl_get_fd,
2913         .sys_acl_blob_get_file_fn = smb_time_audit_sys_acl_blob_get_file,
2914         .sys_acl_blob_get_fd_fn = smb_time_audit_sys_acl_blob_get_fd,
2915         .sys_acl_set_file_fn = smb_time_audit_sys_acl_set_file,
2916         .sys_acl_set_fd_fn = smb_time_audit_sys_acl_set_fd,
2917         .sys_acl_delete_def_file_fn = smb_time_audit_sys_acl_delete_def_file,
2918         .getxattr_fn = smb_time_audit_getxattr,
2919         .getxattrat_send_fn = smb_time_audit_getxattrat_send,
2920         .getxattrat_recv_fn = smb_time_audit_getxattrat_recv,
2921         .fgetxattr_fn = smb_time_audit_fgetxattr,
2922         .listxattr_fn = smb_time_audit_listxattr,
2923         .flistxattr_fn = smb_time_audit_flistxattr,
2924         .removexattr_fn = smb_time_audit_removexattr,
2925         .fremovexattr_fn = smb_time_audit_fremovexattr,
2926         .setxattr_fn = smb_time_audit_setxattr,
2927         .fsetxattr_fn = smb_time_audit_fsetxattr,
2928         .aio_force_fn = smb_time_audit_aio_force,
2929         .durable_cookie_fn = smb_time_audit_durable_cookie,
2930         .durable_disconnect_fn = smb_time_audit_durable_disconnect,
2931         .durable_reconnect_fn = smb_time_audit_durable_reconnect,
2932         .readdir_attr_fn = smb_time_audit_readdir_attr,
2933 };
2934
2935
2936 static_decl_vfs;
2937 NTSTATUS vfs_time_audit_init(TALLOC_CTX *ctx)
2938 {
2939         smb_vfs_assert_all_fns(&vfs_time_audit_fns, "time_audit");
2940
2941         audit_timeout = (double)lp_parm_int(-1, "time_audit", "timeout",
2942                                             10000) / 1000.0;
2943         return smb_register_vfs(SMB_VFS_INTERFACE_VERSION, "time_audit",
2944                                 &vfs_time_audit_fns);
2945 }