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