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