"./vfs.test -test.v -test.timeout 30m0s -remote TestOneDrive: -verbose -test.run '^TestRWFileHandleOpenTests$'" - Starting (try 3/5) === RUN TestRWFileHandleOpenTests 2020/03/09 06:13:56 INFO : One drive root 'rclone-test-lucowit2qajikih9vebaduy0': poll-interval is not supported by this remote 2020/03/09 06:13:56 DEBUG : vfs cache root is "/home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-lucowit2qajikih9vebaduy0" 2020/03/09 06:13:56 DEBUG : Adding path "vfs/forget" to remote control registry 2020/03/09 06:13:56 DEBUG : Adding path "vfs/refresh" to remote control registry 2020/03/09 06:13:56 DEBUG : Adding path "vfs/poll-interval" to remote control registry 2020/03/09 06:13:56 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=-rw-rw-rw- 2020/03/09 06:13:56 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:13:56 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:13:56 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:13:56 DEBUG : open-test-file(0xc0001fbfc0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:13:56 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:13:56 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:13:56 DEBUG : open-test-file(0xc0001fbfc0): close: 2020/03/09 06:13:56 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:13:57 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:13:58 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:13:58 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:13:58 INFO : open-test-file: Copied (new) 2020/03/09 06:13:58 DEBUG : open-test-file: transferred to remote 2020/03/09 06:13:58 DEBUG : open-test-file(0xc0001fbfc0): >close: err= 2020/03/09 06:13:58 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=-rw-rw-rw- 2020/03/09 06:13:58 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:13:58 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:13:58 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:13:58 DEBUG : open-test-file: Size and modification time the same (differ by 536.094963ms, within tolerance 1s) 2020/03/09 06:13:58 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:13:58 DEBUG : open-test-file(0xc000080340): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:13:58 DEBUG : open-test-file(0xc000080340): close: 2020/03/09 06:13:58 DEBUG : open-test-file(0xc000080340): >close: err= 2020/03/09 06:13:58 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:13:58 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:13:58 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:13:58 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:13:58 DEBUG : open-test-file: Size and modification time the same (differ by 536.094963ms, within tolerance 1s) 2020/03/09 06:13:58 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:13:58 DEBUG : open-test-file(0xc000080380): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:13:58 DEBUG : open-test-file(0xc000080380): close: 2020/03/09 06:13:58 DEBUG : open-test-file(0xc000080380): >close: err= 2020/03/09 06:13:59 INFO : open-test-file: Removed from cache 2020/03/09 06:13:59 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:13:59 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:13:59 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:13:59 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000080940): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:13:59 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:13:59 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000080940): close: 2020/03/09 06:13:59 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:13:59 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:13:59 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:13:59 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:13:59 INFO : open-test-file: Copied (new) 2020/03/09 06:13:59 DEBUG : open-test-file: transferred to remote 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000080940): >close: err= 2020/03/09 06:13:59 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:13:59 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:13:59 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:13:59 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:13:59 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:13:59 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:13:59 DEBUG : open-test-file: Size and modification time the same (differ by 96.134765ms, within tolerance 1s) 2020/03/09 06:13:59 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000080c80): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000080c80): close: 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000080c80): >close: err= 2020/03/09 06:13:59 INFO : open-test-file: Removed from cache 2020/03/09 06:13:59 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:13:59 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:13:59 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:13:59 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000124240): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:13:59 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:13:59 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:13:59 DEBUG : open-test-file(0xc000124240): close: 2020/03/09 06:13:59 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:13:59 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:00 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:00 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:00 INFO : open-test-file: Copied (new) 2020/03/09 06:14:00 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:00 DEBUG : open-test-file(0xc000124240): >close: err= 2020/03/09 06:14:00 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:00 DEBUG : open-test-file: Open: flags=O_RDONLY|O_SYNC 2020/03/09 06:14:00 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:00 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:00 DEBUG : open-test-file: Size and modification time the same (differ by 988.148634ms, within tolerance 1s) 2020/03/09 06:14:00 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:00 DEBUG : open-test-file(0xc0001246c0): Opened existing cached copy with flags=O_RDONLY|O_SYNC 2020/03/09 06:14:00 DEBUG : open-test-file(0xc0001246c0): close: 2020/03/09 06:14:00 DEBUG : open-test-file(0xc0001246c0): >close: err= 2020/03/09 06:14:00 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:00 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:00 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:00 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:00 DEBUG : open-test-file: Size and modification time the same (differ by 988.148634ms, within tolerance 1s) 2020/03/09 06:14:00 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:00 DEBUG : open-test-file(0xc000124700): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:00 DEBUG : open-test-file(0xc000124700): close: 2020/03/09 06:14:00 DEBUG : open-test-file(0xc000124700): >close: err= 2020/03/09 06:14:01 INFO : open-test-file: Removed from cache 2020/03/09 06:14:01 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:01 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:01 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:01 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:01 DEBUG : open-test-file(0xc000124780): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:01 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:01 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:01 DEBUG : open-test-file(0xc000124780): close: 2020/03/09 06:14:01 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:01 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:01 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:01 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:01 INFO : open-test-file: Copied (new) 2020/03/09 06:14:01 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:01 DEBUG : open-test-file(0xc000124780): >close: err= 2020/03/09 06:14:01 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:01 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:01 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:01 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:01 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:01 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:01 DEBUG : open-test-file: Size and modification time the same (differ by 160.166855ms, within tolerance 1s) 2020/03/09 06:14:01 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:01 DEBUG : open-test-file(0xc000081040): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:01 DEBUG : open-test-file(0xc000081040): close: 2020/03/09 06:14:01 DEBUG : open-test-file(0xc000081040): >close: err= 2020/03/09 06:14:02 INFO : open-test-file: Removed from cache 2020/03/09 06:14:02 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:02 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:02 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:02 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:02 DEBUG : open-test-file(0xc000124b00): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:02 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:02 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:02 DEBUG : open-test-file(0xc000124b00): close: 2020/03/09 06:14:02 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:02 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:02 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:02 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:02 INFO : open-test-file: Copied (new) 2020/03/09 06:14:02 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:02 DEBUG : open-test-file(0xc000124b00): >close: err= 2020/03/09 06:14:02 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:02 DEBUG : open-test-file: Open: flags=O_RDONLY|O_EXCL 2020/03/09 06:14:02 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:02 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:02 DEBUG : open-test-file: Size and modification time the same (differ by 332.185077ms, within tolerance 1s) 2020/03/09 06:14:02 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:02 DEBUG : open-test-file(0xc0001fb400): Opened existing cached copy with flags=O_RDONLY|O_EXCL 2020/03/09 06:14:02 DEBUG : open-test-file(0xc0001fb400): close: 2020/03/09 06:14:02 DEBUG : open-test-file(0xc0001fb400): >close: err= 2020/03/09 06:14:02 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:02 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:02 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:02 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:02 DEBUG : open-test-file: Size and modification time the same (differ by 332.185077ms, within tolerance 1s) 2020/03/09 06:14:02 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:02 DEBUG : open-test-file(0xc0001fb480): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:02 DEBUG : open-test-file(0xc0001fb480): close: 2020/03/09 06:14:02 DEBUG : open-test-file(0xc0001fb480): >close: err= 2020/03/09 06:14:03 INFO : open-test-file: Removed from cache 2020/03/09 06:14:03 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:03 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:03 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:03 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:03 DEBUG : open-test-file(0xc0001fb540): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:03 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:03 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:03 DEBUG : open-test-file(0xc0001fb540): close: 2020/03/09 06:14:03 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:03 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:03 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:03 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:03 INFO : open-test-file: Copied (new) 2020/03/09 06:14:03 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:03 DEBUG : open-test-file(0xc0001fb540): >close: err= 2020/03/09 06:14:03 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:03 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:03 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:03 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:03 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:03 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:03 DEBUG : open-test-file: Size and modification time the same (differ by 212.198759ms, within tolerance 1s) 2020/03/09 06:14:03 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:03 DEBUG : open-test-file(0xc000081400): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:03 DEBUG : open-test-file(0xc000081400): close: 2020/03/09 06:14:03 DEBUG : open-test-file(0xc000081400): >close: err= 2020/03/09 06:14:04 INFO : open-test-file: Removed from cache 2020/03/09 06:14:04 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:04 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:04 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:04 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:04 DEBUG : open-test-file(0xc000124e40): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:04 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file(0xc000124e40): close: 2020/03/09 06:14:04 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:04 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:04 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:04 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:04 INFO : open-test-file: Copied (new) 2020/03/09 06:14:04 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:04 DEBUG : open-test-file(0xc000124e40): >close: err= 2020/03/09 06:14:04 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:04 DEBUG : open-test-file: Open: flags=O_RDONLY|O_EXCL|O_SYNC 2020/03/09 06:14:04 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file: Size and modification time the same (differ by 60.211943ms, within tolerance 1s) 2020/03/09 06:14:04 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce440): Opened existing cached copy with flags=O_RDONLY|O_EXCL|O_SYNC 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce440): close: 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce440): >close: err= 2020/03/09 06:14:04 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:04 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:04 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file: Size and modification time the same (differ by 60.211943ms, within tolerance 1s) 2020/03/09 06:14:04 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce500): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce500): close: 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce500): >close: err= 2020/03/09 06:14:04 INFO : open-test-file: Removed from cache 2020/03/09 06:14:04 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:04 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:04 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:04 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce600): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:04 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:04 DEBUG : open-test-file(0xc0004ce600): close: 2020/03/09 06:14:04 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:04 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:05 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:05 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:05 INFO : open-test-file: Copied (new) 2020/03/09 06:14:05 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:05 DEBUG : open-test-file(0xc0004ce600): >close: err= 2020/03/09 06:14:05 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:05 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:05 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:05 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:05 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:05 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:05 DEBUG : open-test-file: Size and modification time the same (differ by 924.225376ms, within tolerance 1s) 2020/03/09 06:14:05 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:05 DEBUG : open-test-file(0xc0004ce980): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:05 DEBUG : open-test-file(0xc0004ce980): close: 2020/03/09 06:14:05 DEBUG : open-test-file(0xc0004ce980): >close: err= 2020/03/09 06:14:06 INFO : open-test-file: Removed from cache 2020/03/09 06:14:06 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:14:06 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE 2020/03/09 06:14:06 DEBUG : open-test-file(0xc0001facc0): Opening cached copy with flags=O_RDONLY|O_CREATE 2020/03/09 06:14:06 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file(0xc0001facc0): close: 2020/03/09 06:14:06 DEBUG : open-test-file(0xc0001facc0): >close: err= 2020/03/09 06:14:06 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:06 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:06 DEBUG : open-test-file(0xc0001fad40): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:06 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file(0xc0001fad40): close: 2020/03/09 06:14:06 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:06 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:06 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:06 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:06 INFO : open-test-file: Copied (new) 2020/03/09 06:14:06 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:06 DEBUG : open-test-file(0xc0001fad40): >close: err= 2020/03/09 06:14:06 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:14:06 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE 2020/03/09 06:14:06 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file: Size and modification time the same (differ by 164.244655ms, within tolerance 1s) 2020/03/09 06:14:06 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:06 DEBUG : open-test-file(0xc000080b40): Opened existing cached copy with flags=O_RDONLY|O_CREATE 2020/03/09 06:14:06 DEBUG : open-test-file(0xc000080b40): close: 2020/03/09 06:14:06 DEBUG : open-test-file(0xc000080b40): >close: err= 2020/03/09 06:14:06 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:06 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:06 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:06 DEBUG : open-test-file: Size and modification time the same (differ by 164.244655ms, within tolerance 1s) 2020/03/09 06:14:06 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:06 DEBUG : open-test-file(0xc000080b80): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:06 DEBUG : open-test-file(0xc000080b80): close: 2020/03/09 06:14:06 DEBUG : open-test-file(0xc000080b80): >close: err= 2020/03/09 06:14:07 INFO : open-test-file: Removed from cache 2020/03/09 06:14:07 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:07 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:07 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:07 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:07 DEBUG : open-test-file(0xc000080c00): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:07 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:07 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:07 DEBUG : open-test-file(0xc000080c00): close: 2020/03/09 06:14:07 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:07 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:07 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:07 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:07 INFO : open-test-file: Copied (new) 2020/03/09 06:14:07 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:07 DEBUG : open-test-file(0xc000080c00): >close: err= 2020/03/09 06:14:07 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:07 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:07 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:07 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:07 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:07 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:07 DEBUG : open-test-file: Size and modification time the same (differ by 4.257714ms, within tolerance 1s) 2020/03/09 06:14:07 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:07 DEBUG : open-test-file(0xc000081200): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:07 DEBUG : open-test-file(0xc000081200): close: 2020/03/09 06:14:07 DEBUG : open-test-file(0xc000081200): >close: err= 2020/03/09 06:14:08 INFO : open-test-file: Removed from cache 2020/03/09 06:14:08 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:08 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE|O_SYNC 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0004ceb00): Opening cached copy with flags=O_RDONLY|O_CREATE|O_SYNC 2020/03/09 06:14:08 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0004ceb00): close: 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0004ceb00): >close: err= 2020/03/09 06:14:08 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:08 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0004ceb80): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:08 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0004ceb80): close: 2020/03/09 06:14:08 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:08 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:08 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:08 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:08 INFO : open-test-file: Copied (new) 2020/03/09 06:14:08 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0004ceb80): >close: err= 2020/03/09 06:14:08 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:08 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE|O_SYNC 2020/03/09 06:14:08 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file: Size and modification time the same (differ by 212.276495ms, within tolerance 1s) 2020/03/09 06:14:08 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0000812c0): Opened existing cached copy with flags=O_RDONLY|O_CREATE|O_SYNC 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0000812c0): close: 2020/03/09 06:14:08 DEBUG : open-test-file(0xc0000812c0): >close: err= 2020/03/09 06:14:08 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:08 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:08 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:08 DEBUG : open-test-file: Size and modification time the same (differ by 212.276495ms, within tolerance 1s) 2020/03/09 06:14:08 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:08 DEBUG : open-test-file(0xc000081340): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:08 DEBUG : open-test-file(0xc000081340): close: 2020/03/09 06:14:08 DEBUG : open-test-file(0xc000081340): >close: err= 2020/03/09 06:14:09 INFO : open-test-file: Removed from cache 2020/03/09 06:14:09 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:09 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:09 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:09 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:09 DEBUG : open-test-file(0xc0004cef80): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:09 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:09 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:09 DEBUG : open-test-file(0xc0004cef80): close: 2020/03/09 06:14:09 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:09 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:09 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:09 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:09 INFO : open-test-file: Copied (new) 2020/03/09 06:14:09 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:09 DEBUG : open-test-file(0xc0004cef80): >close: err= 2020/03/09 06:14:09 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:09 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:09 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:09 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:09 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:09 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:09 DEBUG : open-test-file: Size and modification time the same (differ by 128.290736ms, within tolerance 1s) 2020/03/09 06:14:09 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:09 DEBUG : open-test-file(0xc0000816c0): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:09 DEBUG : open-test-file(0xc0000816c0): close: 2020/03/09 06:14:09 DEBUG : open-test-file(0xc0000816c0): >close: err= 2020/03/09 06:14:10 INFO : open-test-file: Removed from cache 2020/03/09 06:14:10 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:10 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE|O_EXCL 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081780): Opening cached copy with flags=O_RDONLY|O_CREATE|O_EXCL 2020/03/09 06:14:10 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081780): close: 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081780): >close: err= 2020/03/09 06:14:10 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:10 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081800): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:10 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081800): close: 2020/03/09 06:14:10 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:10 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:10 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:10 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:10 INFO : open-test-file: Copied (new) 2020/03/09 06:14:10 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081800): >close: err= 2020/03/09 06:14:10 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:10 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE|O_EXCL 2020/03/09 06:14:10 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:10 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:10 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:10 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:10 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:10 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file: Size and modification time the same (differ by 996.304232ms, within tolerance 1s) 2020/03/09 06:14:10 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081d40): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081d40): close: 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081d40): >close: err= 2020/03/09 06:14:10 INFO : open-test-file: Removed from cache 2020/03/09 06:14:10 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:10 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:10 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:10 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081dc0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:10 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:10 DEBUG : open-test-file(0xc000081dc0): close: 2020/03/09 06:14:10 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:10 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:11 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:11 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:11 INFO : open-test-file: Copied (new) 2020/03/09 06:14:11 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000081dc0): >close: err= 2020/03/09 06:14:11 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:11 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:11 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:11 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:11 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:11 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:11 DEBUG : open-test-file: Size and modification time the same (differ by 848.317479ms, within tolerance 1s) 2020/03/09 06:14:11 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000081f40): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000081f40): close: 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000081f40): >close: err= 2020/03/09 06:14:11 INFO : open-test-file: Removed from cache 2020/03/09 06:14:11 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:11 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000760280): Opening cached copy with flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:11 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:11 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000760280): close: 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000760280): >close: err= 2020/03/09 06:14:11 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:11 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000760300): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:11 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:11 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:11 DEBUG : open-test-file(0xc000760300): close: 2020/03/09 06:14:11 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:11 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:12 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:12 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:12 INFO : open-test-file: Copied (new) 2020/03/09 06:14:12 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:12 DEBUG : open-test-file(0xc000760300): >close: err= 2020/03/09 06:14:12 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:12 DEBUG : open-test-file: Open: flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:12 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:12 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:12 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:12 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:12 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:12 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:12 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:12 DEBUG : open-test-file: Size and modification time the same (differ by 708.330849ms, within tolerance 1s) 2020/03/09 06:14:12 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:12 DEBUG : open-test-file(0xc0004cf580): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:12 DEBUG : open-test-file(0xc0004cf580): close: 2020/03/09 06:14:12 DEBUG : open-test-file(0xc0004cf580): >close: err= 2020/03/09 06:14:13 INFO : open-test-file: Removed from cache 2020/03/09 06:14:13 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:13 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:13 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:13 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:13 DEBUG : open-test-file(0xc0004cf600): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:13 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:13 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:13 DEBUG : open-test-file(0xc0004cf600): close: 2020/03/09 06:14:13 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:13 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:13 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:13 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:13 INFO : open-test-file: Copied (new) 2020/03/09 06:14:13 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:13 DEBUG : open-test-file(0xc0004cf600): >close: err= 2020/03/09 06:14:13 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:13 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:13 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:13 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:13 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:13 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:13 DEBUG : open-test-file: Size and modification time the same (differ by 44.35162ms, within tolerance 1s) 2020/03/09 06:14:13 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:13 DEBUG : open-test-file(0xc000124200): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:13 DEBUG : open-test-file(0xc000124200): close: 2020/03/09 06:14:13 DEBUG : open-test-file(0xc000124200): >close: err= 2020/03/09 06:14:13 INFO : open-test-file: Removed from cache 2020/03/09 06:14:13 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND, perm=-rw-rw-rw- 2020/03/09 06:14:13 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:13 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:13 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:13 DEBUG : open-test-file(0xc0004cfac0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:13 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:13 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:13 DEBUG : open-test-file(0xc0004cfac0): close: 2020/03/09 06:14:13 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:13 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:14 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:14 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:14 INFO : open-test-file: Copied (new) 2020/03/09 06:14:14 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:14 DEBUG : open-test-file(0xc0004cfac0): >close: err= 2020/03/09 06:14:14 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND, perm=-rw-rw-rw- 2020/03/09 06:14:14 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND 2020/03/09 06:14:14 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:14 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:14 DEBUG : open-test-file: Size and modification time the same (differ by 936.365488ms, within tolerance 1s) 2020/03/09 06:14:14 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760640): Opened existing cached copy with flags=O_RDONLY|O_APPEND 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760640): close: 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760640): >close: err= 2020/03/09 06:14:14 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:14 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:14 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:14 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:14 DEBUG : open-test-file: Size and modification time the same (differ by 936.365488ms, within tolerance 1s) 2020/03/09 06:14:14 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760680): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760680): close: 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760680): >close: err= 2020/03/09 06:14:14 INFO : open-test-file: Removed from cache 2020/03/09 06:14:14 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:14 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:14 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:14 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760740): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:14 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:14 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:14 DEBUG : open-test-file(0xc000760740): close: 2020/03/09 06:14:14 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:14 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:15 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:15 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:15 INFO : open-test-file: Copied (new) 2020/03/09 06:14:15 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:15 DEBUG : open-test-file(0xc000760740): >close: err= 2020/03/09 06:14:15 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:15 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:15 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:15 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:15 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:15 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:15 DEBUG : open-test-file: Size and modification time the same (differ by 808.379045ms, within tolerance 1s) 2020/03/09 06:14:15 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:15 DEBUG : open-test-file(0xc000124b00): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:15 DEBUG : open-test-file(0xc000124b00): close: 2020/03/09 06:14:15 DEBUG : open-test-file(0xc000124b00): >close: err= 2020/03/09 06:14:15 INFO : open-test-file: Removed from cache 2020/03/09 06:14:15 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:15 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:15 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:15 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:15 DEBUG : open-test-file(0xc0004cfc40): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:15 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:15 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:15 DEBUG : open-test-file(0xc0004cfc40): close: 2020/03/09 06:14:15 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:15 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:16 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:16 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:16 INFO : open-test-file: Copied (new) 2020/03/09 06:14:16 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:16 DEBUG : open-test-file(0xc0004cfc40): >close: err= 2020/03/09 06:14:16 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:16 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_SYNC 2020/03/09 06:14:16 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:16 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:16 DEBUG : open-test-file: Size and modification time the same (differ by 660.392291ms, within tolerance 1s) 2020/03/09 06:14:16 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:16 DEBUG : open-test-file(0xc000124e00): Opened existing cached copy with flags=O_RDONLY|O_APPEND|O_SYNC 2020/03/09 06:14:16 DEBUG : open-test-file(0xc000124e00): close: 2020/03/09 06:14:16 DEBUG : open-test-file(0xc000124e00): >close: err= 2020/03/09 06:14:16 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:16 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:16 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:16 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:16 DEBUG : open-test-file: Size and modification time the same (differ by 660.392291ms, within tolerance 1s) 2020/03/09 06:14:16 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:16 DEBUG : open-test-file(0xc000124f00): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:16 DEBUG : open-test-file(0xc000124f00): close: 2020/03/09 06:14:16 DEBUG : open-test-file(0xc000124f00): >close: err= 2020/03/09 06:14:16 INFO : open-test-file: Removed from cache 2020/03/09 06:14:16 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:16 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:16 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:16 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:16 DEBUG : open-test-file(0xc0007609c0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:16 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:16 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:16 DEBUG : open-test-file(0xc0007609c0): close: 2020/03/09 06:14:16 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:16 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:16 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:17 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:17 INFO : open-test-file: Copied (new) 2020/03/09 06:14:17 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:17 DEBUG : open-test-file(0xc0007609c0): >close: err= 2020/03/09 06:14:17 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:17 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:17 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:17 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:17 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: Size and modification time the same (differ by 536.405911ms, within tolerance 1s) 2020/03/09 06:14:17 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000125340): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000125340): close: 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000125340): >close: err= 2020/03/09 06:14:17 INFO : open-test-file: Removed from cache 2020/03/09 06:14:17 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:17 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:17 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:17 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:17 DEBUG : open-test-file(0xc0001253c0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:17 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file(0xc0001253c0): close: 2020/03/09 06:14:17 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:17 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:17 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:17 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:17 INFO : open-test-file: Copied (new) 2020/03/09 06:14:17 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:17 DEBUG : open-test-file(0xc0001253c0): >close: err= 2020/03/09 06:14:17 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:17 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_EXCL 2020/03/09 06:14:17 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: Size and modification time the same (differ by 416.419592ms, within tolerance 1s) 2020/03/09 06:14:17 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000760d80): Opened existing cached copy with flags=O_RDONLY|O_APPEND|O_EXCL 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000760d80): close: 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000760d80): >close: err= 2020/03/09 06:14:17 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:17 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:17 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:17 DEBUG : open-test-file: Size and modification time the same (differ by 416.419592ms, within tolerance 1s) 2020/03/09 06:14:17 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000760e00): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000760e00): close: 2020/03/09 06:14:17 DEBUG : open-test-file(0xc000760e00): >close: err= 2020/03/09 06:14:18 INFO : open-test-file: Removed from cache 2020/03/09 06:14:18 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:18 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:18 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:18 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:18 DEBUG : open-test-file(0xc0001256c0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:18 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:18 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:18 DEBUG : open-test-file(0xc0001256c0): close: 2020/03/09 06:14:18 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:18 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:18 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:18 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:18 INFO : open-test-file: Copied (new) 2020/03/09 06:14:18 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:18 DEBUG : open-test-file(0xc0001256c0): >close: err= 2020/03/09 06:14:18 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:18 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:18 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:18 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:18 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:18 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:18 DEBUG : open-test-file: Size and modification time the same (differ by 276.432963ms, within tolerance 1s) 2020/03/09 06:14:18 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:18 DEBUG : open-test-file(0xc0001383c0): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:18 DEBUG : open-test-file(0xc0001383c0): close: 2020/03/09 06:14:18 DEBUG : open-test-file(0xc0001383c0): >close: err= 2020/03/09 06:14:19 INFO : open-test-file: Removed from cache 2020/03/09 06:14:19 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:19 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:19 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:19 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:19 DEBUG : open-test-file(0xc000080080): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:19 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:19 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:19 DEBUG : open-test-file(0xc000080080): close: 2020/03/09 06:14:19 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:19 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:19 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:19 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:19 INFO : open-test-file: Copied (new) 2020/03/09 06:14:19 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:19 DEBUG : open-test-file(0xc000080080): >close: err= 2020/03/09 06:14:19 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:19 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_EXCL|O_SYNC 2020/03/09 06:14:19 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:19 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:19 DEBUG : open-test-file: Size and modification time the same (differ by 212.447515ms, within tolerance 1s) 2020/03/09 06:14:19 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:19 DEBUG : open-test-file(0xc0001faf40): Opened existing cached copy with flags=O_RDONLY|O_APPEND|O_EXCL|O_SYNC 2020/03/09 06:14:19 DEBUG : open-test-file(0xc0001faf40): close: 2020/03/09 06:14:19 DEBUG : open-test-file(0xc0001faf40): >close: err= 2020/03/09 06:14:19 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:19 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:19 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:19 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:19 DEBUG : open-test-file: Size and modification time the same (differ by 212.447515ms, within tolerance 1s) 2020/03/09 06:14:19 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:19 DEBUG : open-test-file(0xc0001fb040): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:19 DEBUG : open-test-file(0xc0001fb040): close: 2020/03/09 06:14:19 DEBUG : open-test-file(0xc0001fb040): >close: err= 2020/03/09 06:14:20 INFO : open-test-file: Removed from cache 2020/03/09 06:14:20 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:20 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:20 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:20 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:20 DEBUG : open-test-file(0xc0001fb180): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:20 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:20 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:20 DEBUG : open-test-file(0xc0001fb180): close: 2020/03/09 06:14:20 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:20 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:20 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:20 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:20 INFO : open-test-file: Copied (new) 2020/03/09 06:14:20 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:20 DEBUG : open-test-file(0xc0001fb180): >close: err= 2020/03/09 06:14:20 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:20 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:20 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:20 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:20 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:20 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:20 DEBUG : open-test-file: Size and modification time the same (differ by 60.460699ms, within tolerance 1s) 2020/03/09 06:14:20 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:20 DEBUG : open-test-file(0xc0004ce600): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:20 DEBUG : open-test-file(0xc0004ce600): close: 2020/03/09 06:14:20 DEBUG : open-test-file(0xc0004ce600): >close: err= 2020/03/09 06:14:21 INFO : open-test-file: Removed from cache 2020/03/09 06:14:21 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:14:21 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000080b00): Opening cached copy with flags=O_RDONLY|O_APPEND|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000080b00): close: 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000080b00): >close: err= 2020/03/09 06:14:21 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:21 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000080b80): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000080b80): close: 2020/03/09 06:14:21 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:21 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:21 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:21 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:21 INFO : open-test-file: Copied (new) 2020/03/09 06:14:21 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000080b80): >close: err= 2020/03/09 06:14:21 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:14:21 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: Size and modification time the same (differ by 20.475624ms, within tolerance 1s) 2020/03/09 06:14:21 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000081200): Opened existing cached copy with flags=O_RDONLY|O_APPEND|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000081200): close: 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000081200): >close: err= 2020/03/09 06:14:21 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:21 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:21 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: Size and modification time the same (differ by 20.475624ms, within tolerance 1s) 2020/03/09 06:14:21 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000081280): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000081280): close: 2020/03/09 06:14:21 DEBUG : open-test-file(0xc000081280): >close: err= 2020/03/09 06:14:21 INFO : open-test-file: Removed from cache 2020/03/09 06:14:21 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:21 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:21 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:21 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file(0xc0004ce780): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:21 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:21 DEBUG : open-test-file(0xc0004ce780): close: 2020/03/09 06:14:21 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:21 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:22 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:22 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:22 INFO : open-test-file: Copied (new) 2020/03/09 06:14:22 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004ce780): >close: err= 2020/03/09 06:14:22 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:22 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:22 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:22 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:22 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:22 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:22 DEBUG : open-test-file: Size and modification time the same (differ by 908.48943ms, within tolerance 1s) 2020/03/09 06:14:22 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004cec80): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004cec80): close: 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004cec80): >close: err= 2020/03/09 06:14:22 INFO : open-test-file: Removed from cache 2020/03/09 06:14:22 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:22 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004ced80): Opening cached copy with flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC 2020/03/09 06:14:22 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:22 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004ced80): close: 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004ced80): >close: err= 2020/03/09 06:14:22 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:22 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004cee00): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:22 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:22 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:22 DEBUG : open-test-file(0xc0004cee00): close: 2020/03/09 06:14:22 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:22 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:23 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:23 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:23 INFO : open-test-file: Copied (new) 2020/03/09 06:14:23 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:23 DEBUG : open-test-file(0xc0004cee00): >close: err= 2020/03/09 06:14:23 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:23 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC 2020/03/09 06:14:23 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:23 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:23 DEBUG : open-test-file: Size and modification time the same (differ by 764.502739ms, within tolerance 1s) 2020/03/09 06:14:23 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:23 DEBUG : open-test-file(0xc0000816c0): Opened existing cached copy with flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC 2020/03/09 06:14:23 DEBUG : open-test-file(0xc0000816c0): close: 2020/03/09 06:14:23 DEBUG : open-test-file(0xc0000816c0): >close: err= 2020/03/09 06:14:23 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:23 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:23 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:23 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:23 DEBUG : open-test-file: Size and modification time the same (differ by 764.502739ms, within tolerance 1s) 2020/03/09 06:14:23 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:23 DEBUG : open-test-file(0xc000081740): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:23 DEBUG : open-test-file(0xc000081740): close: 2020/03/09 06:14:23 DEBUG : open-test-file(0xc000081740): >close: err= 2020/03/09 06:14:23 INFO : open-test-file: Removed from cache 2020/03/09 06:14:23 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:23 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:23 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:23 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:23 DEBUG : open-test-file(0xc000081800): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:23 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:23 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:23 DEBUG : open-test-file(0xc000081800): close: 2020/03/09 06:14:23 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:23 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:23 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:24 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:24 INFO : open-test-file: Copied (new) 2020/03/09 06:14:24 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:24 DEBUG : open-test-file(0xc000081800): >close: err= 2020/03/09 06:14:24 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:24 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:24 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:24 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:24 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:24 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:24 DEBUG : open-test-file: Size and modification time the same (differ by 624.51611ms, within tolerance 1s) 2020/03/09 06:14:24 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:24 DEBUG : open-test-file(0xc0001242c0): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:24 DEBUG : open-test-file(0xc0001242c0): close: 2020/03/09 06:14:24 DEBUG : open-test-file(0xc0001242c0): >close: err= 2020/03/09 06:14:24 INFO : open-test-file: Removed from cache 2020/03/09 06:14:24 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:24 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL 2020/03/09 06:14:24 DEBUG : open-test-file(0xc000124380): Opening cached copy with flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL 2020/03/09 06:14:24 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:24 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:24 DEBUG : open-test-file(0xc000124380): close: 2020/03/09 06:14:24 DEBUG : open-test-file(0xc000124380): >close: err= 2020/03/09 06:14:24 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:24 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:24 DEBUG : open-test-file(0xc000124400): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:24 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:24 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:24 DEBUG : open-test-file(0xc000124400): close: 2020/03/09 06:14:24 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:24 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:24 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:25 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:25 INFO : open-test-file: Copied (new) 2020/03/09 06:14:25 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:25 DEBUG : open-test-file(0xc000124400): >close: err= 2020/03/09 06:14:25 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:25 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL 2020/03/09 06:14:25 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:25 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:25 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:25 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:25 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:25 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:25 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:25 DEBUG : open-test-file: Size and modification time the same (differ by 488.529542ms, within tolerance 1s) 2020/03/09 06:14:25 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:25 DEBUG : open-test-file(0xc000124780): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:25 DEBUG : open-test-file(0xc000124780): close: 2020/03/09 06:14:25 DEBUG : open-test-file(0xc000124780): >close: err= 2020/03/09 06:14:25 INFO : open-test-file: Removed from cache 2020/03/09 06:14:25 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:25 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:25 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:25 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:25 DEBUG : open-test-file(0xc000138580): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:25 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:25 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:25 DEBUG : open-test-file(0xc000138580): close: 2020/03/09 06:14:25 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:25 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:25 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:26 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:26 INFO : open-test-file: Copied (new) 2020/03/09 06:14:26 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000138580): >close: err= 2020/03/09 06:14:26 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:26 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:26 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:26 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:26 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:26 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:26 DEBUG : open-test-file: Size and modification time the same (differ by 640.547453ms, within tolerance 1s) 2020/03/09 06:14:26 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000138900): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000138900): close: 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000138900): >close: err= 2020/03/09 06:14:26 INFO : open-test-file: Removed from cache 2020/03/09 06:14:26 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:26 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000124900): Opening cached copy with flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:26 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:26 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000124900): close: 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000124900): >close: err= 2020/03/09 06:14:26 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:26 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000124980): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:26 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:26 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:26 DEBUG : open-test-file(0xc000124980): close: 2020/03/09 06:14:26 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:26 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:26 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:27 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:27 INFO : open-test-file: Copied (new) 2020/03/09 06:14:27 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:27 DEBUG : open-test-file(0xc000124980): >close: err= 2020/03/09 06:14:27 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:27 DEBUG : open-test-file: Open: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:27 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:27 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:27 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:27 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:27 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:27 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:27 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:27 DEBUG : open-test-file: Size and modification time the same (differ by 492.560699ms, within tolerance 1s) 2020/03/09 06:14:27 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:27 DEBUG : open-test-file(0xc000138c40): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:27 DEBUG : open-test-file(0xc000138c40): close: 2020/03/09 06:14:27 DEBUG : open-test-file(0xc000138c40): >close: err= 2020/03/09 06:14:27 INFO : open-test-file: Removed from cache 2020/03/09 06:14:27 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:27 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:27 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:27 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:27 DEBUG : open-test-file(0xc000760080): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:27 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:27 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:27 DEBUG : open-test-file(0xc000760080): close: 2020/03/09 06:14:27 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:27 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:28 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:28 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:28 INFO : open-test-file: Copied (new) 2020/03/09 06:14:28 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:28 DEBUG : open-test-file(0xc000760080): >close: err= 2020/03/09 06:14:28 DEBUG : open-test-file: OpenFile: flags=O_RDONLY|O_APPEND|O_CREATE|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:28 DEBUG : open-test-file: >OpenFile: fd=, err=invalid argument 2020/03/09 06:14:28 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:28 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:28 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:28 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:28 DEBUG : open-test-file: Size and modification time the same (differ by 880.582278ms, within tolerance 1s) 2020/03/09 06:14:28 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:28 DEBUG : open-test-file(0xc000124f00): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:28 DEBUG : open-test-file(0xc000124f00): close: 2020/03/09 06:14:28 DEBUG : open-test-file(0xc000124f00): >close: err= 2020/03/09 06:14:28 INFO : open-test-file: Removed from cache 2020/03/09 06:14:28 DEBUG : open-test-file: OpenFile: flags=O_WRONLY, perm=-rw-rw-rw- 2020/03/09 06:14:28 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:28 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:28 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:28 DEBUG : open-test-file(0xc000760240): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:28 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:28 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:28 DEBUG : open-test-file(0xc000760240): close: 2020/03/09 06:14:28 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:28 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:29 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:29 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:29 INFO : open-test-file: Copied (new) 2020/03/09 06:14:29 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:29 DEBUG : open-test-file(0xc000760240): >close: err= 2020/03/09 06:14:29 DEBUG : open-test-file: OpenFile: flags=O_WRONLY, perm=-rw-rw-rw- 2020/03/09 06:14:29 DEBUG : open-test-file: Open: flags=O_WRONLY 2020/03/09 06:14:29 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:29 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:29 DEBUG : open-test-file: Size and modification time the same (differ by 736.595586ms, within tolerance 1s) 2020/03/09 06:14:29 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:29 DEBUG : open-test-file(0xc0007607c0): Opened existing cached copy with flags=O_WRONLY 2020/03/09 06:14:29 DEBUG : open-test-file(0xc0007607c0): close: 2020/03/09 06:14:29 DEBUG : open-test-file: Modification times differ by -1.300604354s: 2020-03-09 06:14:29.300604354 +0000 UTC, 2020-03-09 06:14:28 +0000 UTC 2020/03/09 06:14:29 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 (Local file system at /home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-lucowit2qajikih9vebaduy0) 2020/03/09 06:14:29 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d (One drive root 'rclone-test-lucowit2qajikih9vebaduy0') 2020/03/09 06:14:29 DEBUG : open-test-file: SHA-1 differ 2020/03/09 06:14:29 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:29 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:29 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 OK 2020/03/09 06:14:29 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:29 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:29 DEBUG : open-test-file(0xc0007607c0): >close: err= 2020/03/09 06:14:29 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:29 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:29 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:29 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:29 DEBUG : open-test-file: Size and modification time the same (differ by 300.604354ms, within tolerance 1s) 2020/03/09 06:14:29 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:29 DEBUG : open-test-file(0xc000139040): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:29 DEBUG : open-test-file(0xc000139040): close: 2020/03/09 06:14:29 DEBUG : open-test-file(0xc000139040): >close: err= 2020/03/09 06:14:30 INFO : open-test-file: Removed from cache 2020/03/09 06:14:30 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:30 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:30 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:30 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:30 DEBUG : open-test-file(0xc000139080): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:30 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:30 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:30 DEBUG : open-test-file(0xc000139080): close: 2020/03/09 06:14:30 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:30 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:30 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:30 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:30 INFO : open-test-file: Copied (new) 2020/03/09 06:14:30 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:30 DEBUG : open-test-file(0xc000139080): >close: err= 2020/03/09 06:14:30 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:30 DEBUG : open-test-file: Open: flags=O_WRONLY|O_TRUNC 2020/03/09 06:14:30 DEBUG : open-test-file(0xc000760dc0): Opening cached copy with flags=O_WRONLY|O_TRUNC 2020/03/09 06:14:30 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:30 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:30 DEBUG : open-test-file(0xc000760dc0): close: 2020/03/09 06:14:30 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:30 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:31 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:14:31 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:14:31 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:31 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:31 DEBUG : open-test-file(0xc000760dc0): >close: err= 2020/03/09 06:14:31 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:31 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:31 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:31 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:31 DEBUG : open-test-file: Size and modification time the same (differ by 740.626742ms, within tolerance 1s) 2020/03/09 06:14:31 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:31 DEBUG : open-test-file(0xc000139340): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:31 DEBUG : open-test-file(0xc000139340): close: 2020/03/09 06:14:31 DEBUG : open-test-file(0xc000139340): >close: err= 2020/03/09 06:14:31 INFO : open-test-file: Removed from cache 2020/03/09 06:14:31 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:31 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:31 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:31 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:31 DEBUG : open-test-file(0xc000188440): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:31 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:31 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:31 DEBUG : open-test-file(0xc000188440): close: 2020/03/09 06:14:31 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:31 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:31 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:32 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:32 INFO : open-test-file: Copied (new) 2020/03/09 06:14:32 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000188440): >close: err= 2020/03/09 06:14:32 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:32 DEBUG : open-test-file: Open: flags=O_WRONLY|O_SYNC 2020/03/09 06:14:32 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:32 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:32 DEBUG : open-test-file: Size and modification time the same (differ by 608.640237ms, within tolerance 1s) 2020/03/09 06:14:32 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000080b80): Opened existing cached copy with flags=O_WRONLY|O_SYNC 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000080b80): close: 2020/03/09 06:14:32 DEBUG : open-test-file: Modification times differ by -1.192649316s: 2020-03-09 06:14:32.192649316 +0000 UTC, 2020-03-09 06:14:31 +0000 UTC 2020/03/09 06:14:32 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 (Local file system at /home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-lucowit2qajikih9vebaduy0) 2020/03/09 06:14:32 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d (One drive root 'rclone-test-lucowit2qajikih9vebaduy0') 2020/03/09 06:14:32 DEBUG : open-test-file: SHA-1 differ 2020/03/09 06:14:32 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:32 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:32 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 OK 2020/03/09 06:14:32 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:32 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000080b80): >close: err= 2020/03/09 06:14:32 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:32 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:32 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:32 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:32 DEBUG : open-test-file: Size and modification time the same (differ by 192.649316ms, within tolerance 1s) 2020/03/09 06:14:32 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000760500): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000760500): close: 2020/03/09 06:14:32 DEBUG : open-test-file(0xc000760500): >close: err= 2020/03/09 06:14:33 INFO : open-test-file: Removed from cache 2020/03/09 06:14:33 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:33 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:33 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:33 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:33 DEBUG : open-test-file(0xc000080f80): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:33 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:33 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:33 DEBUG : open-test-file(0xc000080f80): close: 2020/03/09 06:14:33 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:33 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:33 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:33 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:33 INFO : open-test-file: Copied (new) 2020/03/09 06:14:33 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:33 DEBUG : open-test-file(0xc000080f80): >close: err= 2020/03/09 06:14:33 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:33 DEBUG : open-test-file: Open: flags=O_WRONLY|O_SYNC|O_TRUNC 2020/03/09 06:14:33 DEBUG : open-test-file(0xc000760940): Opening cached copy with flags=O_WRONLY|O_SYNC|O_TRUNC 2020/03/09 06:14:33 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:33 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:33 DEBUG : open-test-file(0xc000760940): close: 2020/03/09 06:14:33 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:33 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:34 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:14:34 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:14:34 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:34 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:34 DEBUG : open-test-file(0xc000760940): >close: err= 2020/03/09 06:14:34 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:34 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:34 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:34 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:34 DEBUG : open-test-file: Size and modification time the same (differ by 656.672077ms, within tolerance 1s) 2020/03/09 06:14:34 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:34 DEBUG : open-test-file(0xc000081480): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:34 DEBUG : open-test-file(0xc000081480): close: 2020/03/09 06:14:34 DEBUG : open-test-file(0xc000081480): >close: err= 2020/03/09 06:14:34 INFO : open-test-file: Removed from cache 2020/03/09 06:14:34 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:34 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:34 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:34 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:34 DEBUG : open-test-file(0xc000760c40): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:34 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:34 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:34 DEBUG : open-test-file(0xc000760c40): close: 2020/03/09 06:14:34 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:34 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:35 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:35 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:35 INFO : open-test-file: Copied (new) 2020/03/09 06:14:35 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:35 DEBUG : open-test-file(0xc000760c40): >close: err= 2020/03/09 06:14:35 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:35 DEBUG : open-test-file: Open: flags=O_WRONLY|O_EXCL 2020/03/09 06:14:35 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:35 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:35 DEBUG : open-test-file: Size and modification time the same (differ by 636.687313ms, within tolerance 1s) 2020/03/09 06:14:35 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:35 DEBUG : open-test-file(0xc0001fb0c0): Opened existing cached copy with flags=O_WRONLY|O_EXCL 2020/03/09 06:14:35 DEBUG : open-test-file(0xc0001fb0c0): close: 2020/03/09 06:14:35 DEBUG : open-test-file: Modification times differ by -1.496700684s: 2020-03-09 06:14:35.496700684 +0000 UTC, 2020-03-09 06:14:34 +0000 UTC 2020/03/09 06:14:35 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 (Local file system at /home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-lucowit2qajikih9vebaduy0) 2020/03/09 06:14:35 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d (One drive root 'rclone-test-lucowit2qajikih9vebaduy0') 2020/03/09 06:14:35 DEBUG : open-test-file: SHA-1 differ 2020/03/09 06:14:35 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:35 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:36 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 OK 2020/03/09 06:14:36 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:36 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:36 DEBUG : open-test-file(0xc0001fb0c0): >close: err= 2020/03/09 06:14:36 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:36 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:36 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:36 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:36 DEBUG : open-test-file: Size and modification time the same (differ by 496.700684ms, within tolerance 1s) 2020/03/09 06:14:36 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:36 DEBUG : open-test-file(0xc000081780): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:36 DEBUG : open-test-file(0xc000081780): close: 2020/03/09 06:14:36 DEBUG : open-test-file(0xc000081780): >close: err= 2020/03/09 06:14:36 INFO : open-test-file: Removed from cache 2020/03/09 06:14:36 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:36 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:36 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:36 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:36 DEBUG : open-test-file(0xc0007611c0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:36 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:36 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:36 DEBUG : open-test-file(0xc0007611c0): close: 2020/03/09 06:14:36 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:36 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:36 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:36 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:36 INFO : open-test-file: Copied (new) 2020/03/09 06:14:36 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:36 DEBUG : open-test-file(0xc0007611c0): >close: err= 2020/03/09 06:14:36 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:36 DEBUG : open-test-file: Open: flags=O_WRONLY|O_EXCL|O_TRUNC 2020/03/09 06:14:36 DEBUG : open-test-file(0xc000761540): Opening cached copy with flags=O_WRONLY|O_EXCL|O_TRUNC 2020/03/09 06:14:36 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:36 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:36 DEBUG : open-test-file(0xc000761540): close: 2020/03/09 06:14:36 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:36 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:37 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:14:37 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:14:37 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:37 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:37 DEBUG : open-test-file(0xc000761540): >close: err= 2020/03/09 06:14:37 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:37 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:37 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:37 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:37 DEBUG : open-test-file: Size and modification time the same (differ by 944.723196ms, within tolerance 1s) 2020/03/09 06:14:37 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:37 DEBUG : open-test-file(0xc000761a00): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:37 DEBUG : open-test-file(0xc000761a00): close: 2020/03/09 06:14:37 DEBUG : open-test-file(0xc000761a00): >close: err= 2020/03/09 06:14:40 INFO : open-test-file: Removed from cache 2020/03/09 06:14:40 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:40 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:40 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:40 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:40 DEBUG : open-test-file(0xc0004ce180): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:40 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:40 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:40 DEBUG : open-test-file(0xc0004ce180): close: 2020/03/09 06:14:40 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:40 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:41 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:41 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:41 INFO : open-test-file: Copied (new) 2020/03/09 06:14:41 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:41 DEBUG : open-test-file(0xc0004ce180): >close: err= 2020/03/09 06:14:41 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:41 DEBUG : open-test-file: Open: flags=O_WRONLY|O_EXCL|O_SYNC 2020/03/09 06:14:41 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:41 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:41 DEBUG : open-test-file: Size and modification time the same (differ by 980.785942ms, within tolerance 1s) 2020/03/09 06:14:41 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:41 DEBUG : open-test-file(0xc000081d40): Opened existing cached copy with flags=O_WRONLY|O_EXCL|O_SYNC 2020/03/09 06:14:41 DEBUG : open-test-file(0xc000081d40): close: 2020/03/09 06:14:41 DEBUG : open-test-file: Modification times differ by -1.916800495s: 2020-03-09 06:14:41.916800495 +0000 UTC, 2020-03-09 06:14:40 +0000 UTC 2020/03/09 06:14:41 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 (Local file system at /home/rclone/.cache/rclone/vfs/TestOneDrive/rclone-test-lucowit2qajikih9vebaduy0) 2020/03/09 06:14:41 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d (One drive root 'rclone-test-lucowit2qajikih9vebaduy0') 2020/03/09 06:14:41 DEBUG : open-test-file: SHA-1 differ 2020/03/09 06:14:41 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:42 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:42 DEBUG : open-test-file: SHA-1 = c43a17390bfb39fea55dda4ee9c2da6838d17125 OK 2020/03/09 06:14:42 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:42 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:42 DEBUG : open-test-file(0xc000081d40): >close: err= 2020/03/09 06:14:42 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:42 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:42 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:42 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:42 DEBUG : open-test-file: Size and modification time the same (differ by 916.800495ms, within tolerance 1s) 2020/03/09 06:14:42 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:42 DEBUG : open-test-file(0xc0004ce840): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:42 DEBUG : open-test-file(0xc0004ce840): close: 2020/03/09 06:14:42 DEBUG : open-test-file(0xc0004ce840): >close: err= 2020/03/09 06:14:43 INFO : open-test-file: Removed from cache 2020/03/09 06:14:43 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:43 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:43 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:43 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:43 DEBUG : open-test-file(0xc0004ce8c0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:43 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:43 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:43 DEBUG : open-test-file(0xc0004ce8c0): close: 2020/03/09 06:14:43 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:43 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:43 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:43 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:43 INFO : open-test-file: Copied (new) 2020/03/09 06:14:43 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:43 DEBUG : open-test-file(0xc0004ce8c0): >close: err= 2020/03/09 06:14:43 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:43 DEBUG : open-test-file: Open: flags=O_WRONLY|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:14:43 DEBUG : open-test-file(0xc0004cea80): Opening cached copy with flags=O_WRONLY|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:14:43 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:43 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:43 DEBUG : open-test-file(0xc0004cea80): close: 2020/03/09 06:14:43 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:43 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:44 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:14:44 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:14:44 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:44 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:44 DEBUG : open-test-file(0xc0004cea80): >close: err= 2020/03/09 06:14:44 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:44 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:44 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:44 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:44 DEBUG : open-test-file: Size and modification time the same (differ by 656.827546ms, within tolerance 1s) 2020/03/09 06:14:44 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:44 DEBUG : open-test-file(0xc0004cee80): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:44 DEBUG : open-test-file(0xc0004cee80): close: 2020/03/09 06:14:44 DEBUG : open-test-file(0xc0004cee80): >close: err= 2020/03/09 06:14:44 INFO : open-test-file: Removed from cache 2020/03/09 06:14:44 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:14:44 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:44 DEBUG : open-test-file(0xc000124780): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:44 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:44 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:44 DEBUG : open-test-file(0xc000124780): close: 2020/03/09 06:14:44 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:44 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:44 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:45 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:45 INFO : open-test-file: Copied (new) 2020/03/09 06:14:45 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:45 DEBUG : open-test-file(0xc000124780): >close: err= 2020/03/09 06:14:45 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:45 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:45 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:45 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:45 DEBUG : open-test-file: Size and modification time the same (differ by 560.8416ms, within tolerance 1s) 2020/03/09 06:14:45 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:45 DEBUG : open-test-file(0xc000124b00): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:45 DEBUG : open-test-file(0xc000124b00): close: 2020/03/09 06:14:45 DEBUG : open-test-file: Modification times differ by -1.304853167s: 2020-03-09 06:14:45.304853167 +0000 UTC, 2020-03-09 06:14:44 +0000 UTC 2020/03/09 06:14:45 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:45 INFO : open-test-file: Updated modification time in destination 2020/03/09 06:14:45 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:45 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:45 DEBUG : open-test-file(0xc000124b00): >close: err= 2020/03/09 06:14:45 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:14:45 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:45 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:45 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:45 DEBUG : open-test-file: Size and modification time the same (differ by 304.853167ms, within tolerance 1s) 2020/03/09 06:14:45 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:45 DEBUG : open-test-file(0xc0004cf280): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:45 DEBUG : open-test-file(0xc0004cf280): close: 2020/03/09 06:14:45 DEBUG : open-test-file: Size and modification time the same (differ by -840.8615ms, within tolerance 1s) 2020/03/09 06:14:45 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:45 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:45 DEBUG : open-test-file(0xc0004cf280): >close: err= 2020/03/09 06:14:45 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:45 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:45 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:45 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:45 DEBUG : open-test-file: Size and modification time the same (differ by 840.8615ms, within tolerance 1s) 2020/03/09 06:14:45 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:45 DEBUG : open-test-file(0xc0004cf2c0): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:45 DEBUG : open-test-file(0xc0004cf2c0): close: 2020/03/09 06:14:45 DEBUG : open-test-file(0xc0004cf2c0): >close: err= 2020/03/09 06:14:46 INFO : open-test-file: Removed from cache 2020/03/09 06:14:46 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:46 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2020/03/09 06:14:46 DEBUG : open-test-file(0xc0001384c0): Opening cached copy with flags=O_WRONLY|O_CREATE|O_TRUNC 2020/03/09 06:14:46 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:46 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:46 DEBUG : open-test-file(0xc0001384c0): close: 2020/03/09 06:14:46 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:46 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:46 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:46 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:46 INFO : open-test-file: Copied (new) 2020/03/09 06:14:46 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:46 DEBUG : open-test-file(0xc0001384c0): >close: err= 2020/03/09 06:14:46 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:46 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:46 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:46 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:46 DEBUG : open-test-file: Size and modification time the same (differ by 132.86604ms, within tolerance 1s) 2020/03/09 06:14:46 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:46 DEBUG : open-test-file(0xc000761bc0): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:46 DEBUG : open-test-file(0xc000761bc0): close: 2020/03/09 06:14:46 DEBUG : open-test-file: Size and modification time the same (differ by -684.874622ms, within tolerance 1s) 2020/03/09 06:14:46 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:46 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:46 DEBUG : open-test-file(0xc000761bc0): >close: err= 2020/03/09 06:14:46 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:46 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_TRUNC 2020/03/09 06:14:46 DEBUG : open-test-file(0xc000761c80): Opening cached copy with flags=O_WRONLY|O_CREATE|O_TRUNC 2020/03/09 06:14:46 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:46 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:46 DEBUG : open-test-file(0xc000761c80): close: 2020/03/09 06:14:46 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:46 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:47 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:14:47 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:14:47 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:47 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:47 DEBUG : open-test-file(0xc000761c80): >close: err= 2020/03/09 06:14:47 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:47 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:47 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:47 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:47 DEBUG : open-test-file: Size and modification time the same (differ by 684.874622ms, within tolerance 1s) 2020/03/09 06:14:47 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:47 DEBUG : open-test-file(0xc000760380): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:47 DEBUG : open-test-file(0xc000760380): close: 2020/03/09 06:14:47 DEBUG : open-test-file(0xc000760380): >close: err= 2020/03/09 06:14:48 INFO : open-test-file: Removed from cache 2020/03/09 06:14:48 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:48 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_SYNC 2020/03/09 06:14:48 DEBUG : open-test-file(0xc0001fad40): Opening cached copy with flags=O_WRONLY|O_CREATE|O_SYNC 2020/03/09 06:14:48 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file(0xc0001fad40): close: 2020/03/09 06:14:48 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:48 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:48 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:48 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:48 INFO : open-test-file: Copied (new) 2020/03/09 06:14:48 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:48 DEBUG : open-test-file(0xc0001fad40): >close: err= 2020/03/09 06:14:48 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:48 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:48 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: Size and modification time the same (differ by 236.898749ms, within tolerance 1s) 2020/03/09 06:14:48 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080a40): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080a40): close: 2020/03/09 06:14:48 DEBUG : open-test-file: Size and modification time the same (differ by -860.908451ms, within tolerance 1s) 2020/03/09 06:14:48 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:48 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080a40): >close: err= 2020/03/09 06:14:48 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:48 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_SYNC 2020/03/09 06:14:48 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: Size and modification time the same (differ by 860.908451ms, within tolerance 1s) 2020/03/09 06:14:48 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080b00): Opened existing cached copy with flags=O_WRONLY|O_CREATE|O_SYNC 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080b00): close: 2020/03/09 06:14:48 DEBUG : open-test-file: Size and modification time the same (differ by -860.908451ms, within tolerance 1s) 2020/03/09 06:14:48 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:48 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080b00): >close: err= 2020/03/09 06:14:48 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:48 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:48 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:48 DEBUG : open-test-file: Size and modification time the same (differ by 860.908451ms, within tolerance 1s) 2020/03/09 06:14:48 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080b80): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080b80): close: 2020/03/09 06:14:48 DEBUG : open-test-file(0xc000080b80): >close: err= 2020/03/09 06:14:49 INFO : open-test-file: Removed from cache 2020/03/09 06:14:49 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:49 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_SYNC|O_TRUNC 2020/03/09 06:14:49 DEBUG : open-test-file(0xc000080cc0): Opening cached copy with flags=O_WRONLY|O_CREATE|O_SYNC|O_TRUNC 2020/03/09 06:14:49 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:49 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:49 DEBUG : open-test-file(0xc000080cc0): close: 2020/03/09 06:14:49 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:49 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:49 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:49 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:49 INFO : open-test-file: Copied (new) 2020/03/09 06:14:49 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:49 DEBUG : open-test-file(0xc000080cc0): >close: err= 2020/03/09 06:14:49 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:49 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:49 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:49 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:49 DEBUG : open-test-file: Size and modification time the same (differ by 164.913178ms, within tolerance 1s) 2020/03/09 06:14:49 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:49 DEBUG : open-test-file(0xc0000811c0): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:49 DEBUG : open-test-file(0xc0000811c0): close: 2020/03/09 06:14:49 DEBUG : open-test-file: Size and modification time the same (differ by -884.924371ms, within tolerance 1s) 2020/03/09 06:14:49 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:49 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:49 DEBUG : open-test-file(0xc0000811c0): >close: err= 2020/03/09 06:14:49 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:49 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_SYNC|O_TRUNC 2020/03/09 06:14:49 DEBUG : open-test-file(0xc000081280): Opening cached copy with flags=O_WRONLY|O_CREATE|O_SYNC|O_TRUNC 2020/03/09 06:14:49 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:49 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:49 DEBUG : open-test-file(0xc000081280): close: 2020/03/09 06:14:49 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:49 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:50 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:14:50 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:14:50 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:50 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:50 DEBUG : open-test-file(0xc000081280): >close: err= 2020/03/09 06:14:50 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:50 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:50 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:50 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:50 DEBUG : open-test-file: Size and modification time the same (differ by 884.924371ms, within tolerance 1s) 2020/03/09 06:14:50 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:50 DEBUG : open-test-file(0xc000081400): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:50 DEBUG : open-test-file(0xc000081400): close: 2020/03/09 06:14:50 DEBUG : open-test-file(0xc000081400): >close: err= 2020/03/09 06:14:51 INFO : open-test-file: Removed from cache 2020/03/09 06:14:51 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:51 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL 2020/03/09 06:14:51 DEBUG : open-test-file(0xc000760b40): Opening cached copy with flags=O_WRONLY|O_CREATE|O_EXCL 2020/03/09 06:14:51 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:51 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:51 DEBUG : open-test-file(0xc000760b40): close: 2020/03/09 06:14:51 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:51 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:51 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:52 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:52 INFO : open-test-file: Copied (new) 2020/03/09 06:14:52 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000760b40): >close: err= 2020/03/09 06:14:52 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:52 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:52 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:52 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:52 DEBUG : open-test-file: Size and modification time the same (differ by 540.950116ms, within tolerance 1s) 2020/03/09 06:14:52 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000760e80): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000760e80): close: 2020/03/09 06:14:52 DEBUG : open-test-file: Modification times differ by -1.124959196s: 2020-03-09 06:14:52.124959196 +0000 UTC, 2020-03-09 06:14:51 +0000 UTC 2020/03/09 06:14:52 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:52 INFO : open-test-file: Updated modification time in destination 2020/03/09 06:14:52 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:52 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000760e80): >close: err= 2020/03/09 06:14:52 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:14:52 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL 2020/03/09 06:14:52 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:52 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:52 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:52 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:52 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:52 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:52 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:52 DEBUG : open-test-file: Size and modification time the same (differ by 124.959196ms, within tolerance 1s) 2020/03/09 06:14:52 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000081640): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000081640): close: 2020/03/09 06:14:52 DEBUG : open-test-file(0xc000081640): >close: err= 2020/03/09 06:14:53 INFO : open-test-file: Removed from cache 2020/03/09 06:14:53 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:53 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL|O_TRUNC 2020/03/09 06:14:53 DEBUG : open-test-file(0xc0004ce400): Opening cached copy with flags=O_WRONLY|O_CREATE|O_EXCL|O_TRUNC 2020/03/09 06:14:53 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:53 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:53 DEBUG : open-test-file(0xc0004ce400): close: 2020/03/09 06:14:53 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:53 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:53 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:53 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:53 INFO : open-test-file: Copied (new) 2020/03/09 06:14:53 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:53 DEBUG : open-test-file(0xc0004ce400): >close: err= 2020/03/09 06:14:53 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:53 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:53 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:53 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:53 DEBUG : open-test-file: Size and modification time the same (differ by 996.972752ms, within tolerance 1s) 2020/03/09 06:14:53 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:53 DEBUG : open-test-file(0xc000081b40): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:53 DEBUG : open-test-file(0xc000081b40): close: 2020/03/09 06:14:53 DEBUG : open-test-file: Modification times differ by -1.57698177s: 2020-03-09 06:14:53.57698177 +0000 UTC, 2020-03-09 06:14:52 +0000 UTC 2020/03/09 06:14:53 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:53 INFO : open-test-file: Updated modification time in destination 2020/03/09 06:14:53 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:53 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:53 DEBUG : open-test-file(0xc000081b40): >close: err= 2020/03/09 06:14:53 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:53 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL|O_TRUNC 2020/03/09 06:14:53 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:53 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:53 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:53 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:53 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:53 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:53 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:53 DEBUG : open-test-file: Size and modification time the same (differ by 576.98177ms, within tolerance 1s) 2020/03/09 06:14:53 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:53 DEBUG : open-test-file(0xc000081dc0): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:53 DEBUG : open-test-file(0xc000081dc0): close: 2020/03/09 06:14:53 DEBUG : open-test-file(0xc000081dc0): >close: err= 2020/03/09 06:14:54 INFO : open-test-file: Removed from cache 2020/03/09 06:14:54 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:54 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:54 DEBUG : open-test-file(0xc000761240): Opening cached copy with flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:54 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:54 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:54 DEBUG : open-test-file(0xc000761240): close: 2020/03/09 06:14:54 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:54 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:54 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:55 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:55 INFO : open-test-file: Copied (new) 2020/03/09 06:14:55 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:55 DEBUG : open-test-file(0xc000761240): >close: err= 2020/03/09 06:14:55 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:55 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:55 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:55 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:55 DEBUG : open-test-file: Size and modification time the same (differ by 264.992466ms, within tolerance 1s) 2020/03/09 06:14:55 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:55 DEBUG : open-test-file(0xc0004cea00): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:55 DEBUG : open-test-file(0xc0004cea00): close: 2020/03/09 06:14:55 DEBUG : open-test-file: Modification times differ by -1.193006893s: 2020-03-09 06:14:55.193006893 +0000 UTC, 2020-03-09 06:14:54 +0000 UTC 2020/03/09 06:14:55 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:55 ERROR : open-test-file: Failed to set modification time: resourceModified: ETag does not match current item's value 2020/03/09 06:14:55 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:55 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:55 DEBUG : open-test-file(0xc0004cea00): >close: err= 2020/03/09 06:14:55 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:14:55 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC 2020/03/09 06:14:55 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:55 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:55 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:55 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:55 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:55 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:55 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:55 DEBUG : open-test-file: Modification times differ by 1.193006893s: 2020-03-09 06:14:54 +0000 UTC, 2020-03-09 06:14:55.193006893 +0000 UTC 2020/03/09 06:14:55 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:55 INFO : open-test-file: Updated modification time in destination 2020/03/09 06:14:55 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:55 DEBUG : open-test-file(0xc000761640): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:55 DEBUG : open-test-file(0xc000761640): close: 2020/03/09 06:14:55 DEBUG : open-test-file(0xc000761640): >close: err= 2020/03/09 06:14:56 INFO : open-test-file: Removed from cache 2020/03/09 06:14:56 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:56 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:14:56 DEBUG : open-test-file(0xc000761840): Opening cached copy with flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:14:56 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:56 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:56 DEBUG : open-test-file(0xc000761840): close: 2020/03/09 06:14:56 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:56 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:56 INFO : Cleaned the cache: objects 2 (was 2), total size 5 (was 0) 2020/03/09 06:14:56 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:56 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:56 INFO : open-test-file: Copied (new) 2020/03/09 06:14:56 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:56 DEBUG : open-test-file(0xc000761840): >close: err= 2020/03/09 06:14:56 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:56 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:56 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:56 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:56 DEBUG : open-test-file: Size and modification time the same (differ by 165.022004ms, within tolerance 1s) 2020/03/09 06:14:56 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:56 DEBUG : open-test-file(0xc0004cf040): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:56 DEBUG : open-test-file(0xc0004cf040): close: 2020/03/09 06:14:56 DEBUG : open-test-file: Size and modification time the same (differ by -833.032389ms, within tolerance 1s) 2020/03/09 06:14:56 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:56 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:56 DEBUG : open-test-file(0xc0004cf040): >close: err= 2020/03/09 06:14:56 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:56 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:14:56 ERROR : open-test-file: File.openRW failed: file already exists 2020/03/09 06:14:56 DEBUG : open-test-file: >Open: fd=, err=file already exists 2020/03/09 06:14:56 DEBUG : open-test-file: >OpenFile: fd=, err=file already exists 2020/03/09 06:14:56 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:56 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:56 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:56 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:56 DEBUG : open-test-file: Size and modification time the same (differ by 833.032389ms, within tolerance 1s) 2020/03/09 06:14:56 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:56 DEBUG : open-test-file(0xc0004cf100): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:56 DEBUG : open-test-file(0xc0004cf100): close: 2020/03/09 06:14:56 DEBUG : open-test-file(0xc0004cf100): >close: err= 2020/03/09 06:14:57 INFO : open-test-file: Removed from cache 2020/03/09 06:14:57 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND, perm=-rw-rw-rw- 2020/03/09 06:14:57 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:57 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:57 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:57 DEBUG : open-test-file(0xc000081fc0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:57 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:57 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:57 DEBUG : open-test-file(0xc000081fc0): close: 2020/03/09 06:14:57 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:57 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:57 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:58 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:58 INFO : open-test-file: Copied (new) 2020/03/09 06:14:58 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:58 DEBUG : open-test-file(0xc000081fc0): >close: err= 2020/03/09 06:14:58 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND, perm=-rw-rw-rw- 2020/03/09 06:14:58 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND 2020/03/09 06:14:58 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:58 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:58 DEBUG : open-test-file: Size and modification time the same (differ by 445.041903ms, within tolerance 1s) 2020/03/09 06:14:58 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:58 DEBUG : open-test-file(0xc0004cf380): Opened existing cached copy with flags=O_WRONLY|O_APPEND 2020/03/09 06:14:58 DEBUG : open-test-file(0xc0004cf380): close: 2020/03/09 06:14:58 DEBUG : open-test-file: Sizes differ (src 8 vs dst 5) 2020/03/09 06:14:58 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:58 DEBUG : open-test-file: Uploading segment 0/8 size 8 2020/03/09 06:14:58 DEBUG : open-test-file: SHA-1 = 4bdb719e7a59ae117ac2aa7573d9fadfd83fc7cb OK 2020/03/09 06:14:58 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:14:58 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:58 DEBUG : open-test-file(0xc0004cf380): >close: err= 2020/03/09 06:14:58 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:14:58 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:14:58 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:58 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:58 DEBUG : open-test-file: Size and modification time the same (differ by 57.051419ms, within tolerance 1s) 2020/03/09 06:14:58 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:14:58 DEBUG : open-test-file(0xc000124640): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:14:58 DEBUG : open-test-file(0xc000124640): close: 2020/03/09 06:14:58 DEBUG : open-test-file(0xc000124640): >close: err= 2020/03/09 06:14:59 INFO : open-test-file: Removed from cache 2020/03/09 06:14:59 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:59 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:14:59 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:14:59 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:14:59 DEBUG : open-test-file(0xc0001246c0): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:14:59 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:59 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:59 DEBUG : open-test-file(0xc0001246c0): close: 2020/03/09 06:14:59 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:14:59 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:14:59 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:14:59 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:14:59 INFO : open-test-file: Copied (new) 2020/03/09 06:14:59 DEBUG : open-test-file: transferred to remote 2020/03/09 06:14:59 DEBUG : open-test-file(0xc0001246c0): >close: err= 2020/03/09 06:14:59 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:14:59 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_TRUNC 2020/03/09 06:14:59 DEBUG : open-test-file(0xc000138500): Opening cached copy with flags=O_WRONLY|O_APPEND|O_TRUNC 2020/03/09 06:14:59 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:14:59 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:14:59 DEBUG : open-test-file(0xc000138500): close: 2020/03/09 06:14:59 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:14:59 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:00 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:15:00 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:15:00 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:00 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:00 DEBUG : open-test-file(0xc000138500): >close: err= 2020/03/09 06:15:00 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:00 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:00 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:00 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:00 DEBUG : open-test-file: Size and modification time the same (differ by 561.0748ms, within tolerance 1s) 2020/03/09 06:15:00 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:00 DEBUG : open-test-file(0xc000138900): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:00 DEBUG : open-test-file(0xc000138900): close: 2020/03/09 06:15:00 DEBUG : open-test-file(0xc000138900): >close: err= 2020/03/09 06:15:00 INFO : open-test-file: Removed from cache 2020/03/09 06:15:00 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:15:00 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:15:00 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:00 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:00 DEBUG : open-test-file(0xc000138980): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:00 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:00 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:00 DEBUG : open-test-file(0xc000138980): close: 2020/03/09 06:15:00 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:00 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:01 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:01 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:01 INFO : open-test-file: Copied (new) 2020/03/09 06:15:01 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:01 DEBUG : open-test-file(0xc000138980): >close: err= 2020/03/09 06:15:01 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:15:01 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_SYNC 2020/03/09 06:15:01 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:01 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:01 DEBUG : open-test-file: Size and modification time the same (differ by 757.093394ms, within tolerance 1s) 2020/03/09 06:15:01 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:01 DEBUG : open-test-file(0xc000124d00): Opened existing cached copy with flags=O_WRONLY|O_APPEND|O_SYNC 2020/03/09 06:15:01 DEBUG : open-test-file(0xc000124d00): close: 2020/03/09 06:15:01 DEBUG : open-test-file: Sizes differ (src 8 vs dst 5) 2020/03/09 06:15:01 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:01 DEBUG : open-test-file: Uploading segment 0/8 size 8 2020/03/09 06:15:01 DEBUG : open-test-file: SHA-1 = 4bdb719e7a59ae117ac2aa7573d9fadfd83fc7cb OK 2020/03/09 06:15:01 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:01 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:01 DEBUG : open-test-file(0xc000124d00): >close: err= 2020/03/09 06:15:01 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:01 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:01 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:01 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:01 DEBUG : open-test-file: Size and modification time the same (differ by 349.102597ms, within tolerance 1s) 2020/03/09 06:15:01 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:01 DEBUG : open-test-file(0xc0004cfac0): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:01 DEBUG : open-test-file(0xc0004cfac0): close: 2020/03/09 06:15:01 DEBUG : open-test-file(0xc0004cfac0): >close: err= 2020/03/09 06:15:02 INFO : open-test-file: Removed from cache 2020/03/09 06:15:02 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:15:02 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:15:02 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:02 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:02 DEBUG : open-test-file(0xc000138b80): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:02 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:02 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:02 DEBUG : open-test-file(0xc000138b80): close: 2020/03/09 06:15:02 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:02 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:02 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:02 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:02 INFO : open-test-file: Copied (new) 2020/03/09 06:15:02 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:02 DEBUG : open-test-file(0xc000138b80): >close: err= 2020/03/09 06:15:02 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:15:02 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_SYNC|O_TRUNC 2020/03/09 06:15:02 DEBUG : open-test-file(0xc0001faa80): Opening cached copy with flags=O_WRONLY|O_APPEND|O_SYNC|O_TRUNC 2020/03/09 06:15:02 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:02 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:02 DEBUG : open-test-file(0xc0001faa80): close: 2020/03/09 06:15:02 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:15:02 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:03 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:15:03 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:15:03 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:03 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:03 DEBUG : open-test-file(0xc0001faa80): >close: err= 2020/03/09 06:15:03 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:03 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:03 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:03 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:03 DEBUG : open-test-file: Size and modification time the same (differ by 941.127348ms, within tolerance 1s) 2020/03/09 06:15:03 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:03 DEBUG : open-test-file(0xc000080940): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:03 DEBUG : open-test-file(0xc000080940): close: 2020/03/09 06:15:03 DEBUG : open-test-file(0xc000080940): >close: err= 2020/03/09 06:15:03 INFO : open-test-file: Removed from cache 2020/03/09 06:15:03 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:15:03 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:15:03 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:03 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:03 DEBUG : open-test-file(0xc0001fb400): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:03 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:03 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:03 DEBUG : open-test-file(0xc0001fb400): close: 2020/03/09 06:15:03 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:03 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:04 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:04 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:04 INFO : open-test-file: Copied (new) 2020/03/09 06:15:04 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:04 DEBUG : open-test-file(0xc0001fb400): >close: err= 2020/03/09 06:15:04 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL, perm=-rw-rw-rw- 2020/03/09 06:15:04 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_EXCL 2020/03/09 06:15:04 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:04 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:04 DEBUG : open-test-file: Size and modification time the same (differ by 865.141713ms, within tolerance 1s) 2020/03/09 06:15:04 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:04 DEBUG : open-test-file(0xc000080d00): Opened existing cached copy with flags=O_WRONLY|O_APPEND|O_EXCL 2020/03/09 06:15:04 DEBUG : open-test-file(0xc000080d00): close: 2020/03/09 06:15:04 DEBUG : open-test-file: Sizes differ (src 8 vs dst 5) 2020/03/09 06:15:04 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:04 DEBUG : open-test-file: Uploading segment 0/8 size 8 2020/03/09 06:15:05 DEBUG : open-test-file: SHA-1 = 4bdb719e7a59ae117ac2aa7573d9fadfd83fc7cb OK 2020/03/09 06:15:05 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:05 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000080d00): >close: err= 2020/03/09 06:15:05 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:05 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:05 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:05 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:05 DEBUG : open-test-file: Size and modification time the same (differ by 433.150543ms, within tolerance 1s) 2020/03/09 06:15:05 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760880): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760880): close: 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760880): >close: err= 2020/03/09 06:15:05 INFO : open-test-file: Removed from cache 2020/03/09 06:15:05 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:15:05 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:15:05 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:05 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760940): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:05 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:05 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760940): close: 2020/03/09 06:15:05 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:05 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:05 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:05 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:05 INFO : open-test-file: Copied (new) 2020/03/09 06:15:05 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760940): >close: err= 2020/03/09 06:15:05 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:15:05 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_EXCL|O_TRUNC 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760f80): Opening cached copy with flags=O_WRONLY|O_APPEND|O_EXCL|O_TRUNC 2020/03/09 06:15:05 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:05 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:05 DEBUG : open-test-file(0xc000760f80): close: 2020/03/09 06:15:05 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:15:05 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:06 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:15:07 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:15:07 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:07 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000760f80): >close: err= 2020/03/09 06:15:07 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:07 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:07 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:07 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:07 DEBUG : open-test-file: Size and modification time the same (differ by 929.1738ms, within tolerance 1s) 2020/03/09 06:15:07 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000761400): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000761400): close: 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000761400): >close: err= 2020/03/09 06:15:07 INFO : open-test-file: Removed from cache 2020/03/09 06:15:07 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:15:07 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:15:07 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:07 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000761480): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:07 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:07 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000761480): close: 2020/03/09 06:15:07 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:07 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:07 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:07 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:07 INFO : open-test-file: Copied (new) 2020/03/09 06:15:07 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000761480): >close: err= 2020/03/09 06:15:07 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC, perm=-rw-rw-rw- 2020/03/09 06:15:07 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC 2020/03/09 06:15:07 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:07 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:07 DEBUG : open-test-file: Size and modification time the same (differ by 321.195441ms, within tolerance 1s) 2020/03/09 06:15:07 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000124600): Opened existing cached copy with flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC 2020/03/09 06:15:07 DEBUG : open-test-file(0xc000124600): close: 2020/03/09 06:15:07 DEBUG : open-test-file: Sizes differ (src 8 vs dst 5) 2020/03/09 06:15:07 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:08 DEBUG : open-test-file: Uploading segment 0/8 size 8 2020/03/09 06:15:08 DEBUG : open-test-file: SHA-1 = 4bdb719e7a59ae117ac2aa7573d9fadfd83fc7cb OK 2020/03/09 06:15:08 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:08 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:08 DEBUG : open-test-file(0xc000124600): >close: err= 2020/03/09 06:15:08 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:08 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:08 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:08 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:08 DEBUG : open-test-file: Size and modification time the same (differ by 909.204583ms, within tolerance 1s) 2020/03/09 06:15:08 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:08 DEBUG : open-test-file(0xc000761a00): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:08 DEBUG : open-test-file(0xc000761a00): close: 2020/03/09 06:15:08 DEBUG : open-test-file(0xc000761a00): >close: err= 2020/03/09 06:15:09 INFO : open-test-file: Removed from cache 2020/03/09 06:15:09 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:15:09 DEBUG : open-test-file: >OpenFile: fd=, err=file does not exist 2020/03/09 06:15:09 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:09 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:09 DEBUG : open-test-file(0xc000124a40): Opening cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:09 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:09 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:09 DEBUG : open-test-file(0xc000124a40): close: 2020/03/09 06:15:09 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:09 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:09 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:09 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:09 INFO : open-test-file: Copied (new) 2020/03/09 06:15:09 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:09 DEBUG : open-test-file(0xc000124a40): >close: err= 2020/03/09 06:15:09 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC|O_TRUNC, perm=-rw-rw-rw- 2020/03/09 06:15:09 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:15:09 DEBUG : open-test-file(0xc000125000): Opening cached copy with flags=O_WRONLY|O_APPEND|O_EXCL|O_SYNC|O_TRUNC 2020/03/09 06:15:09 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:09 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:09 DEBUG : open-test-file(0xc000125000): close: 2020/03/09 06:15:09 DEBUG : open-test-file: Sizes differ (src 3 vs dst 5) 2020/03/09 06:15:09 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:10 DEBUG : open-test-file: Uploading segment 0/3 size 3 2020/03/09 06:15:10 DEBUG : open-test-file: SHA-1 = 2499da6f5e54b5dfb00e20c687819f9a7cba3d62 OK 2020/03/09 06:15:10 INFO : open-test-file: Copied (replaced existing) 2020/03/09 06:15:10 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:10 DEBUG : open-test-file(0xc000125000): >close: err= 2020/03/09 06:15:10 DEBUG : open-test-file: OpenFile: flags=O_RDONLY, perm=---------- 2020/03/09 06:15:10 DEBUG : open-test-file: Open: flags=O_RDONLY 2020/03/09 06:15:10 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:10 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:10 DEBUG : open-test-file: Size and modification time the same (differ by 757.233312ms, within tolerance 1s) 2020/03/09 06:15:10 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:10 DEBUG : open-test-file(0xc000125500): Opened existing cached copy with flags=O_RDONLY 2020/03/09 06:15:10 DEBUG : open-test-file(0xc000125500): close: 2020/03/09 06:15:10 DEBUG : open-test-file(0xc000125500): >close: err= 2020/03/09 06:15:10 INFO : open-test-file: Removed from cache 2020/03/09 06:15:10 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:15:10 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_CREATE 2020/03/09 06:15:10 DEBUG : open-test-file(0xc0000812c0): Opening cached copy with flags=O_WRONLY|O_APPEND|O_CREATE 2020/03/09 06:15:10 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:10 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:10 DEBUG : open-test-file(0xc0000812c0): close: 2020/03/09 06:15:10 DEBUG : open-test-file: Need to transfer - File not found at Destination 2020/03/09 06:15:10 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:11 DEBUG : open-test-file: Uploading segment 0/5 size 5 2020/03/09 06:15:11 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:11 INFO : open-test-file: Copied (new) 2020/03/09 06:15:11 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:11 DEBUG : open-test-file(0xc0000812c0): >close: err= 2020/03/09 06:15:11 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_CREATE, perm=-rwxrwxrwx 2020/03/09 06:15:11 DEBUG : open-test-file: Open: flags=O_WRONLY|O_CREATE 2020/03/09 06:15:11 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:11 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:11 DEBUG : open-test-file: Size and modification time the same (differ by 665.247428ms, within tolerance 1s) 2020/03/09 06:15:11 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:11 DEBUG : open-test-file(0xc000761ec0): Opened existing cached copy with flags=O_WRONLY|O_CREATE 2020/03/09 06:15:11 DEBUG : open-test-file(0xc000761ec0): close: 2020/03/09 06:15:11 DEBUG : open-test-file: Modification times differ by -1.30525738s: 2020-03-09 06:15:11.30525738 +0000 UTC, 2020-03-09 06:15:10 +0000 UTC 2020/03/09 06:15:11 DEBUG : open-test-file: SHA-1 = aaf4c61ddcc5e8a2dabede0f3b482cd9aea9434d OK 2020/03/09 06:15:11 INFO : open-test-file: Updated modification time in destination 2020/03/09 06:15:11 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:11 DEBUG : open-test-file: transferred to remote 2020/03/09 06:15:11 DEBUG : open-test-file(0xc000761ec0): >close: err= 2020/03/09 06:15:11 DEBUG : open-test-file: OpenFile: flags=O_WRONLY|O_APPEND|O_CREATE, perm=-rw-rw-rw- 2020/03/09 06:15:11 DEBUG : open-test-file: Open: flags=O_WRONLY|O_APPEND|O_CREATE 2020/03/09 06:15:11 DEBUG : open-test-file: >Open: fd=open-test-file (rw), err= 2020/03/09 06:15:11 DEBUG : open-test-file: >OpenFile: fd=open-test-file (rw), err= 2020/03/09 06:15:11 DEBUG : open-test-file: Size and modification time the same (differ by 305.25738ms, within tolerance 1s) 2020/03/09 06:15:11 DEBUG : open-test-file: Unchanged skipping 2020/03/09 06:15:11 DEBUG : open-test-file(0xc0004ce440): Opened existing cached copy with flags=O_WRONLY|O_APPEND|O_CREATE 2020/03/09 06:15:11 DEBUG : open-test-file(0xc0004ce440): close: 2020/03/09 06:15:11 DEBUG : open-test-file: Sizes differ (src 8 vs dst 5) 2020/03/09 06:15:11 DEBUG : open-test-file: Starting multipart upload 2020/03/09 06:15:12 DEBUG : open-test-file: Uploading segment 0/8 size 8 2020/03/09 06:15:12 DEBUG : open-test-file: Error encountered during upload: resourceModified: ETag does not match current item's value 2020/03/09 06:15:12 DEBUG : open-test-file: Cancelling multipart upload 2020/03/09 06:15:12 ERROR : open-test-file: Failed to copy: resourceModified: ETag does not match current item's value 2020/03/09 06:15:12 ERROR : open-test-file(0xc0004ce440): failed to transfer file from cache to remote: resourceModified: ETag does not match current item's value 2020/03/09 06:15:12 DEBUG : open-test-file(0xc0004ce440): >close: err=failed to transfer file from cache to remote: resourceModified: ETag does not match current item's value 2020/03/09 06:15:12 DEBUG : cache cleaner exiting --- FAIL: TestRWFileHandleOpenTests (77.38s) run.go:176: Remote "One drive root 'rclone-test-lucowit2qajikih9vebaduy0'", Local "Local file system at /tmp/rclone909226988", Modify Window "1s" read_write_test.go:634: Error Trace: read_write_test.go:634 read_write_test.go:670 Error: Received unexpected error: resourceModified: ETag does not match current item's value failed to transfer file from cache to remote github.com/rclone/rclone/vfs.(*RWFileHandle).flushWrites /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write.go:294 github.com/rclone/rclone/vfs.(*RWFileHandle).close /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write.go:328 github.com/rclone/rclone/vfs.(*RWFileHandle).Close /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write.go:335 github.com/rclone/rclone/vfs.testRWFileHandleOpenTest /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:633 github.com/rclone/rclone/vfs.TestRWFileHandleOpenTests /home/rclone/go/src/github.com/rclone/rclone/vfs/read_write_test.go:670 testing.tRunner /usr/local/go/src/testing/testing.go:909 runtime.goexit /usr/local/go/src/runtime/asm_amd64.s:1357 Test: TestRWFileHandleOpenTests Messages: os.O_WRONLY|os.O_APPEND|os.O_CREATE FAIL 2020/03/09 06:15:13 DEBUG : One drive root 'rclone-test-lucowit2qajikih9vebaduy0': Purge remote "./vfs.test -test.v -test.timeout 30m0s -remote TestOneDrive: -verbose -test.run '^TestRWFileHandleOpenTests$'" - Finished ERROR in 1m18.806439654s (try 3/5): exit status 1: Failed [TestRWFileHandleOpenTests]