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