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_fname(const char *syscallname, double elapsed,
  90                                     const char *fname)
  91 {
  92         char cwd[PATH_MAX];
  93         char *msg = NULL;
  94
  95         if (getcwd(cwd, sizeof(cwd)) == NULL) {
  96                 snprintf(cwd, sizeof(cwd), "<getcwd() error %d>", errno);
  97         }
  98         if (fname != NULL) {
  99                 msg = talloc_asprintf(talloc_tos(),
 100                                       "cwd = \"%s\", filename = \"%s\"",
 101                                       cwd, fname);
 102         } else {
 103                 msg = talloc_asprintf(talloc_tos(),
 104                                       "cwd = \"%s\", filename = <NULL>",
 105                                       cwd);
 106         }
 107         smb_time_audit_log_msg(syscallname, elapsed, msg);
 108         TALLOC_FREE(msg);
 109 }
 110
 111 static void smb_time_audit_log_smb_fname(const char *syscallname, double elapsed,
 112                                        const struct smb_filename *smb_fname)
 113 {
 114         if (smb_fname != NULL) {
 115                 smb_time_audit_log_fname(syscallname, elapsed,
 116                                          smb_fname->base_name);
 117         } else {
 118                 smb_time_audit_log_fname(syscallname, elapsed,
 119                                          "smb_fname = <NULL>");
 120         }
 121 }
 122
 123 static int smb_time_audit_connect(vfs_handle_struct *handle,
 124                                   const char *svc, const char *user)
 125 {
 126         int result;
 127         struct timespec ts1,ts2;
 128         double timediff;
 129
 130         if (!handle) {
 131                 return -1;
 132         }
 133
 134         clock_gettime_mono(&ts1);
 135         result = SMB_VFS_NEXT_CONNECT(handle, svc, user);
 136         clock_gettime_mono(&ts2);
 137         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 138         if (timediff > audit_timeout) {
 139                 smb_time_audit_log_msg("connect", timediff, user);
 140         }
 141         return result;
 142 }
 143
 144 static void smb_time_audit_disconnect(vfs_handle_struct *handle)
 145 {
 146         struct timespec ts1,ts2;
 147         double timediff;
 148
 149         clock_gettime_mono(&ts1);
 150         SMB_VFS_NEXT_DISCONNECT(handle);
 151         clock_gettime_mono(&ts2);
 152         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 153
 154         if (timediff > audit_timeout) {
 155                 smb_time_audit_log("disconnect", timediff);
 156         }
 157 }
 158
 159 static uint64_t smb_time_audit_disk_free(vfs_handle_struct *handle,
 160                                          const char *path, uint64_t *bsize,
 161                                          uint64_t *dfree, uint64_t *dsize)
 162 {
 163         uint64_t result;
 164         struct timespec ts1,ts2;
 165         double timediff;
 166
 167         clock_gettime_mono(&ts1);
 168         result = SMB_VFS_NEXT_DISK_FREE(handle, path, bsize, dfree, dsize);
 169         clock_gettime_mono(&ts2);
 170         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 171
 172         /* Don't have a reasonable notion of failure here */
 173         if (timediff > audit_timeout) {
 174                 smb_time_audit_log_fname("disk_free", timediff, path);
 175         }
 176
 177         return result;
 178 }
 179
 180 static int smb_time_audit_get_quota(struct vfs_handle_struct *handle,
 181                                     const char *path, enum SMB_QUOTA_TYPE qtype,
 182                                     unid_t id, SMB_DISK_QUOTA *qt)
 183 {
 184         int result;
 185         struct timespec ts1,ts2;
 186         double timediff;
 187
 188         clock_gettime_mono(&ts1);
 189         result = SMB_VFS_NEXT_GET_QUOTA(handle, path, qtype, id, qt);
 190         clock_gettime_mono(&ts2);
 191         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 192
 193         if (timediff > audit_timeout) {
 194                 smb_time_audit_log("get_quota", timediff);
 195         }
 196         return result;
 197 }
 198
 199 static int smb_time_audit_set_quota(struct vfs_handle_struct *handle,
 200                                     enum SMB_QUOTA_TYPE qtype, unid_t id,
 201                                     SMB_DISK_QUOTA *qt)
 202 {
 203         int result;
 204         struct timespec ts1,ts2;
 205         double timediff;
 206
 207         clock_gettime_mono(&ts1);
 208         result = SMB_VFS_NEXT_SET_QUOTA(handle, qtype, id, qt);
 209         clock_gettime_mono(&ts2);
 210         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 211
 212         if (timediff > audit_timeout) {
 213                 smb_time_audit_log("set_quota", timediff);
 214         }
 215
 216         return result;
 217 }
 218
 219 static int smb_time_audit_get_shadow_copy_data(struct vfs_handle_struct *handle,
 220                                                struct files_struct *fsp,
 221                                                struct shadow_copy_data *shadow_copy_data,
 222                                                bool labels)
 223 {
 224         int result;
 225         struct timespec ts1,ts2;
 226         double timediff;
 227
 228         clock_gettime_mono(&ts1);
 229         result = SMB_VFS_NEXT_GET_SHADOW_COPY_DATA(handle, fsp,
 230                                                    shadow_copy_data, labels);
 231         clock_gettime_mono(&ts2);
 232         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 233
 234         if (timediff > audit_timeout) {
 235                 smb_time_audit_log_fsp("get_shadow_copy_data", timediff, fsp);
 236         }
 237
 238         return result;
 239 }
 240
 241 static int smb_time_audit_statvfs(struct vfs_handle_struct *handle,
 242                                   const char *path,
 243                                   struct vfs_statvfs_struct *statbuf)
 244 {
 245         int result;
 246         struct timespec ts1,ts2;
 247         double timediff;
 248
 249         clock_gettime_mono(&ts1);
 250         result = SMB_VFS_NEXT_STATVFS(handle, path, statbuf);
 251         clock_gettime_mono(&ts2);
 252         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 253
 254         if (timediff > audit_timeout) {
 255                 smb_time_audit_log_fname("statvfs", timediff, path);
 256         }
 257
 258         return result;
 259 }
 260
 261 static uint32_t smb_time_audit_fs_capabilities(struct vfs_handle_struct *handle,
 262                                                enum timestamp_set_resolution *p_ts_res)
 263 {
 264         uint32_t result;
 265         struct timespec ts1,ts2;
 266         double timediff;
 267
 268         clock_gettime_mono(&ts1);
 269         result = SMB_VFS_NEXT_FS_CAPABILITIES(handle, p_ts_res);
 270         clock_gettime_mono(&ts2);
 271         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 272
 273         if (timediff > audit_timeout) {
 274                 smb_time_audit_log("fs_capabilities", timediff);
 275         }
 276
 277         return result;
 278 }
 279
 280 static NTSTATUS smb_time_audit_snap_check_path(struct vfs_handle_struct *handle,
 281                                                TALLOC_CTX *mem_ctx,
 282                                                const char *service_path,
 283                                                char **base_volume)
 284 {
 285         NTSTATUS status;
 286         struct timespec ts1,ts2;
 287         double timediff;
 288
 289         clock_gettime_mono(&ts1);
 290         status = SMB_VFS_NEXT_SNAP_CHECK_PATH(handle, mem_ctx, service_path,
 291                                               base_volume);
 292         clock_gettime_mono(&ts2);
 293         timediff = nsec_time_diff(&ts2, &ts1) * 1.0e-9;
 294
 295         if (timediff > audit_timeout) {
 296                 smb_time_audit_log("snap_check_path", timediff);
 297         }
 298
 299         return status;
 300 }
 301
 302 static NTSTATUS smb_time_audit_snap_create(struct vfs_handle_struct *handle,
 303                                            TALLOC_CTX *mem_ctx,
 304                                            const char *base_volume,
 305                                            time_t *tstamp,
 306                                            bool rw,
 307                                            char **base_path,
 308                                            char **snap_path)
 309 {
 310         NTSTATUS status;
 311         struct timespec ts1,ts2;
 312         double timediff;
 313
 314         clock_gettime_mono(&ts1);
 315         status = SMB_VFS_NEXT_SNAP_CREATE(handle, mem_ctx, base_volume, tstamp,
 316                                           rw, base_path, snap_path);
 317         clock_gettime_mono(&ts2);
 318         timediff = nsec_time_diff(&ts2 ,&ts1) * 1.0e-9;
 319
 320         if (timediff > audit_timeout) {
 321                 smb_time_audit_log("snap_create", timediff);
 322         }
 323
 324         return status;
 325 }
 326
 327 static NTSTATUS smb_time_audit_snap_delete(struct vfs_handle_struct *handle,
 328                                            TALLOC_CTX *mem_ctx,
 329                                            char *base_path,
 330                                            char *snap_path)
 331 {
 332         NTSTATUS status;
 333         struct timespec ts1,ts2;
 334         double timediff;
 335
 336         clock_gettime_mono(&ts1);
 337         status = SMB_VFS_NEXT_SNAP_DELETE(handle, mem_ctx, base_path,
 338                                           snap_path);
 339         clock_gettime_mono(&ts2);
 340         timediff = nsec_time_diff(&ts2, &ts1) * 1.0e-9;
 341
 342         if (timediff > audit_timeout) {
 343                 smb_time_audit_log("snap_delete", timediff);
 344         }
 345
 346         return status;
 347 }
 348
 349 static DIR *smb_time_audit_opendir(vfs_handle_struct *handle,
 350                                    const struct smb_filename *smb_fname,
 351                                    const char *mask, uint32_t attr)
 352 {
 353         DIR *result;
 354         struct timespec ts1,ts2;
 355         double timediff;
 356
 357         clock_gettime_mono(&ts1);
 358         result = SMB_VFS_NEXT_OPENDIR(handle, smb_fname, mask, attr);
 359         clock_gettime_mono(&ts2);
 360         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 361
 362         if (timediff > audit_timeout) {
 363                 smb_time_audit_log_smb_fname("opendir", timediff, smb_fname);
 364         }
 365
 366         return result;
 367 }
 368
 369 static DIR *smb_time_audit_fdopendir(vfs_handle_struct *handle,
 370                                               files_struct *fsp,
 371                                               const char *mask, uint32_t attr)
 372 {
 373         DIR *result;
 374         struct timespec ts1,ts2;
 375         double timediff;
 376
 377         clock_gettime_mono(&ts1);
 378         result = SMB_VFS_NEXT_FDOPENDIR(handle, fsp, mask, attr);
 379         clock_gettime_mono(&ts2);
 380         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 381
 382         if (timediff > audit_timeout) {
 383                 smb_time_audit_log_fsp("fdopendir", timediff, fsp);
 384         }
 385
 386         return result;
 387 }
 388
 389 static struct dirent *smb_time_audit_readdir(vfs_handle_struct *handle,
 390                                                  DIR *dirp,
 391                                                  SMB_STRUCT_STAT *sbuf)
 392 {
 393         struct dirent *result;
 394         struct timespec ts1,ts2;
 395         double timediff;
 396
 397         clock_gettime_mono(&ts1);
 398         result = SMB_VFS_NEXT_READDIR(handle, dirp, sbuf);
 399         clock_gettime_mono(&ts2);
 400         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 401
 402         if (timediff > audit_timeout) {
 403                 smb_time_audit_log("readdir", timediff);
 404         }
 405
 406         return result;
 407 }
 408
 409 static void smb_time_audit_seekdir(vfs_handle_struct *handle,
 410                                    DIR *dirp, long offset)
 411 {
 412         struct timespec ts1,ts2;
 413         double timediff;
 414
 415         clock_gettime_mono(&ts1);
 416         SMB_VFS_NEXT_SEEKDIR(handle, dirp, offset);
 417         clock_gettime_mono(&ts2);
 418         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 419
 420         if (timediff > audit_timeout) {
 421                 smb_time_audit_log("seekdir", timediff);
 422         }
 423
 424 }
 425
 426 static long smb_time_audit_telldir(vfs_handle_struct *handle,
 427                                    DIR *dirp)
 428 {
 429         long result;
 430         struct timespec ts1,ts2;
 431         double timediff;
 432
 433         clock_gettime_mono(&ts1);
 434         result = SMB_VFS_NEXT_TELLDIR(handle, dirp);
 435         clock_gettime_mono(&ts2);
 436         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 437
 438         if (timediff > audit_timeout) {
 439                 smb_time_audit_log("telldir", timediff);
 440         }
 441
 442         return result;
 443 }
 444
 445 static void smb_time_audit_rewinddir(vfs_handle_struct *handle,
 446                                      DIR *dirp)
 447 {
 448         struct timespec ts1,ts2;
 449         double timediff;
 450
 451         clock_gettime_mono(&ts1);
 452         SMB_VFS_NEXT_REWINDDIR(handle, dirp);
 453         clock_gettime_mono(&ts2);
 454         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 455
 456         if (timediff > audit_timeout) {
 457                 smb_time_audit_log("rewinddir", timediff);
 458         }
 459
 460 }
 461
 462 static int smb_time_audit_mkdir(vfs_handle_struct *handle,
 463                                 const struct smb_filename *smb_fname,
 464                                 mode_t mode)
 465 {
 466         int result;
 467         struct timespec ts1,ts2;
 468         double timediff;
 469
 470         clock_gettime_mono(&ts1);
 471         result = SMB_VFS_NEXT_MKDIR(handle, smb_fname, mode);
 472         clock_gettime_mono(&ts2);
 473         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 474
 475         if (timediff > audit_timeout) {
 476                 smb_time_audit_log_smb_fname("mkdir",
 477                         timediff,
 478                         smb_fname);
 479         }
 480
 481         return result;
 482 }
 483
 484 static int smb_time_audit_rmdir(vfs_handle_struct *handle,
 485                                 const struct smb_filename *smb_fname)
 486 {
 487         int result;
 488         struct timespec ts1,ts2;
 489         double timediff;
 490
 491         clock_gettime_mono(&ts1);
 492         result = SMB_VFS_NEXT_RMDIR(handle, smb_fname);
 493         clock_gettime_mono(&ts2);
 494         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 495
 496         if (timediff > audit_timeout) {
 497                 smb_time_audit_log_smb_fname("rmdir",
 498                         timediff,
 499                         smb_fname);
 500         }
 501
 502         return result;
 503 }
 504
 505 static int smb_time_audit_closedir(vfs_handle_struct *handle,
 506                                    DIR *dirp)
 507 {
 508         int result;
 509         struct timespec ts1,ts2;
 510         double timediff;
 511
 512         clock_gettime_mono(&ts1);
 513         result = SMB_VFS_NEXT_CLOSEDIR(handle, dirp);
 514         clock_gettime_mono(&ts2);
 515         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 516
 517         if (timediff > audit_timeout) {
 518                 smb_time_audit_log("closedir", timediff);
 519         }
 520
 521         return result;
 522 }
 523
 524 static void smb_time_audit_init_search_op(vfs_handle_struct *handle,
 525                                           DIR *dirp)
 526 {
 527         struct timespec ts1,ts2;
 528         double timediff;
 529
 530         clock_gettime_mono(&ts1);
 531         SMB_VFS_NEXT_INIT_SEARCH_OP(handle, dirp);
 532         clock_gettime_mono(&ts2);
 533         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 534
 535         if (timediff > audit_timeout) {
 536                 smb_time_audit_log("init_search_op", timediff);
 537         }
 538 }
 539
 540 static int smb_time_audit_open(vfs_handle_struct *handle,
 541                                struct smb_filename *fname,
 542                                files_struct *fsp,
 543                                int flags, mode_t mode)
 544 {
 545         int result;
 546         struct timespec ts1,ts2;
 547         double timediff;
 548
 549         clock_gettime_mono(&ts1);
 550         result = SMB_VFS_NEXT_OPEN(handle, fname, fsp, flags, mode);
 551         clock_gettime_mono(&ts2);
 552         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 553
 554         if (timediff > audit_timeout) {
 555                 smb_time_audit_log_fsp("open", timediff, fsp);
 556         }
 557
 558         return result;
 559 }
 560
 561 static NTSTATUS smb_time_audit_create_file(vfs_handle_struct *handle,
 562                                            struct smb_request *req,
 563                                            uint16_t root_dir_fid,
 564                                            struct smb_filename *fname,
 565                                            uint32_t access_mask,
 566                                            uint32_t share_access,
 567                                            uint32_t create_disposition,
 568                                            uint32_t create_options,
 569                                            uint32_t file_attributes,
 570                                            uint32_t oplock_request,
 571                                            struct smb2_lease *lease,
 572                                            uint64_t allocation_size,
 573                                            uint32_t private_flags,
 574                                            struct security_descriptor *sd,
 575                                            struct ea_list *ea_list,
 576                                            files_struct **result_fsp,
 577                                            int *pinfo,
 578                                            const struct smb2_create_blobs *in_context_blobs,
 579                                            struct smb2_create_blobs *out_context_blobs)
 580 {
 581         NTSTATUS result;
 582         struct timespec ts1,ts2;
 583         double timediff;
 584
 585         clock_gettime_mono(&ts1);
 586         result = SMB_VFS_NEXT_CREATE_FILE(
 587                 handle,                                 /* handle */
 588                 req,                                    /* req */
 589                 root_dir_fid,                           /* root_dir_fid */
 590                 fname,                                  /* fname */
 591                 access_mask,                            /* access_mask */
 592                 share_access,                           /* share_access */
 593                 create_disposition,                     /* create_disposition*/
 594                 create_options,                         /* create_options */
 595                 file_attributes,                        /* file_attributes */
 596                 oplock_request,                         /* oplock_request */
 597                 lease,                                  /* lease */
 598                 allocation_size,                        /* allocation_size */
 599                 private_flags,
 600                 sd,                                     /* sd */
 601                 ea_list,                                /* ea_list */
 602                 result_fsp,                             /* result */
 603                 pinfo,
 604                 in_context_blobs, out_context_blobs);   /* create context */
 605         clock_gettime_mono(&ts2);
 606         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 607
 608         if (timediff > audit_timeout) {
 609                 /*
 610                  * can't use result_fsp this time, may have
 611                  * invalid content causing smbd crash
 612                  */
 613                 smb_time_audit_log_smb_fname("create_file", timediff,
 614                                            fname);
 615         }
 616
 617         return result;
 618 }
 619
 620 static int smb_time_audit_close(vfs_handle_struct *handle, files_struct *fsp)
 621 {
 622         int result;
 623         struct timespec ts1,ts2;
 624         double timediff;
 625
 626         clock_gettime_mono(&ts1);
 627         result = SMB_VFS_NEXT_CLOSE(handle, fsp);
 628         clock_gettime_mono(&ts2);
 629         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 630
 631         if (timediff > audit_timeout) {
 632                 smb_time_audit_log_fsp("close", timediff, fsp);
 633         }
 634
 635         return result;
 636 }
 637
 638 static ssize_t smb_time_audit_read(vfs_handle_struct *handle,
 639                                    files_struct *fsp, void *data, size_t n)
 640 {
 641         ssize_t result;
 642         struct timespec ts1,ts2;
 643         double timediff;
 644
 645         clock_gettime_mono(&ts1);
 646         result = SMB_VFS_NEXT_READ(handle, fsp, data, n);
 647         clock_gettime_mono(&ts2);
 648         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 649
 650         if (timediff > audit_timeout) {
 651                 smb_time_audit_log_fsp("read", timediff, fsp);
 652         }
 653
 654         return result;
 655 }
 656
 657 static ssize_t smb_time_audit_pread(vfs_handle_struct *handle,
 658                                     files_struct *fsp,
 659                                     void *data, size_t n, off_t offset)
 660 {
 661         ssize_t result;
 662         struct timespec ts1,ts2;
 663         double timediff;
 664
 665         clock_gettime_mono(&ts1);
 666         result = SMB_VFS_NEXT_PREAD(handle, fsp, data, n, offset);
 667         clock_gettime_mono(&ts2);
 668         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 669
 670         if (timediff > audit_timeout) {
 671                 smb_time_audit_log_fsp("pread", timediff, fsp);
 672         }
 673
 674         return result;
 675 }
 676
 677 struct smb_time_audit_pread_state {
 678         struct files_struct *fsp;
 679         ssize_t ret;
 680         struct vfs_aio_state vfs_aio_state;
 681 };
 682
 683 static void smb_time_audit_pread_done(struct tevent_req *subreq);
 684
 685 static struct tevent_req *smb_time_audit_pread_send(
 686         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 687         struct tevent_context *ev, struct files_struct *fsp,
 688         void *data, size_t n, off_t offset)
 689 {
 690         struct tevent_req *req, *subreq;
 691         struct smb_time_audit_pread_state *state;
 692
 693         req = tevent_req_create(mem_ctx, &state,
 694                                 struct smb_time_audit_pread_state);
 695         if (req == NULL) {
 696                 return NULL;
 697         }
 698         state->fsp = fsp;
 699
 700         subreq = SMB_VFS_NEXT_PREAD_SEND(state, ev, handle, fsp, data,
 701                                          n, offset);
 702         if (tevent_req_nomem(subreq, req)) {
 703                 return tevent_req_post(req, ev);
 704         }
 705         tevent_req_set_callback(subreq, smb_time_audit_pread_done, req);
 706         return req;
 707 }
 708
 709 static void smb_time_audit_pread_done(struct tevent_req *subreq)
 710 {
 711         struct tevent_req *req = tevent_req_callback_data(
 712                 subreq, struct tevent_req);
 713         struct smb_time_audit_pread_state *state = tevent_req_data(
 714                 req, struct smb_time_audit_pread_state);
 715
 716         state->ret = SMB_VFS_PREAD_RECV(subreq, &state->vfs_aio_state);
 717         TALLOC_FREE(subreq);
 718         tevent_req_done(req);
 719 }
 720
 721 static ssize_t smb_time_audit_pread_recv(struct tevent_req *req,
 722                                          struct vfs_aio_state *vfs_aio_state)
 723 {
 724         struct smb_time_audit_pread_state *state = tevent_req_data(
 725                 req, struct smb_time_audit_pread_state);
 726         double timediff;
 727
 728         timediff = state->vfs_aio_state.duration * 1.0e-9;
 729
 730         if (timediff > audit_timeout) {
 731                 smb_time_audit_log_fsp("async pread", timediff, state->fsp);
 732         }
 733
 734         if (tevent_req_is_unix_error(req, &vfs_aio_state->error)) {
 735                 return -1;
 736         }
 737         *vfs_aio_state = state->vfs_aio_state;
 738         return state->ret;
 739 }
 740
 741 static ssize_t smb_time_audit_write(vfs_handle_struct *handle,
 742                                     files_struct *fsp,
 743                                     const void *data, size_t n)
 744 {
 745         ssize_t result;
 746         struct timespec ts1,ts2;
 747         double timediff;
 748
 749         clock_gettime_mono(&ts1);
 750         result = SMB_VFS_NEXT_WRITE(handle, fsp, data, n);
 751         clock_gettime_mono(&ts2);
 752         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 753
 754         if (timediff > audit_timeout) {
 755                 smb_time_audit_log_fsp("write", timediff, fsp);
 756         }
 757
 758         return result;
 759 }
 760
 761 static ssize_t smb_time_audit_pwrite(vfs_handle_struct *handle,
 762                                      files_struct *fsp,
 763                                      const void *data, size_t n,
 764                                      off_t offset)
 765 {
 766         ssize_t result;
 767         struct timespec ts1,ts2;
 768         double timediff;
 769
 770         clock_gettime_mono(&ts1);
 771         result = SMB_VFS_NEXT_PWRITE(handle, fsp, data, n, offset);
 772         clock_gettime_mono(&ts2);
 773         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 774
 775         if (timediff > audit_timeout) {
 776                 smb_time_audit_log_fsp("pwrite", timediff, fsp);
 777         }
 778
 779         return result;
 780 }
 781
 782 struct smb_time_audit_pwrite_state {
 783         struct files_struct *fsp;
 784         ssize_t ret;
 785         struct vfs_aio_state vfs_aio_state;
 786 };
 787
 788 static void smb_time_audit_pwrite_done(struct tevent_req *subreq);
 789
 790 static struct tevent_req *smb_time_audit_pwrite_send(
 791         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 792         struct tevent_context *ev, struct files_struct *fsp,
 793         const void *data, size_t n, off_t offset)
 794 {
 795         struct tevent_req *req, *subreq;
 796         struct smb_time_audit_pwrite_state *state;
 797
 798         req = tevent_req_create(mem_ctx, &state,
 799                                 struct smb_time_audit_pwrite_state);
 800         if (req == NULL) {
 801                 return NULL;
 802         }
 803         state->fsp = fsp;
 804
 805         subreq = SMB_VFS_NEXT_PWRITE_SEND(state, ev, handle, fsp, data,
 806                                          n, offset);
 807         if (tevent_req_nomem(subreq, req)) {
 808                 return tevent_req_post(req, ev);
 809         }
 810         tevent_req_set_callback(subreq, smb_time_audit_pwrite_done, req);
 811         return req;
 812 }
 813
 814 static void smb_time_audit_pwrite_done(struct tevent_req *subreq)
 815 {
 816         struct tevent_req *req = tevent_req_callback_data(
 817                 subreq, struct tevent_req);
 818         struct smb_time_audit_pwrite_state *state = tevent_req_data(
 819                 req, struct smb_time_audit_pwrite_state);
 820
 821         state->ret = SMB_VFS_PWRITE_RECV(subreq, &state->vfs_aio_state);
 822         TALLOC_FREE(subreq);
 823         tevent_req_done(req);
 824 }
 825
 826 static ssize_t smb_time_audit_pwrite_recv(struct tevent_req *req,
 827                                           struct vfs_aio_state *vfs_aio_state)
 828 {
 829         struct smb_time_audit_pwrite_state *state = tevent_req_data(
 830                 req, struct smb_time_audit_pwrite_state);
 831         double timediff;
 832
 833         timediff = state->vfs_aio_state.duration * 1.0e-9;
 834
 835         if (timediff > audit_timeout) {
 836                 smb_time_audit_log_fsp("async pwrite", timediff, state->fsp);
 837         }
 838
 839         if (tevent_req_is_unix_error(req, &vfs_aio_state->error)) {
 840                 return -1;
 841         }
 842         *vfs_aio_state = state->vfs_aio_state;
 843         return state->ret;
 844 }
 845
 846 static off_t smb_time_audit_lseek(vfs_handle_struct *handle,
 847                                       files_struct *fsp,
 848                                       off_t offset, int whence)
 849 {
 850         off_t result;
 851         struct timespec ts1,ts2;
 852         double timediff;
 853
 854         clock_gettime_mono(&ts1);
 855         result = SMB_VFS_NEXT_LSEEK(handle, fsp, offset, whence);
 856         clock_gettime_mono(&ts2);
 857         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 858
 859         if (timediff > audit_timeout) {
 860                 smb_time_audit_log_fsp("lseek", timediff, fsp);
 861         }
 862
 863         return result;
 864 }
 865
 866 static ssize_t smb_time_audit_sendfile(vfs_handle_struct *handle, int tofd,
 867                                        files_struct *fromfsp,
 868                                        const DATA_BLOB *hdr, off_t offset,
 869                                        size_t n)
 870 {
 871         ssize_t result;
 872         struct timespec ts1,ts2;
 873         double timediff;
 874
 875         clock_gettime_mono(&ts1);
 876         result = SMB_VFS_NEXT_SENDFILE(handle, tofd, fromfsp, hdr, offset, n);
 877         clock_gettime_mono(&ts2);
 878         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 879
 880         if (timediff > audit_timeout) {
 881                 smb_time_audit_log_fsp("sendfile", timediff, fromfsp);
 882         }
 883
 884         return result;
 885 }
 886
 887 static ssize_t smb_time_audit_recvfile(vfs_handle_struct *handle, int fromfd,
 888                                        files_struct *tofsp,
 889                                        off_t offset,
 890                                        size_t n)
 891 {
 892         ssize_t result;
 893         struct timespec ts1,ts2;
 894         double timediff;
 895
 896         clock_gettime_mono(&ts1);
 897         result = SMB_VFS_NEXT_RECVFILE(handle, fromfd, tofsp, offset, n);
 898         clock_gettime_mono(&ts2);
 899         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 900
 901         if (timediff > audit_timeout) {
 902                 smb_time_audit_log_fsp("recvfile", timediff, tofsp);
 903         }
 904
 905         return result;
 906 }
 907
 908 static int smb_time_audit_rename(vfs_handle_struct *handle,
 909                                  const struct smb_filename *oldname,
 910                                  const struct smb_filename *newname)
 911 {
 912         int result;
 913         struct timespec ts1,ts2;
 914         double timediff;
 915
 916         clock_gettime_mono(&ts1);
 917         result = SMB_VFS_NEXT_RENAME(handle, oldname, newname);
 918         clock_gettime_mono(&ts2);
 919         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 920
 921         if (timediff > audit_timeout) {
 922                 smb_time_audit_log_smb_fname("rename", timediff, newname);
 923         }
 924
 925         return result;
 926 }
 927
 928 static int smb_time_audit_fsync(vfs_handle_struct *handle, files_struct *fsp)
 929 {
 930         int result;
 931         struct timespec ts1,ts2;
 932         double timediff;
 933
 934         clock_gettime_mono(&ts1);
 935         result = SMB_VFS_NEXT_FSYNC(handle, fsp);
 936         clock_gettime_mono(&ts2);
 937         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
 938
 939         if (timediff > audit_timeout) {
 940                 smb_time_audit_log_fsp("fsync", timediff, fsp);
 941         }
 942
 943         return result;
 944 }
 945
 946 struct smb_time_audit_fsync_state {
 947         struct files_struct *fsp;
 948         int ret;
 949         struct vfs_aio_state vfs_aio_state;
 950 };
 951
 952 static void smb_time_audit_fsync_done(struct tevent_req *subreq);
 953
 954 static struct tevent_req *smb_time_audit_fsync_send(
 955         struct vfs_handle_struct *handle, TALLOC_CTX *mem_ctx,
 956         struct tevent_context *ev, struct files_struct *fsp)
 957 {
 958         struct tevent_req *req, *subreq;
 959         struct smb_time_audit_fsync_state *state;
 960
 961         req = tevent_req_create(mem_ctx, &state,
 962                                 struct smb_time_audit_fsync_state);
 963         if (req == NULL) {
 964                 return NULL;
 965         }
 966         state->fsp = fsp;
 967
 968         subreq = SMB_VFS_NEXT_FSYNC_SEND(state, ev, handle, fsp);
 969         if (tevent_req_nomem(subreq, req)) {
 970                 return tevent_req_post(req, ev);
 971         }
 972         tevent_req_set_callback(subreq, smb_time_audit_fsync_done, req);
 973         return req;
 974 }
 975
 976 static void smb_time_audit_fsync_done(struct tevent_req *subreq)
 977 {
 978         struct tevent_req *req = tevent_req_callback_data(
 979                 subreq, struct tevent_req);
 980         struct smb_time_audit_fsync_state *state = tevent_req_data(
 981                 req, struct smb_time_audit_fsync_state);
 982
 983         state->ret = SMB_VFS_FSYNC_RECV(subreq, &state->vfs_aio_state);
 984         TALLOC_FREE(subreq);
 985         tevent_req_done(req);
 986 }
 987
 988 static int smb_time_audit_fsync_recv(struct tevent_req *req,
 989                                      struct vfs_aio_state *vfs_aio_state)
 990 {
 991         struct smb_time_audit_fsync_state *state = tevent_req_data(
 992                 req, struct smb_time_audit_fsync_state);
 993         double timediff;
 994
 995         timediff = state->vfs_aio_state.duration * 1.0e-9;
 996
 997         if (timediff > audit_timeout) {
 998                 smb_time_audit_log_fsp("async fsync", timediff, state->fsp);
 999         }
1000
1001         if (tevent_req_is_unix_error(req, &vfs_aio_state->error)) {
1002                 return -1;
1003         }
1004         *vfs_aio_state = state->vfs_aio_state;
1005         return state->ret;
1006 }
1007
1008 static int smb_time_audit_stat(vfs_handle_struct *handle,
1009                                struct smb_filename *fname)
1010 {
1011         int result;
1012         struct timespec ts1,ts2;
1013         double timediff;
1014
1015         clock_gettime_mono(&ts1);
1016         result = SMB_VFS_NEXT_STAT(handle, fname);
1017         clock_gettime_mono(&ts2);
1018         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1019
1020         if (timediff > audit_timeout) {
1021                 smb_time_audit_log_smb_fname("stat", timediff, fname);
1022         }
1023
1024         return result;
1025 }
1026
1027 static int smb_time_audit_fstat(vfs_handle_struct *handle, files_struct *fsp,
1028                                 SMB_STRUCT_STAT *sbuf)
1029 {
1030         int result;
1031         struct timespec ts1,ts2;
1032         double timediff;
1033
1034         clock_gettime_mono(&ts1);
1035         result = SMB_VFS_NEXT_FSTAT(handle, fsp, sbuf);
1036         clock_gettime_mono(&ts2);
1037         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1038
1039         if (timediff > audit_timeout) {
1040                 smb_time_audit_log_fsp("fstat", timediff, fsp);
1041         }
1042
1043         return result;
1044 }
1045
1046 static int smb_time_audit_lstat(vfs_handle_struct *handle,
1047                                 struct smb_filename *path)
1048 {
1049         int result;
1050         struct timespec ts1,ts2;
1051         double timediff;
1052
1053         clock_gettime_mono(&ts1);
1054         result = SMB_VFS_NEXT_LSTAT(handle, path);
1055         clock_gettime_mono(&ts2);
1056         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1057
1058         if (timediff > audit_timeout) {
1059                 smb_time_audit_log_smb_fname("lstat", timediff, path);
1060         }
1061
1062         return result;
1063 }
1064
1065 static uint64_t smb_time_audit_get_alloc_size(vfs_handle_struct *handle,
1066                                               files_struct *fsp,
1067                                               const SMB_STRUCT_STAT *sbuf)
1068 {
1069         uint64_t result;
1070         struct timespec ts1,ts2;
1071         double timediff;
1072
1073         clock_gettime_mono(&ts1);
1074         result = SMB_VFS_NEXT_GET_ALLOC_SIZE(handle, fsp, sbuf);
1075         clock_gettime_mono(&ts2);
1076         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1077
1078         if (timediff > audit_timeout) {
1079                 smb_time_audit_log_fsp("get_alloc_size", timediff, fsp);
1080         }
1081
1082         return result;
1083 }
1084
1085 static int smb_time_audit_unlink(vfs_handle_struct *handle,
1086                                  const struct smb_filename *path)
1087 {
1088         int result;
1089         struct timespec ts1,ts2;
1090         double timediff;
1091
1092         clock_gettime_mono(&ts1);
1093         result = SMB_VFS_NEXT_UNLINK(handle, path);
1094         clock_gettime_mono(&ts2);
1095         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1096
1097         if (timediff > audit_timeout) {
1098                 smb_time_audit_log_smb_fname("unlink", timediff, path);
1099         }
1100
1101         return result;
1102 }
1103
1104 static int smb_time_audit_chmod(vfs_handle_struct *handle,
1105                         const struct smb_filename *smb_fname,
1106                         mode_t mode)
1107 {
1108         int result;
1109         struct timespec ts1,ts2;
1110         double timediff;
1111
1112         clock_gettime_mono(&ts1);
1113         result = SMB_VFS_NEXT_CHMOD(handle, smb_fname, mode);
1114         clock_gettime_mono(&ts2);
1115         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1116
1117         if (timediff > audit_timeout) {
1118                 smb_time_audit_log_fname("chmod",
1119                         timediff,
1120                         smb_fname->base_name);
1121         }
1122
1123         return result;
1124 }
1125
1126 static int smb_time_audit_fchmod(vfs_handle_struct *handle, files_struct *fsp,
1127                                  mode_t mode)
1128 {
1129         int result;
1130         struct timespec ts1,ts2;
1131         double timediff;
1132
1133         clock_gettime_mono(&ts1);
1134         result = SMB_VFS_NEXT_FCHMOD(handle, fsp, mode);
1135         clock_gettime_mono(&ts2);
1136         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1137
1138         if (timediff > audit_timeout) {
1139                 smb_time_audit_log_fsp("fchmod", timediff, fsp);
1140         }
1141
1142         return result;
1143 }
1144
1145 static int smb_time_audit_chown(vfs_handle_struct *handle,
1146                                 const char *path, uid_t uid, gid_t gid)
1147 {
1148         int result;
1149         struct timespec ts1,ts2;
1150         double timediff;
1151
1152         clock_gettime_mono(&ts1);
1153         result = SMB_VFS_NEXT_CHOWN(handle, path, uid, gid);
1154         clock_gettime_mono(&ts2);
1155         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1156
1157         if (timediff > audit_timeout) {
1158                 smb_time_audit_log_fname("chown", timediff, path);
1159         }
1160
1161         return result;
1162 }
1163
1164 static int smb_time_audit_fchown(vfs_handle_struct *handle, files_struct *fsp,
1165                                  uid_t uid, gid_t gid)
1166 {
1167         int result;
1168         struct timespec ts1,ts2;
1169         double timediff;
1170
1171         clock_gettime_mono(&ts1);
1172         result = SMB_VFS_NEXT_FCHOWN(handle, fsp, uid, gid);
1173         clock_gettime_mono(&ts2);
1174         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1175
1176         if (timediff > audit_timeout) {
1177                 smb_time_audit_log_fsp("fchown", timediff, fsp);
1178         }
1179
1180         return result;
1181 }
1182
1183 static int smb_time_audit_lchown(vfs_handle_struct *handle,
1184                                  const char *path, uid_t uid, gid_t gid)
1185 {
1186         int result;
1187         struct timespec ts1,ts2;
1188         double timediff;
1189
1190         clock_gettime_mono(&ts1);
1191         result = SMB_VFS_NEXT_LCHOWN(handle, path, uid, gid);
1192         clock_gettime_mono(&ts2);
1193         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1194
1195         if (timediff > audit_timeout) {
1196                 smb_time_audit_log_fname("lchown", timediff, path);
1197         }
1198
1199         return result;
1200 }
1201
1202 static int smb_time_audit_chdir(vfs_handle_struct *handle, const char *path)
1203 {
1204         int result;
1205         struct timespec ts1,ts2;
1206         double timediff;
1207
1208         clock_gettime_mono(&ts1);
1209         result = SMB_VFS_NEXT_CHDIR(handle, path);
1210         clock_gettime_mono(&ts2);
1211         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1212
1213         if (timediff > audit_timeout) {
1214                 smb_time_audit_log_fname("chdir", timediff, path);
1215         }
1216
1217         return result;
1218 }
1219
1220 static char *smb_time_audit_getwd(vfs_handle_struct *handle)
1221 {
1222         char *result;
1223         struct timespec ts1,ts2;
1224         double timediff;
1225
1226         clock_gettime_mono(&ts1);
1227         result = SMB_VFS_NEXT_GETWD(handle);
1228         clock_gettime_mono(&ts2);
1229         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1230
1231         if (timediff > audit_timeout) {
1232                 smb_time_audit_log("getwd", timediff);
1233         }
1234
1235         return result;
1236 }
1237
1238 static int smb_time_audit_ntimes(vfs_handle_struct *handle,
1239                                  const struct smb_filename *path,
1240                                  struct smb_file_time *ft)
1241 {
1242         int result;
1243         struct timespec ts1,ts2;
1244         double timediff;
1245
1246         clock_gettime_mono(&ts1);
1247         result = SMB_VFS_NEXT_NTIMES(handle, path, ft);
1248         clock_gettime_mono(&ts2);
1249         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1250
1251         if (timediff > audit_timeout) {
1252                 smb_time_audit_log_smb_fname("ntimes", timediff, path);
1253         }
1254
1255         return result;
1256 }
1257
1258 static int smb_time_audit_ftruncate(vfs_handle_struct *handle,
1259                                     files_struct *fsp,
1260                                     off_t len)
1261 {
1262         int result;
1263         struct timespec ts1,ts2;
1264         double timediff;
1265
1266         clock_gettime_mono(&ts1);
1267         result = SMB_VFS_NEXT_FTRUNCATE(handle, fsp, len);
1268         clock_gettime_mono(&ts2);
1269         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1270
1271         if (timediff > audit_timeout) {
1272                 smb_time_audit_log_fsp("ftruncate", timediff, fsp);
1273         }
1274
1275         return result;
1276 }
1277
1278 static int smb_time_audit_fallocate(vfs_handle_struct *handle,
1279                                     files_struct *fsp,
1280                                     uint32_t mode,
1281                                     off_t offset,
1282                                     off_t len)
1283 {
1284         int result;
1285         int saved_errno = 0;
1286         struct timespec ts1,ts2;
1287         double timediff;
1288
1289         clock_gettime_mono(&ts1);
1290         result = SMB_VFS_NEXT_FALLOCATE(handle, fsp, mode, offset, len);
1291         if (result == -1) {
1292                 saved_errno = errno;
1293         }
1294         clock_gettime_mono(&ts2);
1295         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1296
1297         if (timediff > audit_timeout) {
1298                 smb_time_audit_log_fsp("fallocate", timediff, fsp);
1299         }
1300         if (result == -1) {
1301                 errno = saved_errno;
1302         }
1303         return result;
1304 }
1305
1306 static bool smb_time_audit_lock(vfs_handle_struct *handle, files_struct *fsp,
1307                                 int op, off_t offset, off_t count,
1308                                 int type)
1309 {
1310         bool result;
1311         struct timespec ts1,ts2;
1312         double timediff;
1313
1314         clock_gettime_mono(&ts1);
1315         result = SMB_VFS_NEXT_LOCK(handle, fsp, op, offset, count, type);
1316         clock_gettime_mono(&ts2);
1317         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1318
1319         if (timediff > audit_timeout) {
1320                 smb_time_audit_log_fsp("lock", timediff, fsp);
1321         }
1322
1323         return result;
1324 }
1325
1326 static int smb_time_audit_kernel_flock(struct vfs_handle_struct *handle,
1327                                        struct files_struct *fsp,
1328                                        uint32_t share_mode, uint32_t access_mask)
1329 {
1330         int result;
1331         struct timespec ts1,ts2;
1332         double timediff;
1333
1334         clock_gettime_mono(&ts1);
1335         result = SMB_VFS_NEXT_KERNEL_FLOCK(handle, fsp, share_mode,
1336                                            access_mask);
1337         clock_gettime_mono(&ts2);
1338         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1339
1340         if (timediff > audit_timeout) {
1341                 smb_time_audit_log_fsp("kernel_flock", timediff, fsp);
1342         }
1343
1344         return result;
1345 }
1346
1347 static int smb_time_audit_linux_setlease(vfs_handle_struct *handle,
1348                                          files_struct *fsp,
1349                                          int leasetype)
1350 {
1351         int result;
1352         struct timespec ts1,ts2;
1353         double timediff;
1354
1355         clock_gettime_mono(&ts1);
1356         result = SMB_VFS_NEXT_LINUX_SETLEASE(handle, fsp, leasetype);
1357         clock_gettime_mono(&ts2);
1358         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1359
1360         if (timediff > audit_timeout) {
1361                 smb_time_audit_log_fsp("linux_setlease", timediff, fsp);
1362         }
1363
1364         return result;
1365 }
1366
1367 static bool smb_time_audit_getlock(vfs_handle_struct *handle,
1368                                    files_struct *fsp,
1369                                    off_t *poffset, off_t *pcount,
1370                                    int *ptype, pid_t *ppid)
1371 {
1372         bool result;
1373         struct timespec ts1,ts2;
1374         double timediff;
1375
1376         clock_gettime_mono(&ts1);
1377         result = SMB_VFS_NEXT_GETLOCK(handle, fsp, poffset, pcount, ptype,
1378                                       ppid);
1379         clock_gettime_mono(&ts2);
1380         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1381
1382         if (timediff > audit_timeout) {
1383                 smb_time_audit_log_fsp("getlock", timediff, fsp);
1384         }
1385
1386         return result;
1387 }
1388
1389 static int smb_time_audit_symlink(vfs_handle_struct *handle,
1390                                   const char *oldpath, const char *newpath)
1391 {
1392         int result;
1393         struct timespec ts1,ts2;
1394         double timediff;
1395
1396         clock_gettime_mono(&ts1);
1397         result = SMB_VFS_NEXT_SYMLINK(handle, oldpath, newpath);
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_fname("symlink", timediff, newpath);
1403         }
1404
1405         return result;
1406 }
1407
1408 static int smb_time_audit_readlink(vfs_handle_struct *handle,
1409                           const char *path, char *buf, size_t bufsiz)
1410 {
1411         int result;
1412         struct timespec ts1,ts2;
1413         double timediff;
1414
1415         clock_gettime_mono(&ts1);
1416         result = SMB_VFS_NEXT_READLINK(handle, path, buf, bufsiz);
1417         clock_gettime_mono(&ts2);
1418         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1419
1420         if (timediff > audit_timeout) {
1421                 smb_time_audit_log_fname("readlink", timediff, path);
1422         }
1423
1424         return result;
1425 }
1426
1427 static int smb_time_audit_link(vfs_handle_struct *handle,
1428                                const char *oldpath, const char *newpath)
1429 {
1430         int result;
1431         struct timespec ts1,ts2;
1432         double timediff;
1433
1434         clock_gettime_mono(&ts1);
1435         result = SMB_VFS_NEXT_LINK(handle, oldpath, newpath);
1436         clock_gettime_mono(&ts2);
1437         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1438
1439         if (timediff > audit_timeout) {
1440                 smb_time_audit_log_fname("link", timediff, newpath);
1441         }
1442
1443         return result;
1444 }
1445
1446 static int smb_time_audit_mknod(vfs_handle_struct *handle,
1447                                 const char *pathname, mode_t mode,
1448                                 SMB_DEV_T dev)
1449 {
1450         int result;
1451         struct timespec ts1,ts2;
1452         double timediff;
1453
1454         clock_gettime_mono(&ts1);
1455         result = SMB_VFS_NEXT_MKNOD(handle, pathname, mode, dev);
1456         clock_gettime_mono(&ts2);
1457         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1458
1459         if (timediff > audit_timeout) {
1460                 smb_time_audit_log_fname("mknod", timediff, pathname);
1461         }
1462
1463         return result;
1464 }
1465
1466 static char *smb_time_audit_realpath(vfs_handle_struct *handle,
1467                                      const char *path)
1468 {
1469         char *result;
1470         struct timespec ts1,ts2;
1471         double timediff;
1472
1473         clock_gettime_mono(&ts1);
1474         result = SMB_VFS_NEXT_REALPATH(handle, path);
1475         clock_gettime_mono(&ts2);
1476         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1477
1478         if (timediff > audit_timeout) {
1479                 smb_time_audit_log_fname("realpath", timediff, path);
1480         }
1481
1482         return result;
1483 }
1484
1485 static int smb_time_audit_chflags(vfs_handle_struct *handle,
1486                                   const char *path, unsigned int flags)
1487 {
1488         int result;
1489         struct timespec ts1,ts2;
1490         double timediff;
1491
1492         clock_gettime_mono(&ts1);
1493         result = SMB_VFS_NEXT_CHFLAGS(handle, path, flags);
1494         clock_gettime_mono(&ts2);
1495         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1496
1497         if (timediff > audit_timeout) {
1498                 smb_time_audit_log_fname("chflags", timediff, path);
1499         }
1500
1501         return result;
1502 }
1503
1504 static struct file_id smb_time_audit_file_id_create(struct vfs_handle_struct *handle,
1505                                                     const SMB_STRUCT_STAT *sbuf)
1506 {
1507         struct file_id id_zero;
1508         struct file_id result;
1509         struct timespec ts1,ts2;
1510         double timediff;
1511
1512         ZERO_STRUCT(id_zero);
1513
1514         clock_gettime_mono(&ts1);
1515         result = SMB_VFS_NEXT_FILE_ID_CREATE(handle, sbuf);
1516         clock_gettime_mono(&ts2);
1517         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1518
1519         if (timediff > audit_timeout) {
1520                 smb_time_audit_log("file_id_create", timediff);
1521         }
1522
1523         return result;
1524 }
1525
1526 static NTSTATUS smb_time_audit_streaminfo(vfs_handle_struct *handle,
1527                                           struct files_struct *fsp,
1528                                           const char *fname,
1529                                           TALLOC_CTX *mem_ctx,
1530                                           unsigned int *pnum_streams,
1531                                           struct stream_struct **pstreams)
1532 {
1533         NTSTATUS result;
1534         struct timespec ts1,ts2;
1535         double timediff;
1536
1537         clock_gettime_mono(&ts1);
1538         result = SMB_VFS_NEXT_STREAMINFO(handle, fsp, fname, mem_ctx,
1539                                          pnum_streams, pstreams);
1540         clock_gettime_mono(&ts2);
1541         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1542
1543         if (timediff > audit_timeout) {
1544                 smb_time_audit_log_fsp("streaminfo", timediff, fsp);
1545         }
1546
1547         return result;
1548 }
1549
1550 static int smb_time_audit_get_real_filename(struct vfs_handle_struct *handle,
1551                                             const char *path,
1552                                             const char *name,
1553                                             TALLOC_CTX *mem_ctx,
1554                                             char **found_name)
1555 {
1556         int result;
1557         struct timespec ts1,ts2;
1558         double timediff;
1559
1560         clock_gettime_mono(&ts1);
1561         result = SMB_VFS_NEXT_GET_REAL_FILENAME(handle, path, name, mem_ctx,
1562                                                 found_name);
1563         clock_gettime_mono(&ts2);
1564         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1565
1566         if (timediff > audit_timeout) {
1567                 smb_time_audit_log_fname("get_real_filename", timediff, path);
1568         }
1569
1570         return result;
1571 }
1572
1573 static const char *smb_time_audit_connectpath(vfs_handle_struct *handle,
1574                                               const char *fname)
1575 {
1576         const char *result;
1577         struct timespec ts1,ts2;
1578         double timediff;
1579
1580         clock_gettime_mono(&ts1);
1581         result = SMB_VFS_NEXT_CONNECTPATH(handle, fname);
1582         clock_gettime_mono(&ts2);
1583         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1584
1585         if (timediff > audit_timeout) {
1586                 smb_time_audit_log_fname("connectpath", timediff, fname);
1587         }
1588
1589         return result;
1590 }
1591
1592 static NTSTATUS smb_time_audit_brl_lock_windows(struct vfs_handle_struct *handle,
1593                                                 struct byte_range_lock *br_lck,
1594                                                 struct lock_struct *plock,
1595                                                 bool blocking_lock)
1596 {
1597         NTSTATUS result;
1598         struct timespec ts1,ts2;
1599         double timediff;
1600
1601         clock_gettime_mono(&ts1);
1602         result = SMB_VFS_NEXT_BRL_LOCK_WINDOWS(handle, br_lck, plock,
1603                                                blocking_lock);
1604         clock_gettime_mono(&ts2);
1605         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1606
1607         if (timediff > audit_timeout) {
1608                 smb_time_audit_log_fsp("brl_lock_windows", timediff,
1609                                        brl_fsp(br_lck));
1610         }
1611
1612         return result;
1613 }
1614
1615 static bool smb_time_audit_brl_unlock_windows(struct vfs_handle_struct *handle,
1616                                               struct messaging_context *msg_ctx,
1617                                               struct byte_range_lock *br_lck,
1618                                               const struct lock_struct *plock)
1619 {
1620         bool result;
1621         struct timespec ts1,ts2;
1622         double timediff;
1623
1624         clock_gettime_mono(&ts1);
1625         result = SMB_VFS_NEXT_BRL_UNLOCK_WINDOWS(handle, msg_ctx, br_lck,
1626                                                  plock);
1627         clock_gettime_mono(&ts2);
1628         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1629
1630         if (timediff > audit_timeout) {
1631                 smb_time_audit_log_fsp("brl_unlock_windows", timediff,
1632                                        brl_fsp(br_lck));
1633         }
1634
1635         return result;
1636 }
1637
1638 static bool smb_time_audit_brl_cancel_windows(struct vfs_handle_struct *handle,
1639                                               struct byte_range_lock *br_lck,
1640                                               struct lock_struct *plock)
1641 {
1642         bool result;
1643         struct timespec ts1,ts2;
1644         double timediff;
1645
1646         clock_gettime_mono(&ts1);
1647         result = SMB_VFS_NEXT_BRL_CANCEL_WINDOWS(handle, br_lck, plock);
1648         clock_gettime_mono(&ts2);
1649         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1650
1651         if (timediff > audit_timeout) {
1652                 smb_time_audit_log_fsp("brl_cancel_windows", timediff,
1653                                        brl_fsp(br_lck));
1654         }
1655
1656         return result;
1657 }
1658
1659 static bool smb_time_audit_strict_lock(struct vfs_handle_struct *handle,
1660                                        struct files_struct *fsp,
1661                                        struct lock_struct *plock)
1662 {
1663         bool result;
1664         struct timespec ts1,ts2;
1665         double timediff;
1666
1667         clock_gettime_mono(&ts1);
1668         result = SMB_VFS_NEXT_STRICT_LOCK(handle, fsp, plock);
1669         clock_gettime_mono(&ts2);
1670         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1671
1672         if (timediff > audit_timeout) {
1673                 smb_time_audit_log_fsp("strict_lock", timediff, fsp);
1674         }
1675
1676         return result;
1677 }
1678
1679 static void smb_time_audit_strict_unlock(struct vfs_handle_struct *handle,
1680                                          struct files_struct *fsp,
1681                                          struct lock_struct *plock)
1682 {
1683         struct timespec ts1,ts2;
1684         double timediff;
1685
1686         clock_gettime_mono(&ts1);
1687         SMB_VFS_NEXT_STRICT_UNLOCK(handle, fsp, plock);
1688         clock_gettime_mono(&ts2);
1689         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1690
1691         if (timediff > audit_timeout) {
1692                 smb_time_audit_log_fsp("strict_unlock", timediff, fsp);
1693         }
1694 }
1695
1696 static NTSTATUS smb_time_audit_translate_name(struct vfs_handle_struct *handle,
1697                                               const char *name,
1698                                               enum vfs_translate_direction direction,
1699                                               TALLOC_CTX *mem_ctx,
1700                                               char **mapped_name)
1701 {
1702         NTSTATUS result;
1703         struct timespec ts1,ts2;
1704         double timediff;
1705
1706         clock_gettime_mono(&ts1);
1707         result = SMB_VFS_NEXT_TRANSLATE_NAME(handle, name, direction, mem_ctx,
1708                                              mapped_name);
1709         clock_gettime_mono(&ts2);
1710         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1711
1712         if (timediff > audit_timeout) {
1713                 smb_time_audit_log_fname("translate_name", timediff, name);
1714         }
1715
1716         return result;
1717 }
1718
1719 struct time_audit_cc_state {
1720         struct timespec ts_send;
1721         struct vfs_handle_struct *handle;
1722         off_t copied;
1723 };
1724 static void smb_time_audit_copy_chunk_done(struct tevent_req *subreq);
1725
1726 static struct tevent_req *smb_time_audit_copy_chunk_send(struct vfs_handle_struct *handle,
1727                                                          TALLOC_CTX *mem_ctx,
1728                                                          struct tevent_context *ev,
1729                                                          struct files_struct *src_fsp,
1730                                                          off_t src_off,
1731                                                          struct files_struct *dest_fsp,
1732                                                          off_t dest_off,
1733                                                          off_t num)
1734 {
1735         struct tevent_req *req;
1736         struct tevent_req *subreq;
1737         struct time_audit_cc_state *cc_state;
1738
1739         req = tevent_req_create(mem_ctx, &cc_state, struct time_audit_cc_state);
1740         if (req == NULL) {
1741                 return NULL;
1742         }
1743
1744         cc_state->handle = handle;
1745         clock_gettime_mono(&cc_state->ts_send);
1746         subreq = SMB_VFS_NEXT_COPY_CHUNK_SEND(handle, cc_state, ev,
1747                                               src_fsp, src_off,
1748                                               dest_fsp, dest_off, num);
1749         if (tevent_req_nomem(subreq, req)) {
1750                 return tevent_req_post(req, ev);
1751         }
1752
1753         tevent_req_set_callback(subreq, smb_time_audit_copy_chunk_done, req);
1754         return req;
1755 }
1756
1757 static void smb_time_audit_copy_chunk_done(struct tevent_req *subreq)
1758 {
1759         struct tevent_req *req = tevent_req_callback_data(
1760                 subreq, struct tevent_req);
1761         struct time_audit_cc_state *cc_state
1762                         = tevent_req_data(req, struct time_audit_cc_state);
1763         NTSTATUS status;
1764
1765         status = SMB_VFS_NEXT_COPY_CHUNK_RECV(cc_state->handle,
1766                                               subreq,
1767                                               &cc_state->copied);
1768         TALLOC_FREE(subreq);
1769         if (tevent_req_nterror(req, status)) {
1770                 return;
1771         }
1772         tevent_req_done(req);
1773 }
1774
1775 static NTSTATUS smb_time_audit_copy_chunk_recv(struct vfs_handle_struct *handle,
1776                                                struct tevent_req *req,
1777                                                off_t *copied)
1778 {
1779         struct time_audit_cc_state *cc_state
1780                         = tevent_req_data(req, struct time_audit_cc_state);
1781         struct timespec ts_recv;
1782         double timediff;
1783         NTSTATUS status;
1784
1785         clock_gettime_mono(&ts_recv);
1786         timediff = nsec_time_diff(&ts_recv, &cc_state->ts_send)*1.0e-9;
1787         if (timediff > audit_timeout) {
1788                 smb_time_audit_log("copy_chunk", timediff);
1789         }
1790
1791         *copied = cc_state->copied;
1792         if (tevent_req_is_nterror(req, &status)) {
1793                 tevent_req_received(req);
1794                 return status;
1795         }
1796
1797         tevent_req_received(req);
1798         return NT_STATUS_OK;
1799 }
1800
1801 static NTSTATUS smb_time_audit_get_compression(vfs_handle_struct *handle,
1802                                                TALLOC_CTX *mem_ctx,
1803                                                struct files_struct *fsp,
1804                                                struct smb_filename *smb_fname,
1805                                                uint16_t *_compression_fmt)
1806 {
1807         NTSTATUS result;
1808         struct timespec ts1,ts2;
1809         double timediff;
1810
1811         clock_gettime_mono(&ts1);
1812         result = SMB_VFS_NEXT_GET_COMPRESSION(handle, mem_ctx, fsp, smb_fname,
1813                                               _compression_fmt);
1814         clock_gettime_mono(&ts2);
1815         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1816
1817         if (timediff > audit_timeout) {
1818                 if (fsp !=  NULL) {
1819                         smb_time_audit_log_fsp("get_compression",
1820                                                timediff, fsp);
1821                 } else {
1822                         smb_time_audit_log_smb_fname("get_compression",
1823                                                      timediff, smb_fname);
1824                 }
1825         }
1826
1827         return result;
1828 }
1829
1830 static NTSTATUS smb_time_audit_set_compression(vfs_handle_struct *handle,
1831                                                TALLOC_CTX *mem_ctx,
1832                                                struct files_struct *fsp,
1833                                                uint16_t compression_fmt)
1834 {
1835         NTSTATUS result;
1836         struct timespec ts1,ts2;
1837         double timediff;
1838
1839         clock_gettime_mono(&ts1);
1840         result = SMB_VFS_NEXT_SET_COMPRESSION(handle, mem_ctx, fsp,
1841                                               compression_fmt);
1842         clock_gettime_mono(&ts2);
1843         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1844
1845         if (timediff > audit_timeout) {
1846                 smb_time_audit_log_fsp("set_compression", timediff, fsp);
1847         }
1848
1849         return result;
1850 }
1851
1852 static NTSTATUS smb_time_audit_readdir_attr(struct vfs_handle_struct *handle,
1853                                             const struct smb_filename *fname,
1854                                             TALLOC_CTX *mem_ctx,
1855                                             struct readdir_attr_data **pattr_data)
1856 {
1857         NTSTATUS status;
1858         struct timespec ts1,ts2;
1859         double timediff;
1860
1861         clock_gettime_mono(&ts1);
1862         status = SMB_VFS_NEXT_READDIR_ATTR(handle, fname, mem_ctx, pattr_data);
1863         clock_gettime_mono(&ts2);
1864         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1865
1866         if (timediff > audit_timeout) {
1867                 smb_time_audit_log_smb_fname("readdir_attr", timediff, fname);
1868         }
1869
1870         return status;
1871 }
1872
1873 static NTSTATUS smb_time_audit_fget_nt_acl(vfs_handle_struct *handle,
1874                                            files_struct *fsp,
1875                                            uint32_t security_info,
1876                                            TALLOC_CTX *mem_ctx,
1877                                            struct security_descriptor **ppdesc)
1878 {
1879         NTSTATUS result;
1880         struct timespec ts1,ts2;
1881         double timediff;
1882
1883         clock_gettime_mono(&ts1);
1884         result = SMB_VFS_NEXT_FGET_NT_ACL(handle, fsp, security_info,
1885                                           mem_ctx, ppdesc);
1886         clock_gettime_mono(&ts2);
1887         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1888
1889         if (timediff > audit_timeout) {
1890                 smb_time_audit_log_fsp("fget_nt_acl", timediff, fsp);
1891         }
1892
1893         return result;
1894 }
1895
1896 static NTSTATUS smb_time_audit_get_nt_acl(vfs_handle_struct *handle,
1897                                           const struct smb_filename *smb_fname,
1898                                           uint32_t security_info,
1899                                           TALLOC_CTX *mem_ctx,
1900                                           struct security_descriptor **ppdesc)
1901 {
1902         NTSTATUS result;
1903         struct timespec ts1,ts2;
1904         double timediff;
1905
1906         clock_gettime_mono(&ts1);
1907         result = SMB_VFS_NEXT_GET_NT_ACL(handle, smb_fname, security_info,
1908                                          mem_ctx, ppdesc);
1909         clock_gettime_mono(&ts2);
1910         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1911
1912         if (timediff > audit_timeout) {
1913                 smb_time_audit_log_fname("get_nt_acl",
1914                         timediff,
1915                         smb_fname->base_name);
1916         }
1917
1918         return result;
1919 }
1920
1921 static NTSTATUS smb_time_audit_fset_nt_acl(vfs_handle_struct *handle,
1922                                            files_struct *fsp,
1923                                            uint32_t security_info_sent,
1924                                            const struct security_descriptor *psd)
1925 {
1926         NTSTATUS result;
1927         struct timespec ts1,ts2;
1928         double timediff;
1929
1930         clock_gettime_mono(&ts1);
1931         result = SMB_VFS_NEXT_FSET_NT_ACL(handle, fsp, security_info_sent,
1932                                           psd);
1933         clock_gettime_mono(&ts2);
1934         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1935
1936         if (timediff > audit_timeout) {
1937                 smb_time_audit_log_fsp("fset_nt_acl", timediff, fsp);
1938         }
1939
1940         return result;
1941 }
1942
1943 static int smb_time_audit_chmod_acl(vfs_handle_struct *handle,
1944                         const struct smb_filename *smb_fname,
1945                         mode_t mode)
1946 {
1947         int result;
1948         struct timespec ts1,ts2;
1949         double timediff;
1950
1951         clock_gettime_mono(&ts1);
1952         result = SMB_VFS_NEXT_CHMOD_ACL(handle, smb_fname, mode);
1953         clock_gettime_mono(&ts2);
1954         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1955
1956         if (timediff > audit_timeout) {
1957                 smb_time_audit_log_fname("chmod_acl",
1958                         timediff,
1959                         smb_fname->base_name);
1960         }
1961
1962         return result;
1963 }
1964
1965 static int smb_time_audit_fchmod_acl(vfs_handle_struct *handle,
1966                                      files_struct *fsp, mode_t mode)
1967 {
1968         int result;
1969         struct timespec ts1,ts2;
1970         double timediff;
1971
1972         clock_gettime_mono(&ts1);
1973         result = SMB_VFS_NEXT_FCHMOD_ACL(handle, fsp, mode);
1974         clock_gettime_mono(&ts2);
1975         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1976
1977         if (timediff > audit_timeout) {
1978                 smb_time_audit_log_fsp("fchmod_acl", timediff, fsp);
1979         }
1980
1981         return result;
1982 }
1983
1984 static SMB_ACL_T smb_time_audit_sys_acl_get_file(vfs_handle_struct *handle,
1985                                                  const char *path_p,
1986                                                  SMB_ACL_TYPE_T type,
1987                                                  TALLOC_CTX *mem_ctx)
1988 {
1989         SMB_ACL_T result;
1990         struct timespec ts1,ts2;
1991         double timediff;
1992
1993         clock_gettime_mono(&ts1);
1994         result = SMB_VFS_NEXT_SYS_ACL_GET_FILE(handle, path_p, type, mem_ctx);
1995         clock_gettime_mono(&ts2);
1996         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
1997
1998         if (timediff > audit_timeout) {
1999                 smb_time_audit_log_fname("sys_acl_get_file", timediff, path_p);
2000         }
2001
2002         return result;
2003 }
2004
2005 static SMB_ACL_T smb_time_audit_sys_acl_get_fd(vfs_handle_struct *handle,
2006                                                files_struct *fsp,
2007                                                TALLOC_CTX *mem_ctx)
2008 {
2009         SMB_ACL_T result;
2010         struct timespec ts1,ts2;
2011         double timediff;
2012
2013         clock_gettime_mono(&ts1);
2014         result = SMB_VFS_NEXT_SYS_ACL_GET_FD(handle, fsp, mem_ctx);
2015         clock_gettime_mono(&ts2);
2016         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2017
2018         if (timediff > audit_timeout) {
2019                 smb_time_audit_log_fsp("sys_acl_get_fd", timediff, fsp);
2020         }
2021
2022         return result;
2023 }
2024
2025
2026 static int smb_time_audit_sys_acl_blob_get_file(vfs_handle_struct *handle,
2027                                                 const char *path_p,
2028                                                 TALLOC_CTX *mem_ctx,
2029                                                 char **blob_description,
2030                                                 DATA_BLOB *blob)
2031 {
2032         int result;
2033         struct timespec ts1,ts2;
2034         double timediff;
2035
2036         clock_gettime_mono(&ts1);
2037         result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FILE(handle, path_p, mem_ctx, blob_description, blob);
2038         clock_gettime_mono(&ts2);
2039         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2040
2041         if (timediff > audit_timeout) {
2042                 smb_time_audit_log("sys_acl_blob_get_file", timediff);
2043         }
2044
2045         return result;
2046 }
2047
2048 static int smb_time_audit_sys_acl_blob_get_fd(vfs_handle_struct *handle,
2049                                               files_struct *fsp,
2050                                               TALLOC_CTX *mem_ctx,
2051                                               char **blob_description,
2052                                               DATA_BLOB *blob)
2053 {
2054         int result;
2055         struct timespec ts1,ts2;
2056         double timediff;
2057
2058         clock_gettime_mono(&ts1);
2059         result = SMB_VFS_NEXT_SYS_ACL_BLOB_GET_FD(handle, fsp, mem_ctx, blob_description, blob);
2060         clock_gettime_mono(&ts2);
2061         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2062
2063         if (timediff > audit_timeout) {
2064                 smb_time_audit_log("sys_acl_blob_get_fd", timediff);
2065         }
2066
2067         return result;
2068 }
2069
2070 static int smb_time_audit_sys_acl_set_file(vfs_handle_struct *handle,
2071                                            const char *name,
2072                                            SMB_ACL_TYPE_T acltype,
2073                                            SMB_ACL_T theacl)
2074 {
2075         int result;
2076         struct timespec ts1,ts2;
2077         double timediff;
2078
2079         clock_gettime_mono(&ts1);
2080         result = SMB_VFS_NEXT_SYS_ACL_SET_FILE(handle, name, acltype,
2081                                                theacl);
2082         clock_gettime_mono(&ts2);
2083         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2084
2085         if (timediff > audit_timeout) {
2086                 smb_time_audit_log_fname("sys_acl_set_file", timediff, name);
2087         }
2088
2089         return result;
2090 }
2091
2092 static int smb_time_audit_sys_acl_set_fd(vfs_handle_struct *handle,
2093                                          files_struct *fsp,
2094                                          SMB_ACL_T theacl)
2095 {
2096         int result;
2097         struct timespec ts1,ts2;
2098         double timediff;
2099
2100         clock_gettime_mono(&ts1);
2101         result = SMB_VFS_NEXT_SYS_ACL_SET_FD(handle, fsp, theacl);
2102         clock_gettime_mono(&ts2);
2103         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2104
2105         if (timediff > audit_timeout) {
2106                 smb_time_audit_log_fsp("sys_acl_set_fd", timediff, fsp);
2107         }
2108
2109         return result;
2110 }
2111
2112 static int smb_time_audit_sys_acl_delete_def_file(vfs_handle_struct *handle,
2113                                                   const char *path)
2114 {
2115         int result;
2116         struct timespec ts1,ts2;
2117         double timediff;
2118
2119         clock_gettime_mono(&ts1);
2120         result = SMB_VFS_NEXT_SYS_ACL_DELETE_DEF_FILE(handle, path);
2121         clock_gettime_mono(&ts2);
2122         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2123
2124         if (timediff > audit_timeout) {
2125                 smb_time_audit_log_fname("sys_acl_delete_def_file", timediff, path);
2126         }
2127
2128         return result;
2129 }
2130
2131 static ssize_t smb_time_audit_getxattr(struct vfs_handle_struct *handle,
2132                                        const char *path, const char *name,
2133                                        void *value, size_t size)
2134 {
2135         ssize_t result;
2136         struct timespec ts1,ts2;
2137         double timediff;
2138
2139         clock_gettime_mono(&ts1);
2140         result = SMB_VFS_NEXT_GETXATTR(handle, path, name, value, size);
2141         clock_gettime_mono(&ts2);
2142         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2143
2144         if (timediff > audit_timeout) {
2145                 smb_time_audit_log_fname("getxattr", timediff, path);
2146         }
2147
2148         return result;
2149 }
2150
2151 static ssize_t smb_time_audit_fgetxattr(struct vfs_handle_struct *handle,
2152                                         struct files_struct *fsp,
2153                                         const char *name, void *value,
2154                                         size_t size)
2155 {
2156         ssize_t result;
2157         struct timespec ts1,ts2;
2158         double timediff;
2159
2160         clock_gettime_mono(&ts1);
2161         result = SMB_VFS_NEXT_FGETXATTR(handle, fsp, name, value, size);
2162         clock_gettime_mono(&ts2);
2163         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2164
2165         if (timediff > audit_timeout) {
2166                 smb_time_audit_log_fsp("fgetxattr", timediff, fsp);
2167         }
2168
2169         return result;
2170 }
2171
2172 static ssize_t smb_time_audit_listxattr(struct vfs_handle_struct *handle,
2173                                         const char *path, char *list,
2174                                         size_t size)
2175 {
2176         ssize_t result;
2177         struct timespec ts1,ts2;
2178         double timediff;
2179
2180         clock_gettime_mono(&ts1);
2181         result = SMB_VFS_NEXT_LISTXATTR(handle, path, list, size);
2182         clock_gettime_mono(&ts2);
2183         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2184
2185         if (timediff > audit_timeout) {
2186                 smb_time_audit_log_fname("listxattr", timediff, path);
2187         }
2188
2189         return result;
2190 }
2191
2192 static ssize_t smb_time_audit_flistxattr(struct vfs_handle_struct *handle,
2193                                          struct files_struct *fsp, char *list,
2194                                          size_t size)
2195 {
2196         ssize_t result;
2197         struct timespec ts1,ts2;
2198         double timediff;
2199
2200         clock_gettime_mono(&ts1);
2201         result = SMB_VFS_NEXT_FLISTXATTR(handle, fsp, list, size);
2202         clock_gettime_mono(&ts2);
2203         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2204
2205         if (timediff > audit_timeout) {
2206                 smb_time_audit_log_fsp("flistxattr", timediff, fsp);
2207         }
2208
2209         return result;
2210 }
2211
2212 static int smb_time_audit_removexattr(struct vfs_handle_struct *handle,
2213                                       const char *path, const char *name)
2214 {
2215         int result;
2216         struct timespec ts1,ts2;
2217         double timediff;
2218
2219         clock_gettime_mono(&ts1);
2220         result = SMB_VFS_NEXT_REMOVEXATTR(handle, path, name);
2221         clock_gettime_mono(&ts2);
2222         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2223
2224         if (timediff > audit_timeout) {
2225                 smb_time_audit_log_fname("removexattr", timediff, path);
2226         }
2227
2228         return result;
2229 }
2230
2231 static int smb_time_audit_fremovexattr(struct vfs_handle_struct *handle,
2232                                        struct files_struct *fsp,
2233                                        const char *name)
2234 {
2235         int result;
2236         struct timespec ts1,ts2;
2237         double timediff;
2238
2239         clock_gettime_mono(&ts1);
2240         result = SMB_VFS_NEXT_FREMOVEXATTR(handle, fsp, name);
2241         clock_gettime_mono(&ts2);
2242         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2243
2244         if (timediff > audit_timeout) {
2245                 smb_time_audit_log_fsp("fremovexattr", timediff, fsp);
2246         }
2247
2248         return result;
2249 }
2250
2251 static int smb_time_audit_setxattr(struct vfs_handle_struct *handle,
2252                                    const char *path, const char *name,
2253                                    const void *value, size_t size,
2254                                    int flags)
2255 {
2256         int result;
2257         struct timespec ts1,ts2;
2258         double timediff;
2259
2260         clock_gettime_mono(&ts1);
2261         result = SMB_VFS_NEXT_SETXATTR(handle, path, name, value, size,
2262                                        flags);
2263         clock_gettime_mono(&ts2);
2264         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2265
2266         if (timediff > audit_timeout) {
2267                 smb_time_audit_log_fname("setxattr", timediff, path);
2268         }
2269
2270         return result;
2271 }
2272
2273 static int smb_time_audit_fsetxattr(struct vfs_handle_struct *handle,
2274                                     struct files_struct *fsp, const char *name,
2275                                     const void *value, size_t size, int flags)
2276 {
2277         int result;
2278         struct timespec ts1,ts2;
2279         double timediff;
2280
2281         clock_gettime_mono(&ts1);
2282         result = SMB_VFS_NEXT_FSETXATTR(handle, fsp, name, value, size, flags);
2283         clock_gettime_mono(&ts2);
2284         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2285
2286         if (timediff > audit_timeout) {
2287                 smb_time_audit_log_fsp("fsetxattr", timediff, fsp);
2288         }
2289
2290         return result;
2291 }
2292
2293 static bool smb_time_audit_aio_force(struct vfs_handle_struct *handle,
2294                                      struct files_struct *fsp)
2295 {
2296         bool result;
2297         struct timespec ts1,ts2;
2298         double timediff;
2299
2300         clock_gettime_mono(&ts1);
2301         result = SMB_VFS_NEXT_AIO_FORCE(handle, fsp);
2302         clock_gettime_mono(&ts2);
2303         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2304
2305         if (timediff > audit_timeout) {
2306                 smb_time_audit_log_fsp("aio_force", timediff, fsp);
2307         }
2308
2309         return result;
2310 }
2311
2312 static bool smb_time_audit_is_offline(struct vfs_handle_struct *handle,
2313                                       const struct smb_filename *fname,
2314                                       SMB_STRUCT_STAT *sbuf)
2315 {
2316         bool result;
2317         struct timespec ts1,ts2;
2318         double timediff;
2319
2320         clock_gettime_mono(&ts1);
2321         result = SMB_VFS_NEXT_IS_OFFLINE(handle, fname, sbuf);
2322         clock_gettime_mono(&ts2);
2323         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2324
2325         if (timediff > audit_timeout) {
2326                 smb_time_audit_log_smb_fname("is_offline", timediff, fname);
2327         }
2328
2329         return result;
2330 }
2331
2332 static int smb_time_audit_set_offline(struct vfs_handle_struct *handle,
2333                                       const struct smb_filename *fname)
2334 {
2335         int result;
2336         struct timespec ts1,ts2;
2337         double timediff;
2338
2339         clock_gettime_mono(&ts1);
2340         result = SMB_VFS_NEXT_SET_OFFLINE(handle, fname);
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_smb_fname("set_offline", timediff, fname);
2346         }
2347
2348         return result;
2349 }
2350
2351 static NTSTATUS smb_time_audit_durable_cookie(struct vfs_handle_struct *handle,
2352                                               struct files_struct *fsp,
2353                                               TALLOC_CTX *mem_ctx,
2354                                               DATA_BLOB *cookie)
2355 {
2356         NTSTATUS result;
2357         struct timespec ts1,ts2;
2358         double timediff;
2359
2360         clock_gettime_mono(&ts1);
2361         result = SMB_VFS_NEXT_DURABLE_COOKIE(handle, fsp, mem_ctx, cookie);
2362         clock_gettime_mono(&ts2);
2363         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2364
2365         if (timediff > audit_timeout) {
2366                 smb_time_audit_log_fsp("durable_cookie", timediff, fsp);
2367         }
2368
2369         return result;
2370 }
2371
2372 static NTSTATUS smb_time_audit_durable_disconnect(struct vfs_handle_struct *handle,
2373                                                   struct files_struct *fsp,
2374                                                   const DATA_BLOB old_cookie,
2375                                                   TALLOC_CTX *mem_ctx,
2376                                                   DATA_BLOB *new_cookie)
2377 {
2378         NTSTATUS result;
2379         struct timespec ts1,ts2;
2380         double timediff;
2381
2382         clock_gettime_mono(&ts1);
2383         result = SMB_VFS_NEXT_DURABLE_DISCONNECT(handle, fsp, old_cookie,
2384                                                  mem_ctx, new_cookie);
2385         clock_gettime_mono(&ts2);
2386         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2387
2388         if (timediff > audit_timeout) {
2389                 smb_time_audit_log_fsp("durable_disconnect", timediff, fsp);
2390         }
2391
2392         return result;
2393 }
2394
2395 static NTSTATUS smb_time_audit_durable_reconnect(struct vfs_handle_struct *handle,
2396                                                  struct smb_request *smb1req,
2397                                                  struct smbXsrv_open *op,
2398                                                  const DATA_BLOB old_cookie,
2399                                                  TALLOC_CTX *mem_ctx,
2400                                                  struct files_struct **fsp,
2401                                                  DATA_BLOB *new_cookie)
2402 {
2403         NTSTATUS result;
2404         struct timespec ts1,ts2;
2405         double timediff;
2406
2407         clock_gettime_mono(&ts1);
2408         result = SMB_VFS_NEXT_DURABLE_RECONNECT(handle, smb1req, op, old_cookie,
2409                                                 mem_ctx, fsp, new_cookie);
2410         clock_gettime_mono(&ts2);
2411         timediff = nsec_time_diff(&ts2,&ts1)*1.0e-9;
2412
2413         if (timediff > audit_timeout) {
2414                 smb_time_audit_log("durable_reconnect", timediff);
2415         }
2416
2417         return result;
2418 }
2419
2420 /* VFS operations */
2421
2422 static struct vfs_fn_pointers vfs_time_audit_fns = {
2423         .connect_fn = smb_time_audit_connect,
2424         .disconnect_fn = smb_time_audit_disconnect,
2425         .disk_free_fn = smb_time_audit_disk_free,
2426         .get_quota_fn = smb_time_audit_get_quota,
2427         .set_quota_fn = smb_time_audit_set_quota,
2428         .get_shadow_copy_data_fn = smb_time_audit_get_shadow_copy_data,
2429         .statvfs_fn = smb_time_audit_statvfs,
2430         .fs_capabilities_fn = smb_time_audit_fs_capabilities,
2431         .snap_check_path_fn = smb_time_audit_snap_check_path,
2432         .snap_create_fn = smb_time_audit_snap_create,
2433         .snap_delete_fn = smb_time_audit_snap_delete,
2434         .opendir_fn = smb_time_audit_opendir,
2435         .fdopendir_fn = smb_time_audit_fdopendir,
2436         .readdir_fn = smb_time_audit_readdir,
2437         .seekdir_fn = smb_time_audit_seekdir,
2438         .telldir_fn = smb_time_audit_telldir,
2439         .rewind_dir_fn = smb_time_audit_rewinddir,
2440         .mkdir_fn = smb_time_audit_mkdir,
2441         .rmdir_fn = smb_time_audit_rmdir,
2442         .closedir_fn = smb_time_audit_closedir,
2443         .init_search_op_fn = smb_time_audit_init_search_op,
2444         .open_fn = smb_time_audit_open,
2445         .create_file_fn = smb_time_audit_create_file,
2446         .close_fn = smb_time_audit_close,
2447         .read_fn = smb_time_audit_read,
2448         .pread_fn = smb_time_audit_pread,
2449         .pread_send_fn = smb_time_audit_pread_send,
2450         .pread_recv_fn = smb_time_audit_pread_recv,
2451         .write_fn = smb_time_audit_write,
2452         .pwrite_fn = smb_time_audit_pwrite,
2453         .pwrite_send_fn = smb_time_audit_pwrite_send,
2454         .pwrite_recv_fn = smb_time_audit_pwrite_recv,
2455         .lseek_fn = smb_time_audit_lseek,
2456         .sendfile_fn = smb_time_audit_sendfile,
2457         .recvfile_fn = smb_time_audit_recvfile,
2458         .rename_fn = smb_time_audit_rename,
2459         .fsync_fn = smb_time_audit_fsync,
2460         .fsync_send_fn = smb_time_audit_fsync_send,
2461         .fsync_recv_fn = smb_time_audit_fsync_recv,
2462         .stat_fn = smb_time_audit_stat,
2463         .fstat_fn = smb_time_audit_fstat,
2464         .lstat_fn = smb_time_audit_lstat,
2465         .get_alloc_size_fn = smb_time_audit_get_alloc_size,
2466         .unlink_fn = smb_time_audit_unlink,
2467         .chmod_fn = smb_time_audit_chmod,
2468         .fchmod_fn = smb_time_audit_fchmod,
2469         .chown_fn = smb_time_audit_chown,
2470         .fchown_fn = smb_time_audit_fchown,
2471         .lchown_fn = smb_time_audit_lchown,
2472         .chdir_fn = smb_time_audit_chdir,
2473         .getwd_fn = smb_time_audit_getwd,
2474         .ntimes_fn = smb_time_audit_ntimes,
2475         .ftruncate_fn = smb_time_audit_ftruncate,
2476         .fallocate_fn = smb_time_audit_fallocate,
2477         .lock_fn = smb_time_audit_lock,
2478         .kernel_flock_fn = smb_time_audit_kernel_flock,
2479         .linux_setlease_fn = smb_time_audit_linux_setlease,
2480         .getlock_fn = smb_time_audit_getlock,
2481         .symlink_fn = smb_time_audit_symlink,
2482         .readlink_fn = smb_time_audit_readlink,
2483         .link_fn = smb_time_audit_link,
2484         .mknod_fn = smb_time_audit_mknod,
2485         .realpath_fn = smb_time_audit_realpath,
2486         .chflags_fn = smb_time_audit_chflags,
2487         .file_id_create_fn = smb_time_audit_file_id_create,
2488         .streaminfo_fn = smb_time_audit_streaminfo,
2489         .get_real_filename_fn = smb_time_audit_get_real_filename,
2490         .connectpath_fn = smb_time_audit_connectpath,
2491         .brl_lock_windows_fn = smb_time_audit_brl_lock_windows,
2492         .brl_unlock_windows_fn = smb_time_audit_brl_unlock_windows,
2493         .brl_cancel_windows_fn = smb_time_audit_brl_cancel_windows,
2494         .strict_lock_fn = smb_time_audit_strict_lock,
2495         .strict_unlock_fn = smb_time_audit_strict_unlock,
2496         .translate_name_fn = smb_time_audit_translate_name,
2497         .copy_chunk_send_fn = smb_time_audit_copy_chunk_send,
2498         .copy_chunk_recv_fn = smb_time_audit_copy_chunk_recv,
2499         .get_compression_fn = smb_time_audit_get_compression,
2500         .set_compression_fn = smb_time_audit_set_compression,
2501         .readdir_attr_fn = smb_time_audit_readdir_attr,
2502         .fget_nt_acl_fn = smb_time_audit_fget_nt_acl,
2503         .get_nt_acl_fn = smb_time_audit_get_nt_acl,
2504         .fset_nt_acl_fn = smb_time_audit_fset_nt_acl,
2505         .chmod_acl_fn = smb_time_audit_chmod_acl,
2506         .fchmod_acl_fn = smb_time_audit_fchmod_acl,
2507         .sys_acl_get_file_fn = smb_time_audit_sys_acl_get_file,
2508         .sys_acl_get_fd_fn = smb_time_audit_sys_acl_get_fd,
2509         .sys_acl_blob_get_file_fn = smb_time_audit_sys_acl_blob_get_file,
2510         .sys_acl_blob_get_fd_fn = smb_time_audit_sys_acl_blob_get_fd,
2511         .sys_acl_set_file_fn = smb_time_audit_sys_acl_set_file,
2512         .sys_acl_set_fd_fn = smb_time_audit_sys_acl_set_fd,
2513         .sys_acl_delete_def_file_fn = smb_time_audit_sys_acl_delete_def_file,
2514         .getxattr_fn = smb_time_audit_getxattr,
2515         .fgetxattr_fn = smb_time_audit_fgetxattr,
2516         .listxattr_fn = smb_time_audit_listxattr,
2517         .flistxattr_fn = smb_time_audit_flistxattr,
2518         .removexattr_fn = smb_time_audit_removexattr,
2519         .fremovexattr_fn = smb_time_audit_fremovexattr,
2520         .setxattr_fn = smb_time_audit_setxattr,
2521         .fsetxattr_fn = smb_time_audit_fsetxattr,
2522         .aio_force_fn = smb_time_audit_aio_force,
2523         .is_offline_fn = smb_time_audit_is_offline,
2524         .set_offline_fn = smb_time_audit_set_offline,
2525         .durable_cookie_fn = smb_time_audit_durable_cookie,
2526         .durable_disconnect_fn = smb_time_audit_durable_disconnect,
2527         .durable_reconnect_fn = smb_time_audit_durable_reconnect,
2528 };
2529
2530
2531 NTSTATUS vfs_time_audit_init(void);
2532 NTSTATUS vfs_time_audit_init(void)
2533 {
2534         audit_timeout = (double)lp_parm_int(-1, "time_audit", "timeout",
2535                                             10000) / 1000.0;
2536         return smb_register_vfs(SMB_VFS_INTERFACE_VERSION, "time_audit",
2537                                 &vfs_time_audit_fns);
2538 }