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