2018/10/24 05:18:25 Testing packages: github.com/ncw/rclone/fs/operations, github.com/ncw/rclone/fs/sync 2018/10/24 05:18:25 Testing remotes: TestB2:, TestCryptDrive:, TestCryptSwift:, TestDrive:, TestDropbox:, TestGoogleCloudStorage:, TestHubic:, TestJottacloud:, TestOneDrive:, TestS3:, TestSftp:, TestSwift:, TestYandex:, TestFTP:, TestBox:, TestQingStor:, TestAzureBlob:, TestPcloud:, TestWebdav:, TestCache:, TestMega:, TestOpenDrive:, TestUnion: 2018/10/24 05:18:25 github.com/ncw/rclone/fs/operations: Making test binary "operations.test" 2018/10/24 05:18:29 github.com/ncw/rclone/fs/sync: Making test binary "sync.test" 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestQingStor:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestUnion:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCryptDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCryptSwift:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestPcloud:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestDropbox:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestWebdav:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCache:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestMega:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestOpenDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestUnion:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptSwift:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDropbox:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestHubic:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestJottacloud:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestJottacloud: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestOneDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestHubic:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSftp:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestFTP:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestYandex:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestBox:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestBox:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSftp:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestYandex:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestFTP:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestQingStor:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestOneDrive:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob: -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob: -subdir" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob: -subdir -fast-list" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestPcloud:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestWebdav:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCache:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestMega:" - Starting (try 1/5) 2018/10/24 05:18:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestOpenDrive:" - Starting (try 1/5) 2018/10/24 05:18:33 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestUnion:" - Finished OK in 644.118068ms (try 1/5) 2018/10/24 05:18:33 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestUnion:" - Finished OK in 865.38703ms (try 1/5) 2018/10/24 05:18:34 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestFTP:" - Finished OK in 1.996636387s (try 1/5) 2018/10/24 05:18:38 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestFTP:" - Finished OK in 5.853350515s (try 1/5) 2018/10/24 05:18:39 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob: -subdir -fast-list" - Finished OK in 7.502480565s (try 1/5) 2018/10/24 05:18:40 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob:" - Finished OK in 7.855295358s (try 1/5) 2018/10/24 05:18:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSftp:" - Finished OK in 8.620128034s (try 1/5) 2018/10/24 05:18:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob: -subdir" - Finished OK in 8.886033415s (try 1/5) 2018/10/24 05:18:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestAzureBlob: -fast-list" - Finished OK in 9.288668336s (try 1/5) 2018/10/24 05:18:43 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCache:" - Finished OK in 10.929008616s (try 1/5) 2018/10/24 05:18:44 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob: -fast-list" - Finished OK in 11.871364718s (try 1/5) 2018/10/24 05:18:44 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob:" - Finished OK in 12.133657469s (try 1/5) 2018/10/24 05:18:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3:" - Finished OK in 15.557732627s (try 1/5) 2018/10/24 05:18:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3: -subdir -fast-list" - Finished OK in 15.751356359s (try 1/5) 2018/10/24 05:18:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob: -subdir -fast-list" - Finished OK in 15.870352015s (try 1/5) 2018/10/24 05:18:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestAzureBlob: -subdir" - Finished OK in 16.188781818s (try 1/5) 2018/10/24 05:18:49 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3: -fast-list" - Finished OK in 16.638350836s (try 1/5) 2018/10/24 05:18:49 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestS3: -subdir" - Finished OK in 17.132047301s (try 1/5) 2018/10/24 05:18:53 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSftp:" - Finished OK in 21.283539313s (try 1/5) 2018/10/24 05:18:53 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3: -subdir -fast-list" - Finished OK in 21.393736704s (try 1/5) 2018/10/24 05:18:54 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3: -fast-list" - Finished OK in 22.270017237s (try 1/5) 2018/10/24 05:18:55 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3: -subdir" - Finished OK in 22.630679044s (try 1/5) 2018/10/24 05:18:56 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestS3:" - Finished OK in 23.942194271s (try 1/5) 2018/10/24 05:19:00 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCache:" - Finished OK in 27.728255486s (try 1/5) 2018/10/24 05:19:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage:" - Finished OK in 35.706386847s (try 1/5) 2018/10/24 05:19:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -fast-list" - Finished OK in 35.88535213s (try 1/5) 2018/10/24 05:19:09 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir -fast-list" - Finished OK in 37.073565317s (try 1/5) 2018/10/24 05:19:10 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir" - Finished OK in 38.073711642s (try 1/5) 2018/10/24 05:19:17 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift:" - Finished OK in 45.392583676s (try 1/5) 2018/10/24 05:19:24 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift: -fast-list" - Finished OK in 51.625661081s (try 1/5) 2018/10/24 05:19:26 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCryptSwift:" - Finished OK in 54.059579912s (try 1/5) 2018/10/24 05:19:31 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift: -subdir" - Finished OK in 59.00943131s (try 1/5) 2018/10/24 05:19:33 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestSwift: -subdir -fast-list" - Finished OK in 1m1.479574545s (try 1/5) 2018/10/24 05:19:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -fast-list" - Finished OK in 1m15.850966349s (try 1/5) 2018/10/24 05:19:52 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir -fast-list" - Finished OK in 1m20.012233803s (try 1/5) 2018/10/24 05:20:06 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage:" - Finished OK in 1m34.239259574s (try 1/5) 2018/10/24 05:20:07 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestGoogleCloudStorage: -subdir" - Finished OK in 1m35.504147083s (try 1/5) 2018/10/24 05:20:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift:" - Finished OK in 1m36.200455021s (try 1/5) 2018/10/24 05:20:09 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestWebdav:" - Finished OK in 1m37.098943095s (try 1/5) 2018/10/24 05:20:22 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift: -subdir" - Finished OK in 1m50.491859722s (try 1/5) 2018/10/24 05:20:24 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2:" - Finished OK in 1m51.754644517s (try 1/5) 2018/10/24 05:20:27 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptSwift:" - Finished OK in 1m55.045935331s (try 1/5) 2018/10/24 05:20:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift: -fast-list" - Finished OK in 1m55.924578448s (try 1/5) 2018/10/24 05:20:46 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestSwift: -subdir -fast-list" - Finished OK in 2m14.331017955s (try 1/5) 2018/10/24 05:21:02 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestMega:" - Finished OK in 2m29.716090018s (try 1/5) 2018/10/24 05:21:06 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestWebdav:" - Finished ERROR in 2m34.132265205s (try 1/5): exit status 1: Failed [TestServerSideMoveWithFilter] 2018/10/24 05:21:06 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestWebdav: -test.run '^(TestServerSideMoveWithFilter)$'" - Starting (try 2/5) 2018/10/24 05:21:12 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestJottacloud: -fast-list" - Finished OK in 2m40.223406258s (try 1/5) 2018/10/24 05:21:14 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestWebdav: -test.run '^(TestServerSideMoveWithFilter)$'" - Finished OK in 7.978706576s (try 2/5) 2018/10/24 05:21:16 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestMega:" - Finished OK in 2m44.303739252s (try 1/5) 2018/10/24 05:21:18 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestJottacloud:" - Finished OK in 2m45.931369613s (try 1/5) 2018/10/24 05:21:30 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -fast-list" - Finished ERROR in 2m58.231182203s (try 1/5): exit status 1: Failed [TestCount] 2018/10/24 05:21:30 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestCount)$'" - Starting (try 2/5) 2018/10/24 05:21:36 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestOneDrive:" - Finished OK in 3m4.243745441s (try 1/5) 2018/10/24 05:21:40 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestCount)$'" - Finished OK in 10.044459286s (try 2/5) 2018/10/24 05:21:47 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestDropbox:" - Finished OK in 3m14.729018911s (try 1/5) 2018/10/24 05:21:47 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list" - Finished ERROR in 3m15.060241338s (try 1/5): exit status 1: Failed [TestLsWithFilesFrom] 2018/10/24 05:21:47 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list -test.run '^(TestLsWithFilesFrom)$'" - Starting (try 2/5) 2018/10/24 05:21:52 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestBox:" - Finished OK in 3m19.625849234s (try 1/5) 2018/10/24 05:21:58 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list -test.run '^(TestLsWithFilesFrom)$'" - Finished OK in 10.605752644s (try 2/5) 2018/10/24 05:22:04 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestPcloud:" - Finished OK in 3m31.703696058s (try 1/5) 2018/10/24 05:22:10 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir" - Finished ERROR in 3m38.109040341s (try 1/5): exit status 1: Failed [TestLsLong] 2018/10/24 05:22:10 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir -test.run '^(TestLsLong)$'" - Starting (try 2/5) 2018/10/24 05:22:25 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestYandex:" - Finished OK in 3m53.04551644s (try 1/5) 2018/10/24 05:22:25 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestB2: -subdir -test.run '^(TestLsLong)$'" - Finished OK in 15.18804333s (try 2/5) 2018/10/24 05:22:50 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestQingStor:" - Finished OK in 4m17.911352285s (try 1/5) 2018/10/24 05:23:13 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud: -fast-list" - Finished ERROR in 4m41.477399482s (try 1/5): exit status 1: Failed [TestCopyFile] 2018/10/24 05:23:13 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud: -fast-list -test.run '^(TestCopyFile)$'" - Starting (try 2/5) 2018/10/24 05:23:17 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud:" - Finished ERROR in 4m45.068111028s (try 1/5): exit status 1: Failed [TestCopyFile] 2018/10/24 05:23:17 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud: -test.run '^(TestCopyFile)$'" - Starting (try 2/5) 2018/10/24 05:23:18 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud: -fast-list -test.run '^(TestCopyFile)$'" - Finished OK in 4.399835746s (try 2/5) 2018/10/24 05:23:22 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestJottacloud: -test.run '^(TestCopyFile)$'" - Finished OK in 4.804350428s (try 2/5) 2018/10/24 05:23:32 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDropbox:" - Finished OK in 5m0.195216115s (try 1/5) 2018/10/24 05:23:40 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestOneDrive:" - Finished OK in 5m8.033333498s (try 1/5) 2018/10/24 05:23:58 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestBox:" - Finished OK in 5m26.354141227s (try 1/5) 2018/10/24 05:24:42 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestPcloud:" - Finished ERROR in 6m10.334572164s (try 1/5): exit status 1: Failed [TestSyncBackupDir] 2018/10/24 05:24:42 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestPcloud: -test.run '^(TestSyncBackupDir)$'" - Starting (try 2/5) 2018/10/24 05:24:45 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestYandex:" - Finished OK in 6m12.630553539s (try 1/5) 2018/10/24 05:24:53 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2:" - Finished ERROR in 6m21.308016198s (try 1/5): exit status 1: Failed [TestSyncAfterChangingFilesSizeOnly TestSyncWithExcludeAndDeleteExcluded] 2018/10/24 05:24:53 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -test.run '^(TestSyncAfterChangingFilesSizeOnly|TestSyncWithExcludeAndDeleteExcluded)$'" - Starting (try 2/5) 2018/10/24 05:25:07 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -test.run '^(TestSyncAfterChangingFilesSizeOnly|TestSyncWithExcludeAndDeleteExcluded)$'" - Finished OK in 13.509215132s (try 2/5) 2018/10/24 05:25:12 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestPcloud: -test.run '^(TestSyncBackupDir)$'" - Finished OK in 30.090623646s (try 2/5) 2018/10/24 05:26:36 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir" - Finished ERROR in 8m4.288779786s (try 1/5): exit status 1: Failed [TestCopyWithDepth TestCopyWithFilesFrom TestSyncAfterChangingModtimeOnly TestSyncDoesntUpdateModtime TestSyncWithExcludeAndDeleteExcluded] 2018/10/24 05:26:36 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -test.run '^(TestCopyWithDepth|TestCopyWithFilesFrom|TestSyncAfterChangingModtimeOnly|TestSyncDoesntUpdateModtime|TestSyncWithExcludeAndDeleteExcluded)$'" - Starting (try 2/5) 2018/10/24 05:26:58 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -test.run '^(TestCopyWithDepth|TestCopyWithFilesFrom|TestSyncAfterChangingModtimeOnly|TestSyncDoesntUpdateModtime|TestSyncWithExcludeAndDeleteExcluded)$'" - Finished OK in 21.238200882s (try 2/5) 2018/10/24 05:28:03 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestHubic:" - Finished OK in 9m31.315448638s (try 1/5) 2018/10/24 05:28:18 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestOpenDrive:" - Finished OK in 9m46.39125845s (try 1/5) 2018/10/24 05:29:33 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list" - Finished ERROR in 11m1.286826674s (try 1/5): exit status 1: Failed [TestCopyRedownload TestSyncDoesntUpdateModtime TestSyncAfterRemovingAFileAndAddingAFile TestSyncWithUpdateOlder TestSyncUTFNorm TestSyncImmutable] 2018/10/24 05:29:33 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list -test.run '^(TestCopyRedownload|TestSyncDoesntUpdateModtime|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncWithUpdateOlder|TestSyncUTFNorm|TestSyncImmutable)$'" - Starting (try 2/5) 2018/10/24 05:30:37 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestQingStor:" - Finished OK in 12m5.285279512s (try 1/5) 2018/10/24 05:31:11 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list" - Finished ERROR in 12m39.177110432s (try 1/5): exit status 1: Failed [TestServerSideCopy TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime TestSyncDoesntUpdateModtime TestSyncAfterAddingAFile TestSyncAfterChangingFilesSizeOnly TestSyncWithExcludeAndDeleteExcluded] 2018/10/24 05:31:11 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestServerSideCopy|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncDoesntUpdateModtime|TestSyncAfterAddingAFile|TestSyncAfterChangingFilesSizeOnly|TestSyncWithExcludeAndDeleteExcluded)$'" - Starting (try 2/5) 2018/10/24 05:32:30 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list -test.run '^(TestCopyRedownload|TestSyncDoesntUpdateModtime|TestSyncAfterRemovingAFileAndAddingAFile|TestSyncWithUpdateOlder|TestSyncUTFNorm|TestSyncImmutable)$'" - Finished ERROR in 2m56.610175482s (try 2/5): exit status 1: Failed [TestSyncUTFNorm TestSyncImmutable] 2018/10/24 05:32:30 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list -test.run '^(TestSyncUTFNorm|TestSyncImmutable)$'" - Starting (try 3/5) 2018/10/24 05:32:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -subdir -fast-list -test.run '^(TestSyncUTFNorm|TestSyncImmutable)$'" - Finished OK in 10.678103207s (try 3/5) 2018/10/24 05:33:46 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestServerSideCopy|TestSyncAfterChangingModtimeOnlyWithNoUpdateModTime|TestSyncDoesntUpdateModtime|TestSyncAfterAddingAFile|TestSyncAfterChangingFilesSizeOnly|TestSyncWithExcludeAndDeleteExcluded)$'" - Finished ERROR in 2m34.465138564s (try 2/5): exit status 1: Failed [TestSyncDoesntUpdateModtime TestSyncAfterChangingFilesSizeOnly TestSyncWithExcludeAndDeleteExcluded] 2018/10/24 05:33:46 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestSyncDoesntUpdateModtime|TestSyncAfterChangingFilesSizeOnly|TestSyncWithExcludeAndDeleteExcluded)$'" - Starting (try 3/5) 2018/10/24 05:35:14 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestOpenDrive:" - Finished OK in 16m41.858516702s (try 1/5) 2018/10/24 05:35:15 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestSyncDoesntUpdateModtime|TestSyncAfterChangingFilesSizeOnly|TestSyncWithExcludeAndDeleteExcluded)$'" - Finished ERROR in 1m29.614658026s (try 3/5): exit status 1: Failed [TestSyncWithExcludeAndDeleteExcluded] 2018/10/24 05:35:15 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestSyncWithExcludeAndDeleteExcluded)$'" - Starting (try 4/5) 2018/10/24 05:35:27 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestB2: -fast-list -test.run '^(TestSyncWithExcludeAndDeleteExcluded)$'" - Finished OK in 11.842278136s (try 4/5) 2018/10/24 05:38:27 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestHubic:" - Finished ERROR in 19m54.561591096s (try 1/5): exit status 1: Failed [TestSyncAfterChangingModtimeOnly TestServerSideMove TestServerSideMoveWithFilter] 2018/10/24 05:38:27 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestHubic: -test.run '^(TestSyncAfterChangingModtimeOnly|TestServerSideMove|TestServerSideMoveWithFilter)$'" - Starting (try 2/5) 2018/10/24 05:41:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCryptDrive:" - Finished OK in 23m15.995588817s (try 1/5) 2018/10/24 05:42:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestHubic: -test.run '^(TestSyncAfterChangingModtimeOnly|TestServerSideMove|TestServerSideMoveWithFilter)$'" - Finished OK in 3m41.361338692s (try 2/5) 2018/10/24 05:42:44 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestDrive:" - Finished OK in 24m12.445612083s (try 1/5) 2018/10/24 05:43:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Finished OK in 24m56.154726505s (try 1/5) 2018/10/24 05:46:11 "/home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Finished OK in 27m38.822144899s (try 1/5) 2018/10/24 05:48:38 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Expecting errors but got none: [] 2018/10/24 05:48:38 ------------------------------------------------------------ 2018/10/24 05:48:38 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" ---- 2018/10/24 05:48:38 2018/10/24 05:18:42 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:23:27 ERROR : : error listing: directory not found 2018/10/24 05:28:48 NOTICE: empty space: Not updating modification time as --dry-run 2018/10/24 05:31:00 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:31:00 NOTICE: potato: Not deleting as --dry-run 2018/10/24 05:35:26 ERROR : Google drive root 'rclone-test-hotaxic1jegaqiz7natodod2': not deleting files as there were IO errors 2018/10/24 05:35:26 ERROR : Google drive root 'rclone-test-hotaxic1jegaqiz7natodod2': not deleting directories as there were IO errors 2018/10/24 05:44:41 purge failed: directory not found 2018/10/24 05:47:28 purge failed: directory not found panic: test timed out after 30m0s goroutine 4988 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive, 1 minutes]: testing.(*T).Run(0xc0003c8000, 0x108b91c, 0x11, 0x10cff58, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc0004fe000) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc0004fe000, 0xc000393dc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0002d2340, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc00012ed00, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc00012ed00) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc00012ed00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 19 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 5031 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5011 [IO wait]: internal/poll.runtime_pollWait(0x7f8b5e5c5bc0, 0x72, 0xc00005d760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc0006b8f18, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc0006b8f18, 0xc00038cc00, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc0006b8f00, 0xc00038cc00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc0006b8f00, 0xc00038cc00, 0x400, 0x400, 0xc00018a140, 0xc0001520c0, 0xc000364000) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001ab740, 0xc00038cc00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc00038cc00, 0x400, 0x400, 0x8, 0x8, 0xc0004c6000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00050c2c0, 0xc00005d950, 0xc00038cc00, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00050c2c0, 0xc00038cc00, 0x400, 0x400, 0xfea4c0, 0x0, 0x7f8b5e581000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000153bc0, 0x7f8b5e581000, 0xc00050c2c0, 0x5, 0xc00050c2c0, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc000376380, 0x10d1417, 0xc0003764a0, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc000376380, 0xc000488000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000f9320, 0xc000488000, 0x1000, 0x1000, 0xc00005dc70, 0x406865, 0xc000360600) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000030720) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000030720, 0x1, 0x0, 0x0, 0x1, 0xc0004ec5a0, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000f9320) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 5034 [semacquire]: sync.runtime_Semacquire(0xc00045c2a8) /usr/local/go/src/runtime/sema.go:56 +0x39 sync.(*WaitGroup).Wait(0xc00045c2a0) /usr/local/go/src/sync/waitgroup.go:130 +0x64 github.com/ncw/rclone/backend/drive.(*Fs).ListR.func2(0xc00045c2a0, 0xc00049a320, 0xc00045c280, 0xc0001ab5d8) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1424 +0x2f created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1422 +0x393 goroutine 5028 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 4885 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).ListR(0xc000083200, 0x0, 0x0, 0xc000420370, 0xf78b01, 0xc000420370) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1453 +0x423 github.com/ncw/rclone/fs.ListRer.ListR-fm(0x0, 0x0, 0xc000420370, 0xc00049a2c0, 0x11e8560) /home/rclone/go/src/github.com/ncw/rclone/fs/fs.go:598 +0x4d github.com/ncw/rclone/fs/walk.walkRDirTree(0x11f21c0, 0xc000083200, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc0002bec40, 0xc000420310, 0xc00049a2a0, 0xda9b41) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:365 +0x130 github.com/ncw/rclone/fs/walk.walkR(0x11f21c0, 0xc000083200, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc00049a2a0, 0xc0002bec40, 0xc000364000, 0xe8a460) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:476 +0x8d github.com/ncw/rclone/fs/walk.walkListR(0x11f21c0, 0xc000083200, 0x0, 0x0, 0xc000323801, 0xffffffffffffffff, 0xc00049a2a0, 0xf78e60, 0x75bcd01) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:87 +0x9a github.com/ncw/rclone/fs/walk.Walk(0x11f21c0, 0xc000083200, 0x0, 0x0, 0xc00009d301, 0xffffffffffffffff, 0xc00049a2a0, 0x0, 0x6) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:66 +0x146 github.com/ncw/rclone/fs/walk.GetAll(0x11f21c0, 0xc000083200, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0x49, 0xc000040a00, 0xc000323a30, 0x4830ca, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:511 +0xe0 github.com/ncw/rclone/fstest.CheckListingWithPrecision(0xc0003c8000, 0x11f21c0, 0xc000083200, 0xc00044e000, 0x5, 0x5, 0x0, 0x0, 0x0, 0xf4240) /home/rclone/go/src/github.com/ncw/rclone/fstest/fstest.go:286 +0x206 github.com/ncw/rclone/fstest.CheckItems(0xc0003c8000, 0x11f21c0, 0xc000083200, 0xc00044e000, 0x5, 0x5) /home/rclone/go/src/github.com/ncw/rclone/fstest/fstest.go:346 +0xeb github.com/ncw/rclone/fs/sync.testSyncBackupDir(0xc0003c8000, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1129 +0xdb4 github.com/ncw/rclone/fs/sync.TestSyncBackupDir(0xc0003c8000) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1146 +0x33 testing.tRunner(0xc0003c8000, 0x10cff58) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 5029 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5026 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc00027eae0) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc00027eae0, 0xc000243cb0, 0xa, 0xfcea40, 0xc00039e001) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc00027eae0, 0xc000243cb0, 0xc00066dd98, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc000083200, 0xc00019e460, 0x1, 0x1, 0x0, 0x0, 0x0, 0xc00057df30, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1325 +0x2bd created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 4744 [select]: net/http.(*persistConn).writeLoop(0xc0001d25a0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4982 [select]: net/http.(*persistConn).writeLoop(0xc0000f9560) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4981 [IO wait]: internal/poll.runtime_pollWait(0x7f8b5e5c5d60, 0x72, 0xc00005f760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc00061ca98, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc00061ca98, 0xc000180800, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc00061ca80, 0xc000180800, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc00061ca80, 0xc000180800, 0x400, 0x400, 0x0, 0x11de080, 0xc000098100) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0002fe1d8, 0xc000180800, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc000180800, 0x400, 0x400, 0x0, 0x11df600, 0xc000404000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00033ea80, 0xc00005f950, 0xc000180800, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00033ea80, 0xc000180800, 0x400, 0x400, 0xfea4c0, 0xc00005ff00, 0x7f8b5e581000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0005912c0, 0x7f8b5e581000, 0xc00033ea80, 0x5, 0xc00033ea80, 0xc000404001) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc00015ee00, 0x10d1417, 0xc00015ef20, 0xc00051c5a0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc00015ee00, 0xc0005c2000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000f9560, 0xc0005c2000, 0x1000, 0x1000, 0x10, 0xc000302470, 0x42c992) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0000ab1a0) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0000ab1a0, 0x1, 0xc00051c5a0, 0xc000302558, 0x0, 0x0, 0xc000052700) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000f9560) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4743 [IO wait]: internal/poll.runtime_pollWait(0x7f8b5e5c5af0, 0x72, 0xc00066c760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000619798, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000619798, 0xc00051e000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000619780, 0xc00051e000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000619780, 0xc00051e000, 0x1000, 0x1000, 0xc0001ac000, 0xc00009c5a0, 0xc000052700) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0000a8020, 0xc00051e000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc00051e000, 0x1000, 0x1000, 0x10, 0x8, 0x80) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc000524020, 0xc00066c950, 0xc00051e000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc000524020, 0xc00051e000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f8b5e581000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc00028d770, 0x7f8b5e581000, 0xc000524020, 0x5, 0xc000524020, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc00015e700, 0x10d1417, 0xc00015e820, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc00015e700, 0xc00051f000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0001d25a0, 0xc00051f000, 0x1000, 0x1000, 0xc00066cc70, 0x406865, 0xc0004ecb40) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000171800) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000171800, 0x1, 0x0, 0x0, 0x1, 0xc00008e420, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0001d25a0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 5027 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5033 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5030 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5037 [sleep]: time.Sleep(0x124f94d6a) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc00027eae0, 0x124f94d6a) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c goroutine 5032 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc000083200, 0xc00045c2a0, 0xc00059e420, 0xc00059e480, 0xc00009df50, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5012 [select]: net/http.(*persistConn).writeLoop(0xc0000f9320) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 2018/10/24 05:48:38 ------------------------------------------------------------ 2018/10/24 05:48:38 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Finished ERROR in 30m6.36283464s (try 1/5): exit status 2: Failed [] 2018/10/24 05:48:38 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Starting (try 2/5) 2018/10/24 05:48:40 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Expecting errors but got none: [] 2018/10/24 05:48:40 ------------------------------------------------------------ 2018/10/24 05:48:40 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" ---- 2018/10/24 05:48:40 2018/10/24 05:18:42 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:22:50 ERROR : : error listing: directory not found 2018/10/24 05:28:27 NOTICE: empty space: Not updating modification time as --dry-run 2018/10/24 05:30:49 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:30:49 NOTICE: potato: Not deleting as --dry-run 2018/10/24 05:34:20 ERROR : Google drive root 'rclone-test-haxinet4hahadag2corilin1': not deleting files as there were IO errors 2018/10/24 05:34:20 ERROR : Google drive root 'rclone-test-haxinet4hahadag2corilin1': not deleting directories as there were IO errors 2018/10/24 05:44:23 ERROR : : error listing: directory not found 2018/10/24 05:44:23 purge failed: directory not found 2018/10/24 05:47:47 ERROR : : error listing: directory not found 2018/10/24 05:47:47 ERROR : : error listing: directory not found 2018/10/24 05:47:48 purge failed: directory not found panic: test timed out after 30m0s goroutine 4396 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive, 1 minutes]: testing.(*T).Run(0xc0005d7400, 0x108b91c, 0x11, 0x10cff58, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000032900) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000032900, 0xc000213dc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0004045c0, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc00010ed00, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc00010ed00) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc00010ed00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4356 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc000504540) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000504540, 0xc00053e930, 0xa, 0xfcea40, 0xc00054a201) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000504540, 0xc00053e930, 0xc000539938, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc000458f00, 0xc000539a10, 0x1, 0x1, 0xc00045c46f, 0x3, 0x1, 0xc000539a20, 0x21, 0x1, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).FindLeaf(0xc000458f00, 0xc000190c60, 0x21, 0xc00045c46f, 0x3, 0x0, 0x0, 0x0, 0x3, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1087 +0xfa github.com/ncw/rclone/lib/dircache.(*DirCache)._findDir(0xc0005fc870, 0xc00045c44a, 0x28, 0x0, 0xc000539c00, 0x7f2787, 0xc0005fc870, 0xc0005fc870) /home/rclone/go/src/github.com/ncw/rclone/lib/dircache/dircache.go:181 +0x12f github.com/ncw/rclone/lib/dircache.(*DirCache).FindRoot(0xc0005fc870, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/lib/dircache/dircache.go:233 +0xa5 github.com/ncw/rclone/backend/drive.NewFs(0xc00045c440, 0x9, 0xc00045c44a, 0x28, 0x11e2dc0, 0xc00016c0f0, 0x28, 0xc00016c0f0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:911 +0x611 github.com/ncw/rclone/fs.NewFs(0xc00045c440, 0x32, 0x2e, 0x107dbd1, 0x4, 0xc00045c440) /home/rclone/go/src/github.com/ncw/rclone/fs/fs.go:1050 +0xb5 github.com/ncw/rclone/fs/sync.testSyncBackupDir(0xc0005d7400, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1109 +0x729 github.com/ncw/rclone/fs/sync.TestSyncBackupDir(0xc0005d7400) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1146 +0x33 testing.tRunner(0xc0005d7400, 0x10cff58) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 3891 [select]: net/http.(*persistConn).writeLoop(0xc0001c0360) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 3890 [IO wait]: internal/poll.runtime_pollWait(0x7f617e551bc0, 0x72, 0xc00005f760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc00010ff18, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc00010ff18, 0xc0000b2800, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc00010ff00, 0xc0000b2800, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc00010ff00, 0xc0000b2800, 0x400, 0x400, 0xc00044e530, 0xc00053e8a0, 0xc000118000) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001996a0, 0xc0000b2800, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0000b2800, 0x400, 0x400, 0x8, 0x8, 0xc0002c6980) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00038e1e0, 0xc00005f950, 0xc0000b2800, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00038e1e0, 0xc0000b2800, 0x400, 0x400, 0xfea4c0, 0x0, 0x7f617e55a390) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0001c57a0, 0x7f617e55a390, 0xc00038e1e0, 0x5, 0xc00038e1e0, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc000053880, 0x10d1417, 0xc0000539a0, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc000053880, 0xc00021c000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0001c0360, 0xc00021c000, 0x1000, 0x1000, 0xc00005fc70, 0x406865, 0xc000083800) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc00026d980) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc00026d980, 0x1, 0x0, 0x0, 0x1, 0xc0000be720, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0001c0360) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4425 [sleep]: time.Sleep(0x21109ca9a) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000504540, 0x21109ca9a) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c 2018/10/24 05:48:40 ------------------------------------------------------------ 2018/10/24 05:48:40 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Finished ERROR in 30m8.027842282s (try 1/5): exit status 2: Failed [] 2018/10/24 05:48:40 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Starting (try 2/5) 2018/10/24 05:48:49 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Expecting errors but got none: [] 2018/10/24 05:48:49 ------------------------------------------------------------ 2018/10/24 05:48:49 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" ---- 2018/10/24 05:48:49 2018/10/24 05:19:09 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:24:44 ERROR : : error listing: directory not found 2018/10/24 05:30:08 NOTICE: empty space: Not copying as --dry-run 2018/10/24 05:32:29 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:32:29 NOTICE: potato: Not deleting as --dry-run 2018/10/24 05:37:29 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-kocipiw5zupekum8manexot5': not deleting files as there were IO errors 2018/10/24 05:37:29 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-kocipiw5zupekum8manexot5': not deleting directories as there were IO errors 2018/10/24 05:44:37 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-kocipiw5zupekum8manexot5': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 05:44:49 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-kocipiw5zupekum8manexot5': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 05:48:09 ERROR : : error listing: directory not found 2018/10/24 05:48:10 purge failed: directory not found panic: test timed out after 30m0s goroutine 4025 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive, 1 minutes]: testing.(*T).Run(0xc000033000, 0x1099950, 0x1c, 0x10cfee8, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000452400) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000452400, 0xc00054bdc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc00036a980, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc000146c80, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc000146c80) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc000146c80) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 19 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 3884 [select]: net/http.(*persistConn).roundTrip(0xc0000fa5a0, 0xc000145a70, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/transport.go:2101 +0x56a net/http.(*Transport).roundTrip(0xc000194120, 0xc00009c300, 0xa, 0xc0000a6590, 0x10) /usr/local/go/src/net/http/transport.go:465 +0x9b1 net/http.(*Transport).RoundTrip(0xc000194120, 0xc00009c300, 0xa, 0xc0000a6590, 0x10) /usr/local/go/src/net/http/roundtrip.go:17 +0x35 github.com/ncw/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000144d50, 0xc00009c300, 0xc00009c300, 0x0, 0xc001390dd8) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:282 +0xff github.com/ncw/rclone/vendor/golang.org/x/oauth2.(*Transport).RoundTrip(0xc0004a0e40, 0xc00009c200, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/golang.org/x/oauth2/transport.go:56 +0xfb net/http.send(0xc00009c200, 0x11df2e0, 0xc0004a0e40, 0x0, 0x0, 0x0, 0xc0001ef5d0, 0xc001390f48, 0xc001390f38, 0x1) /usr/local/go/src/net/http/client.go:250 +0x14b net/http.(*Client).send(0xc0004a0e70, 0xc00009c200, 0x0, 0x0, 0x0, 0xc0001ef5d0, 0x0, 0x1, 0x7f72856ad308) /usr/local/go/src/net/http/client.go:174 +0xfa net/http.(*Client).do(0xc0004a0e70, 0xc00009c200, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/client.go:641 +0x2a8 net/http.(*Client).Do(0xc0004a0e70, 0xc00009c200, 0x1088e13, 0xf, 0x1a4b720) /usr/local/go/src/net/http/client.go:509 +0x35 github.com/ncw/rclone/vendor/google.golang.org/api/gensupport.SendRequest(0x0, 0x0, 0xc0004a0e70, 0xc00009c200, 0x7f728563c908, 0xc000145590, 0xc00009c200) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/gensupport/send.go:43 +0x24b github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesCreateCall).doRequest(0xc0013913d8, 0x107e151, 0x4, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:4281 +0x57c github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesCreateCall).Do(0xc0013913d8, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:4293 +0x8b github.com/ncw/rclone/backend/drive.(*Fs).PutUnchecked.func1(0xc0004a65a0, 0xc000030190, 0x50) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1581 +0x246 github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc0004a65a0, 0xc000030190, 0x1, 0xc0001cfc01, 0xc000030190) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:383 +0x16a github.com/ncw/rclone/lib/pacer.(*Pacer).CallNoRetry(0xc0004a65a0, 0xc000030190, 0x1a, 0x75bcd15) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:415 +0x3e github.com/ncw/rclone/backend/drive.(*Fs).PutUnchecked(0xc00019cf00, 0x11df4e0, 0xc0003d02c0, 0x11eec80, 0xc0003d02e0, 0x0, 0x0, 0x0, 0x18, 0xfb8900, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1575 +0x33f github.com/ncw/rclone/backend/drive.(*Fs).Put(0xc00019cf00, 0x11df4e0, 0xc0003d02c0, 0x11eec80, 0xc0003d02e0, 0x0, 0x0, 0x0, 0x20, 0xfb8900, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1523 +0x154 github.com/ncw/rclone/fs.Fs.Put-fm(0x11df4e0, 0xc0003d02c0, 0x11eec80, 0xc0003d02e0, 0x0, 0x0, 0x0, 0xc0001a8700, 0x0, 0xc001391860, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:231 +0x88 github.com/ncw/rclone/backend/crypt.(*Fs).put(0xc0001552c0, 0x11ddd20, 0xc000152310, 0x11eee00, 0xc00020e050, 0x0, 0x0, 0x0, 0xc001391920, 0xfb6ec0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:357 +0x194 github.com/ncw/rclone/backend/crypt.(*Fs).Put(0xc0001552c0, 0x11ddd20, 0xc000152310, 0x11eee00, 0xc00020e050, 0x0, 0x0, 0x0, 0x70, 0xfb6ec0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:389 +0xd5 github.com/ncw/rclone/fs.Fs.Put-fm(0x11ddd20, 0xc000152310, 0x11eee00, 0xc00020e050, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:231 +0x88 github.com/ncw/rclone/fstest.(*Run).WriteObjectTo(0xc0005d4de0, 0x11f20c0, 0xc0001552c0, 0x1081730, 0x7, 0x10b7d51, 0x44, 0x75bcd15, 0xec689154f, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:243 +0x24c github.com/ncw/rclone/fstest.(*Run).WriteObject(0xc0005d4de0, 0x1081730, 0x7, 0x10b7d51, 0x44, 0x75bcd15, 0xec689154f, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:260 +0xe1 github.com/ncw/rclone/fstest.(*Run).WriteBoth(0xc0005d4de0, 0x1081730, 0x7, 0x10b7d51, 0x44, 0x75bcd15, 0xec689154f, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:271 +0x136 github.com/ncw/rclone/fs/sync.testServerSideMove(0xc000033000, 0xc0005d4de0, 0xc0005d0001) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:918 +0x278 github.com/ncw/rclone/fs/sync.TestServerSideMoveWithFilter(0xc000033000) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1042 +0xa7 testing.tRunner(0xc000033000, 0x10cfee8) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 3910 [IO wait]: internal/poll.runtime_pollWait(0x7f7285671af0, 0x72, 0xc00005d760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc00059c798, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc00059c798, 0xc000488000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc00059c780, 0xc000488000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc00059c780, 0xc000488000, 0x1000, 0x1000, 0xc0002e4050, 0xc000144750, 0xc000052e00) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc000386060, 0xc000488000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc000488000, 0x1000, 0x1000, 0x8, 0x8, 0xc0000ae600) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0013e29a0, 0xc00005d950, 0xc000488000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc0013e29a0, 0xc000488000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f7285635270) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0003272f0, 0x7f7285635270, 0xc0013e29a0, 0x5, 0xc0013e29a0, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0001a9c00, 0x10d1417, 0xc0001a9d20, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0001a9c00, 0xc000489000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000fa5a0, 0xc000489000, 0x1000, 0x1000, 0xc00005dc70, 0x406865, 0xc000339620) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0003f7ce0) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0003f7ce0, 0x1, 0x0, 0x0, 0x1, 0xc000090660, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000fa5a0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 3911 [select]: net/http.(*persistConn).writeLoop(0xc0000fa5a0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 2018/10/24 05:48:49 ------------------------------------------------------------ 2018/10/24 05:48:49 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Finished ERROR in 30m17.342187192s (try 1/5): exit status 2: Failed [] 2018/10/24 05:48:49 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Starting (try 2/5) 2018/10/24 05:49:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Expecting errors but got none: [] 2018/10/24 05:49:08 ------------------------------------------------------------ 2018/10/24 05:49:08 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" ---- 2018/10/24 05:49:08 2018/10/24 05:19:18 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:25:28 ERROR : : error listing: directory not found 2018/10/24 05:30:08 NOTICE: empty space: Not copying as --dry-run 2018/10/24 05:32:30 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:32:30 NOTICE: potato: Not deleting as --dry-run 2018/10/24 05:35:48 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-loxutaq4kofikax3jotepog2': not deleting files as there were IO errors 2018/10/24 05:35:48 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-loxutaq4kofikax3jotepog2': not deleting directories as there were IO errors 2018/10/24 05:42:18 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-loxutaq4kofikax3jotepog2': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 05:42:30 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-loxutaq4kofikax3jotepog2': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 05:46:39 purge failed: directory not found panic: test timed out after 30m0s goroutine 4804 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc0004b8400, 0x10a7310, 0x27, 0x10cfec8, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000033700) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000033700, 0xc00030bdc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc00033e3c0, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc000144c80, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc000144c80) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc000144c80) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 19 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4370 [IO wait]: internal/poll.runtime_pollWait(0x7f0e62dede30, 0x72, 0xc0004ca760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000284318, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000284318, 0xc0013d8000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000284300, 0xc0013d8000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000284300, 0xc0013d8000, 0x1000, 0x1000, 0xc00135cd00, 0xc0001b9e00, 0xc000052a80) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001ed690, 0xc0013d8000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0013d8000, 0x1000, 0x1000, 0x8, 0x8, 0xc00039edc0) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc001400800, 0xc0004ca950, 0xc0013d8000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc001400800, 0xc0013d8000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f0e62dee0d8) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000141cb0, 0x7f0e62dee0d8, 0xc001400800, 0x5, 0xc001400800, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc00026a000, 0x10d1417, 0xc00026a120, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc00026a000, 0xc0013d9000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000f86c0, 0xc0013d9000, 0x1000, 0x1000, 0xc0004cac70, 0x406865, 0xc000335140) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000134540) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000134540, 0x1, 0x0, 0x0, 0x1, 0xc000183080, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000f86c0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 3756 [IO wait]: internal/poll.runtime_pollWait(0x7f0e62dedaf0, 0x72, 0xc0004d1760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc0003d2b18, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc0003d2b18, 0xc001356400, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc0003d2b00, 0xc001356400, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc0003d2b00, 0xc001356400, 0x400, 0x400, 0xc000362b00, 0xc000322f60, 0xc000053500) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001ed5b8, 0xc001356400, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc001356400, 0x400, 0x400, 0x8, 0x8, 0xc001468840) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc001400000, 0xc0004d1950, 0xc001356400, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc001400000, 0xc001356400, 0x400, 0x400, 0xfea4c0, 0x0, 0x7f0e62dee0d8) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000140900, 0x7f0e62dee0d8, 0xc001400000, 0x5, 0xc001400000, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0001a7c00, 0x10d1417, 0xc0001a7d20, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0001a7c00, 0xc000318000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc000326120, 0xc000318000, 0x1000, 0x1000, 0xc0004d1c70, 0x406865, 0xc0001830e0) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc00028ccc0) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc00028ccc0, 0x1, 0x0, 0x0, 0x1, 0xc000182f00, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc000326120) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4371 [select]: net/http.(*persistConn).writeLoop(0xc0000f86c0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4691 [select]: net/http.(*persistConn).roundTrip(0xc0000f86c0, 0xc0002105d0, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/transport.go:2101 +0x56a net/http.(*Transport).roundTrip(0xc000194120, 0xc0003c2700, 0xa, 0xc0000a4590, 0x10) /usr/local/go/src/net/http/transport.go:465 +0x9b1 net/http.(*Transport).RoundTrip(0xc000194120, 0xc0003c2700, 0xa, 0xc0000a4590, 0x10) /usr/local/go/src/net/http/roundtrip.go:17 +0x35 github.com/ncw/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000140db0, 0xc0003c2700, 0xc0003c2700, 0x0, 0xc001374ee0) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:282 +0xff github.com/ncw/rclone/vendor/golang.org/x/oauth2.(*Transport).RoundTrip(0xc00058ec60, 0xc0003c2600, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/golang.org/x/oauth2/transport.go:56 +0xfb net/http.send(0xc0003c2600, 0x11df2e0, 0xc00058ec60, 0x0, 0x0, 0x0, 0xc000524030, 0x0, 0xc001375068, 0x1) /usr/local/go/src/net/http/client.go:250 +0x14b net/http.(*Client).send(0xc00058ec90, 0xc0003c2600, 0x0, 0x0, 0x0, 0xc000524030, 0x0, 0x1, 0xc0004748a8) /usr/local/go/src/net/http/client.go:174 +0xfa net/http.(*Client).do(0xc00058ec90, 0xc0003c2600, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/client.go:641 +0x2a8 net/http.(*Client).Do(0xc00058ec90, 0xc0003c2600, 0x1088e13, 0xf, 0x1a4b720) /usr/local/go/src/net/http/client.go:509 +0x35 github.com/ncw/rclone/vendor/google.golang.org/api/gensupport.SendRequest(0x0, 0x0, 0xc00058ec90, 0xc0003c2600, 0xc0013754b8, 0xc000150690, 0xc0003c2600) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/gensupport/send.go:43 +0x24b github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesUpdateCall).doRequest(0xc001375670, 0x107e151, 0x4, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:5555 +0x682 github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesUpdateCall).Do(0xc001375670, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:5567 +0x8b github.com/ncw/rclone/backend/drive.(*Fs).rmdir.func1(0xc000031860, 0x11d88a0, 0x109f469) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1664 +0x1f6 github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000031860, 0xc000323200, 0xa, 0xfad600, 0xc0000e7e01) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:383 +0x16a github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000031860, 0xc000323200, 0x0, 0xc001362418) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).rmdir(0xc00133a600, 0xc00045c090, 0x21, 0x1, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1655 +0x8a github.com/ncw/rclone/backend/drive.(*Fs).Rmdir(0xc00133a600, 0xc0000e7e20, 0x1a, 0xc0000e7e20, 0x1a) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1705 +0x23f github.com/ncw/rclone/backend/crypt.(*Fs).Rmdir(0xc0003868c0, 0xc00134d420, 0x9, 0x12, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:413 +0x7c github.com/ncw/rclone/fs/operations.TryRmdir(0x11f20c0, 0xc0003868c0, 0xc00134d420, 0x9, 0xc0000e8780, 0xc00135ca01) /home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.go:941 +0x15c github.com/ncw/rclone/fs/sync.deleteEmptyDirectories(0x11f20c0, 0xc0003868c0, 0xc000141260, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:443 +0x38f github.com/ncw/rclone/fs/sync.(*syncCopyMove).run(0xc0013e8380, 0xc0013fa780, 0x11f20c0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:696 +0x3a6 github.com/ncw/rclone/fs/sync.runSyncCopyMove(0x11f20c0, 0xc0013fa780, 0x11f20c0, 0xc0003868c0, 0x10100, 0xc0013fc390, 0xc0013fc360) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:860 +0x142 github.com/ncw/rclone/fs/sync.moveDir(0x11f20c0, 0xc0013fa780, 0x11f20c0, 0xc0003868c0, 0xc000386801, 0x1, 0x1) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:875 +0x59 github.com/ncw/rclone/fs/sync.MoveDir(0x11f20c0, 0xc0013fa780, 0x11f20c0, 0xc0003868c0, 0x1, 0x2, 0x3c) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:914 +0x30b github.com/ncw/rclone/fs/sync.testServerSideMove(0xc0004b8400, 0xc0004e4480, 0xc0004e0100) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:936 +0x741 github.com/ncw/rclone/fs/sync.TestServerSideMoveDeleteEmptySourceDirs(0xc0004b8400) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1049 +0x79 testing.tRunner(0xc0004b8400, 0x10cfec8) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 4756 [chan receive]: github.com/ncw/rclone/fs/march.(*March).Run.func2(0xc0003e2280, 0xc000383980, 0xc00134cb10) /home/rclone/go/src/github.com/ncw/rclone/fs/march/march.go:174 +0x4e created by github.com/ncw/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/ncw/rclone/fs/march/march.go:172 +0x240 goroutine 4558 [select]: net/http.(*persistConn).writeLoop(0xc0003266c0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4788 [sleep]: time.Sleep(0x128b48d01) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000031860, 0x128b48d01) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c goroutine 4557 [IO wait]: internal/poll.runtime_pollWait(0x7f0e62dedbc0, 0x72, 0xc000417760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc0004a4418, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc0004a4418, 0xc00016ec00, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc0004a4400, 0xc00016ec00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc0004a4400, 0xc00016ec00, 0x400, 0x400, 0xc00135c820, 0xc0001b9710, 0xc000053500) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001ed678, 0xc00016ec00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc00016ec00, 0x400, 0x400, 0x8, 0x8, 0xc00039e880) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0014005e0, 0xc000417950, 0xc00016ec00, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc0014005e0, 0xc00016ec00, 0x400, 0x400, 0xfea4c0, 0x0, 0x7f0e62dee0d8) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000322f00, 0x7f0e62dee0d8, 0xc0014005e0, 0x5, 0xc0014005e0, 0xc000053500) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0001a7500, 0x10d1417, 0xc0001a7620, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0001a7500, 0xc0004ad000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0003266c0, 0xc0004ad000, 0x1000, 0x1000, 0xc000417c70, 0x406865, 0xc000334c60) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0004e4720) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0004e4720, 0x1, 0x0, 0x0, 0x1, 0xc000334ba0, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0003266c0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 3757 [select]: net/http.(*persistConn).writeLoop(0xc000326120) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 2018/10/24 05:49:08 ------------------------------------------------------------ 2018/10/24 05:49:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Finished ERROR in 30m35.840973641s (try 1/5): exit status 2: Failed [] 2018/10/24 05:49:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Starting (try 2/5) 2018/10/24 06:18:39 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Expecting errors but got none: [] 2018/10/24 06:18:39 ------------------------------------------------------------ 2018/10/24 06:18:39 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" ---- 2018/10/24 06:18:39 2018/10/24 05:48:40 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:51:43 ERROR : : error listing: directory not found 2018/10/24 05:56:19 NOTICE: empty space: Not updating modification time as --dry-run 2018/10/24 05:59:39 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:59:39 NOTICE: potato: Not deleting as --dry-run 2018/10/24 06:02:49 ERROR : Google drive root 'rclone-test-bepaqiz0zeqotiv9jubacuk7': not deleting files as there were IO errors 2018/10/24 06:02:49 ERROR : Google drive root 'rclone-test-bepaqiz0zeqotiv9jubacuk7': not deleting directories as there were IO errors 2018/10/24 06:12:18 purge failed: directory not found 2018/10/24 06:14:57 purge failed: directory not found panic: test timed out after 30m0s goroutine 5436 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc0004ed400, 0x1089386, 0xf, 0x10d0000, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000598000) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000598000, 0xc00023bdc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0001f0aa0, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc00011cc00, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc00011cc00) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc00011cc00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 5427 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc0002ac960) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc0002ac960, 0xc0001f32c0, 0xa, 0xfcea40, 0xc000039801) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc0002ac960, 0xc0001f32c0, 0xc00035f8c8, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc00029e480, 0xc00035f9b0, 0x1, 0x1, 0x10821db, 0x8, 0xc000000100, 0xc00035f9c0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).getRemoteInfoWithExport(0xc00029e480, 0x10821db, 0x8, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:2265 +0x2ad github.com/ncw/rclone/backend/drive.(*Fs).NewObject(0xc00029e480, 0x10821db, 0x8, 0xc000528e60, 0x0, 0x1a2d520, 0xc0000d8af0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1067 +0x4f github.com/ncw/rclone/backend/drive.(*Fs).Put(0xc00029e480, 0x11ddd20, 0xc0000d8af0, 0x11eee00, 0xc000528e60, 0x0, 0x0, 0x0, 0x70, 0xfb6ec0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1517 +0x5a github.com/ncw/rclone/fs.Fs.Put-fm(0x11ddd20, 0xc0000d8af0, 0x11eee00, 0xc000528e60, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:231 +0x88 github.com/ncw/rclone/fstest.(*Run).WriteObjectTo(0xc000147b60, 0x11f21c0, 0xc00029e480, 0x10821db, 0x8, 0x108cdb3, 0x12, 0x75bcd15, 0xec689154f, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:243 +0x24c github.com/ncw/rclone/fstest.(*Run).WriteObject(0xc000147b60, 0x10821db, 0x8, 0x108cdb3, 0x12, 0x75bcd15, 0xec689154f, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:260 +0xe1 github.com/ncw/rclone/fs/sync.TestSyncUTFNorm(0xc0004ed400) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1167 +0x421 testing.tRunner(0xc0004ed400, 0x10d0000) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 4835 [select]: net/http.(*persistConn).writeLoop(0xc0001ecea0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4834 [IO wait]: internal/poll.runtime_pollWait(0x7fde3acd4e30, 0x72, 0xc00005e760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000600198, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000600198, 0xc0005b7000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000600180, 0xc0005b7000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000600180, 0xc0005b7000, 0x1000, 0x1000, 0xc00038d130, 0xc0001f2870, 0x1a2d520) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001c5630, 0xc0005b7000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0005b7000, 0x1000, 0x1000, 0x8, 0x8, 0xc000145280) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00049e7a0, 0xc00005e950, 0xc0005b7000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00049e7a0, 0xc0005b7000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7fde3ac90000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc00039e600, 0x7fde3ac90000, 0xc00049e7a0, 0x5, 0xc00049e7a0, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc000128a80, 0x10d1417, 0xc000128ba0, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc000128a80, 0xc000614000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0001ecea0, 0xc000614000, 0x1000, 0x1000, 0xc00005ec70, 0x406865, 0xc00008ff80) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0005cf560) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0005cf560, 0x1, 0x0, 0x0, 0x1, 0xc0003b6cc0, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0001ecea0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 5215 [IO wait]: internal/poll.runtime_pollWait(0x7fde3acd4d60, 0x72, 0xc000388760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc0004e3998, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc0004e3998, 0xc0004f7c00, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc0004e3980, 0xc0004f7c00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc0004e3980, 0xc0004f7c00, 0x400, 0x400, 0xc0001cb430, 0xc0002a7110, 0x1a2d520) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001c5720, 0xc0004f7c00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0004f7c00, 0x400, 0x400, 0x8, 0x8, 0xc00047a480) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0004e03e0, 0xc000388950, 0xc0004f7c00, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004e03e0, 0xc0004f7c00, 0x400, 0x400, 0xfea4c0, 0x0, 0x7fde3ac90000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000139c50, 0x7fde3ac90000, 0xc0004e03e0, 0x5, 0xc0004e03e0, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc00016a700, 0x10d1417, 0xc00016a820, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc00016a700, 0xc000437000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000e7e60, 0xc000437000, 0x1000, 0x1000, 0xc000388c70, 0x406865, 0xc000f1a3c0) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0005cef60) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0005cef60, 0x1, 0x0, 0x0, 0x1, 0xc00008e540, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000e7e60) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 5216 [select]: net/http.(*persistConn).writeLoop(0xc0000e7e60) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 5311 [sleep]: time.Sleep(0x125d55c5a) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc0002ac960, 0x125d55c5a) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c 2018/10/24 06:18:39 ------------------------------------------------------------ 2018/10/24 06:18:39 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Finished ERROR in 30m0.604754262s (try 2/5): exit status 2: Failed [] 2018/10/24 06:18:39 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Starting (try 3/5) 2018/10/24 06:18:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Expecting errors but got none: [] 2018/10/24 06:18:41 ------------------------------------------------------------ 2018/10/24 06:18:41 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" ---- 2018/10/24 06:18:41 2018/10/24 05:48:42 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:51:51 ERROR : : error listing: directory not found 2018/10/24 05:55:20 NOTICE: empty space: Not updating modification time as --dry-run 2018/10/24 05:57:49 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:57:49 NOTICE: potato: Not deleting as --dry-run 2018/10/24 06:01:21 ERROR : Google drive root 'rclone-test-wuhaqor3piwitom2sogizex2': not deleting files as there were IO errors 2018/10/24 06:01:21 ERROR : Google drive root 'rclone-test-wuhaqor3piwitom2sogizex2': not deleting directories as there were IO errors 2018/10/24 06:09:01 ERROR : : error listing: directory not found 2018/10/24 06:09:01 purge failed: directory not found 2018/10/24 06:13:47 ERROR : : error listing: directory not found 2018/10/24 06:13:49 ERROR : : error listing: directory not found 2018/10/24 06:13:58 purge failed: directory not found 2018/10/24 06:18:30 ERROR : existing: Source and destination exist but do not match: immutable file modified 2018/10/24 06:18:30 ERROR : Google drive root 'rclone-test-wuhaqor3piwitom2sogizex2': not deleting files as there were IO errors 2018/10/24 06:18:30 ERROR : Google drive root 'rclone-test-wuhaqor3piwitom2sogizex2': not deleting directories as there were IO errors panic: test timed out after 30m0s goroutine 4865 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc000032900, 0x1083115, 0x9, 0x10cfe30, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000032a00) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000032a00, 0xc000241dc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc000592380, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc00011cc80, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc00011cc80) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc00011cc80) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 5042 [semacquire]: sync.runtime_Semacquire(0xc00040a398) /usr/local/go/src/runtime/sema.go:56 +0x39 sync.(*WaitGroup).Wait(0xc00040a390) /usr/local/go/src/sync/waitgroup.go:130 +0x64 github.com/ncw/rclone/fs/walk.walk(0x11f21c0, 0xc0002a6480, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc000506480, 0x10cfd68, 0xc000295c00, 0xd) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:178 +0x317 github.com/ncw/rclone/fs/walk.walkListDirSorted(0x11f21c0, 0xc0002a6480, 0x0, 0x0, 0xc00059b301, 0xffffffffffffffff, 0xc000506480, 0xf79180, 0x4f9701) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:75 +0x7f github.com/ncw/rclone/fs/walk.Walk(0x11f21c0, 0xc0002a6480, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc000506480, 0x1c, 0x100000000000001) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:68 +0xa3 github.com/ncw/rclone/fstest.NewRun.func1() /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:141 +0xd5 github.com/ncw/rclone/fstest.(*Run).Finalise(0xc000078fc0) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:310 +0x2b runtime.Goexit() /usr/local/go/src/runtime/panic.go:397 +0xef testing.(*common).SkipNow(0xc000032900) /usr/local/go/src/testing/testing.go:660 +0x39 testing.(*common).Skip(0xc000032900, 0xc00059deb0, 0x1, 0x1) /usr/local/go/src/testing/testing.go:640 +0x6f github.com/ncw/rclone/fs/sync.TestAbort(0xc000032900) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1221 +0x111 testing.tRunner(0xc000032900, 0x10cfe30) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 5050 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc0002b68a0) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc0002b68a0, 0xc0005d85d0, 0xa, 0xfcea40, 0xc000486001) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc0002b68a0, 0xc0005d85d0, 0xc000063c38, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc0002a6480, 0xc000063d20, 0x1, 0x1, 0x0, 0x0, 0x0, 0xc000063d30, 0x0, 0xc000510660, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).List(0xc0002a6480, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc00013cb40, 0xc000128a80) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1281 +0x18f github.com/ncw/rclone/fs/list.DirSorted(0x11f21c0, 0xc0002a6480, 0x1, 0x0, 0x0, 0x0, 0xc000090480, 0xc00014e0d0, 0xc000510710, 0xdb0bc4) /home/rclone/go/src/github.com/ncw/rclone/fs/list/list.go:23 +0x63 github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:128 +0x1b7 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4668 [select]: net/http.(*persistConn).writeLoop(0xc0000e6480) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 5048 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4667 [IO wait]: internal/poll.runtime_pollWait(0x7fe7d0af2e30, 0x72, 0xc00005f760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000625298, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000625298, 0xc0000d6000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000625280, 0xc0000d6000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000625280, 0xc0000d6000, 0x1000, 0x1000, 0xc0001aefa0, 0xc0002ad320, 0xc000052a80) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001cb600, 0xc0000d6000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0000d6000, 0x1000, 0x1000, 0x8, 0x8, 0xc00013c700) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0004ea340, 0xc00005f950, 0xc0000d6000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc0004ea340, 0xc0000d6000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7fe7d0afb390) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000141350, 0x7fe7d0afb390, 0xc0004ea340, 0x5, 0xc0004ea340, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc000126a80, 0x10d1417, 0xc000126ba0, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc000126a80, 0xc000500000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000e6480, 0xc000500000, 0x1000, 0x1000, 0xc00005fc70, 0x406865, 0xc000090240) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000078a20) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000078a20, 0x1, 0x0, 0x0, 0x1, 0xc00035a0c0, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000e6480) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 5045 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 5043 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 5009 [sleep]: time.Sleep(0x2002e5f68) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc0002b68a0, 0x2002e5f68) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c goroutine 5047 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 5046 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 5049 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 5044 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc00040a370, 0xc000079020, 0x10cfd68, 0x11f21c0, 0xc0002a6480, 0xc00059b301, 0xc00040a368, 0xc000506480, 0xc00040a390, 0xc0005d8570, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 2018/10/24 06:18:41 ------------------------------------------------------------ 2018/10/24 06:18:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Finished ERROR in 30m0.543422372s (try 2/5): exit status 2: Failed [] 2018/10/24 06:18:41 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Starting (try 3/5) 2018/10/24 06:19:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Expecting errors but got none: [] 2018/10/24 06:19:08 ------------------------------------------------------------ 2018/10/24 06:19:08 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" ---- 2018/10/24 06:19:08 2018/10/24 05:49:28 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:52:49 ERROR : : error listing: directory not found 2018/10/24 05:59:07 NOTICE: empty space: Not copying as --dry-run 2018/10/24 06:01:59 NOTICE: potato2: Not copying as --dry-run 2018/10/24 06:01:59 NOTICE: potato: Not deleting as --dry-run 2018/10/24 06:05:11 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-piweyep1xetiyor6bewatuq8': not deleting files as there were IO errors 2018/10/24 06:05:11 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-piweyep1xetiyor6bewatuq8': not deleting directories as there were IO errors 2018/10/24 06:09:49 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-piweyep1xetiyor6bewatuq8': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:11:08 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-piweyep1xetiyor6bewatuq8': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:15:01 ERROR : : error listing: directory not found 2018/10/24 06:15:09 purge failed: directory not found 2018/10/24 06:18:49 ERROR : : error listing: directory not found 2018/10/24 06:18:49 ERROR : : error listing: directory not found 2018/10/24 06:18:58 purge failed: directory not found panic: test timed out after 30m0s goroutine 4456 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc00009ce00, 0x108b91c, 0x11, 0x10cff58, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000032300) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000032300, 0xc0000cddc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0003b6760, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc000138c80, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc000138c80) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc000138c80) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 19 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4439 [sleep]: time.Sleep(0xb1c51817) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000504180, 0xb1c51817) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c goroutine 4249 [IO wait]: internal/poll.runtime_pollWait(0x7fe1df711af0, 0x72, 0xc00005e760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc0001b2c98, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc0001b2c98, 0xc00053f000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc0001b2c80, 0xc00053f000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc0001b2c80, 0xc00053f000, 0x1000, 0x1000, 0xc000534660, 0xc0004dec00, 0x1a2d520) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc0001d3730, 0xc00053f000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc00053f000, 0x1000, 0x1000, 0x8, 0x8, 0xc000190d40) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0003d1060, 0xc00005e950, 0xc00053f000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc0003d1060, 0xc00053f000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7fe1df67d268) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0001ccf00, 0x7fe1df67d268, 0xc0003d1060, 0x5, 0xc0003d1060, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc000053c00, 0x10d1417, 0xc000053d20, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc000053c00, 0xc0004a2000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc000312480, 0xc0004a2000, 0x1000, 0x1000, 0xc00005ec70, 0x406865, 0xc0000bc660) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000151c80) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000151c80, 0x1, 0x0, 0x0, 0x1, 0xc0000bc0c0, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc000312480) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4250 [select]: net/http.(*persistConn).writeLoop(0xc000312480) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4427 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc000504180) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000504180, 0xc0004dec90, 0xa, 0xfcea40, 0xc00015e401) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000504180, 0xc0004dec90, 0xc0004c9778, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc000485980, 0xc0004c9850, 0x1, 0x1, 0xc00015e134, 0x1a, 0x1, 0xc0004c9860, 0x21, 0x1, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).FindLeaf(0xc000485980, 0xc00003be90, 0x21, 0xc00015e134, 0x1a, 0x0, 0x0, 0x0, 0x3, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1087 +0xfa github.com/ncw/rclone/lib/dircache.(*DirCache)._findDir(0xc0002afcb0, 0xc00015e0e0, 0x6e, 0x0, 0xc000561a40, 0x7f2787, 0xc0002afcb0, 0xc0002afcb0) /home/rclone/go/src/github.com/ncw/rclone/lib/dircache/dircache.go:181 +0x12f github.com/ncw/rclone/lib/dircache.(*DirCache).FindRoot(0xc0002afcb0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/lib/dircache/dircache.go:233 +0xa5 github.com/ncw/rclone/backend/drive.NewFs(0xc0004a40a9, 0x9, 0xc00015e0e0, 0x6e, 0x11e2dc0, 0xc0004de0f0, 0x5, 0xc0004de0f0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:911 +0x611 github.com/ncw/rclone/backend/crypt.NewFs(0xc0000a8bc0, 0xe, 0xc0000a8bcf, 0x28, 0x11e2dc0, 0xc0001cd260, 0x28, 0xc0001cd260, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:147 +0x303 github.com/ncw/rclone/fs.NewFs(0xc0000a8bc0, 0x37, 0x33, 0x107dbd1, 0x4, 0xc0000a8bc0) /home/rclone/go/src/github.com/ncw/rclone/fs/fs.go:1050 +0xb5 github.com/ncw/rclone/fs/sync.testSyncBackupDir(0xc00009ce00, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1109 +0x729 github.com/ncw/rclone/fs/sync.TestSyncBackupDir(0xc00009ce00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1146 +0x33 testing.tRunner(0xc00009ce00, 0x10cff58) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 2018/10/24 06:19:08 ------------------------------------------------------------ 2018/10/24 06:19:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Finished ERROR in 30m19.044013809s (try 2/5): exit status 2: Failed [] 2018/10/24 06:19:08 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Starting (try 3/5) 2018/10/24 06:19:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Expecting errors but got none: [] 2018/10/24 06:19:28 ------------------------------------------------------------ 2018/10/24 06:19:28 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" ---- 2018/10/24 06:19:28 2018/10/24 05:49:29 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 05:52:49 ERROR : : error listing: directory not found 2018/10/24 05:56:27 NOTICE: empty space: Not copying as --dry-run 2018/10/24 05:59:10 NOTICE: potato2: Not copying as --dry-run 2018/10/24 05:59:10 NOTICE: potato: Not deleting as --dry-run 2018/10/24 06:02:05 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-veloquk9leworav7konogiv1': not deleting files as there were IO errors 2018/10/24 06:02:05 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-veloquk9leworav7konogiv1': not deleting directories as there were IO errors 2018/10/24 06:07:29 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-veloquk9leworav7konogiv1': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:07:40 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-veloquk9leworav7konogiv1': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:11:20 purge failed: directory not found 2018/10/24 06:15:37 purge failed: directory not found 2018/10/24 06:19:10 ERROR : existing: Source and destination exist but do not match: immutable file modified 2018/10/24 06:19:10 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-veloquk9leworav7konogiv1': not deleting files as there were IO errors 2018/10/24 06:19:10 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-veloquk9leworav7konogiv1': not deleting directories as there were IO errors panic: test timed out after 30m0s goroutine 5654 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc000ed4e00, 0x1083115, 0x9, 0x10cfe30, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000033100) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000033100, 0xc00028bdc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0004c6cc0, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc000156c00, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc000156c00) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc000156c00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4923 [select]: net/http.(*persistConn).writeLoop(0xc00023c7e0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 5612 [select]: net/http.(*persistConn).roundTrip(0xc00023c7e0, 0xc0001e6a20, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/transport.go:2101 +0x56a net/http.(*Transport).roundTrip(0xc0001aa120, 0xc000ed5000, 0xa, 0xc000036610, 0x10) /usr/local/go/src/net/http/transport.go:465 +0x9b1 net/http.(*Transport).RoundTrip(0xc0001aa120, 0xc000ed5000, 0xa, 0xc000036610, 0x10) /usr/local/go/src/net/http/roundtrip.go:17 +0x35 github.com/ncw/rclone/fs/fshttp.(*Transport).RoundTrip(0xc000136c60, 0xc000ed5000, 0xc000ed5000, 0x0, 0xc000eeb498) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:282 +0xff github.com/ncw/rclone/vendor/golang.org/x/oauth2.(*Transport).RoundTrip(0xc0001404b0, 0xc000ed4f00, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/golang.org/x/oauth2/transport.go:56 +0xfb net/http.send(0xc000ed4f00, 0x11df2e0, 0xc0001404b0, 0x0, 0x0, 0x0, 0xc0002036e8, 0xc0005a4630, 0xc000eeb620, 0x1) /usr/local/go/src/net/http/client.go:250 +0x14b net/http.(*Client).send(0xc0001404e0, 0xc000ed4f00, 0x0, 0x0, 0x0, 0xc0002036e8, 0x0, 0x1, 0x40d97f) /usr/local/go/src/net/http/client.go:174 +0xfa net/http.(*Client).do(0xc0001404e0, 0xc000ed4f00, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/client.go:641 +0x2a8 net/http.(*Client).Do(0xc0001404e0, 0xc000ed4f00, 0x1088e13, 0xf, 0x1a4b720) /usr/local/go/src/net/http/client.go:509 +0x35 github.com/ncw/rclone/vendor/google.golang.org/api/gensupport.SendRequest(0x0, 0x0, 0xc0001404e0, 0xc000ed4f00, 0x0, 0x0, 0xc000ed4f00) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/gensupport/send.go:43 +0x24b github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesListCall).doRequest(0xc000077900, 0x107e151, 0x4, 0x0, 0x6b, 0xc000038f50) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:5244 +0x428 github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesListCall).Do(0xc000077900, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:5256 +0x85 github.com/ncw/rclone/backend/drive.(*Fs).list.func1(0xc000030660, 0x40d97f, 0xc0001e68d0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:588 +0x98 github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000030660, 0xc0001e68d0, 0xa, 0xfcea40, 0xc000038f01) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:383 +0x16a github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000030660, 0xc0001e68d0, 0xc0005a4d98, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc0001c8600, 0xc00045a980, 0x1, 0x1, 0x0, 0x0, 0x0, 0xc000eebf30, 0xc0003abb24, 0xffffffffffffffff, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1325 +0x2bd created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5615 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5611 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).ListR(0xc0001c8600, 0x0, 0x0, 0xc0003b2a40, 0x1, 0xc0003b2a40) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1453 +0x423 github.com/ncw/rclone/fs.ListRer.ListR-fm(0x0, 0x0, 0xc0003b2a40, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/fs.go:598 +0x4d github.com/ncw/rclone/backend/crypt.(*Fs).ListR(0xc0000c5e00, 0x0, 0x0, 0xc000312640, 0xf78b01, 0xc000312640) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:310 +0xe6 github.com/ncw/rclone/fs.ListRer.ListR-fm(0x0, 0x0, 0xc000312640, 0xc0003b2a20, 0x20) /home/rclone/go/src/github.com/ncw/rclone/fs/fs.go:598 +0x4d github.com/ncw/rclone/fs/walk.walkRDirTree(0x11f20c0, 0xc0000c5e00, 0x0, 0x0, 0xc0003b2901, 0xffffffffffffffff, 0xc0004c6a60, 0xc0003c65c0, 0xc0003b2a00, 0xa) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:365 +0x130 github.com/ncw/rclone/fs/walk.walkR(0x11f20c0, 0xc0000c5e00, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc0003b2a00, 0xc0004c6a60, 0x1a2d520, 0xd) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:476 +0x8d github.com/ncw/rclone/fs/walk.walkListR(0x11f20c0, 0xc0000c5e00, 0x0, 0x0, 0xc000ee3301, 0xffffffffffffffff, 0xc0003b2a00, 0xf79180, 0x1) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:87 +0x9a github.com/ncw/rclone/fs/walk.Walk(0x11f20c0, 0xc0000c5e00, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc0003b2a00, 0x1, 0x101000000000001) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:66 +0x146 github.com/ncw/rclone/fstest.NewRun.func1() /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:141 +0xd5 github.com/ncw/rclone/fstest.(*Run).Finalise(0xc0005e45a0) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:310 +0x2b runtime.Goexit() /usr/local/go/src/runtime/panic.go:397 +0xef testing.(*common).SkipNow(0xc000ed4e00) /usr/local/go/src/testing/testing.go:660 +0x39 testing.(*common).Skip(0xc000ed4e00, 0xc000ee5eb0, 0x1, 0x1) /usr/local/go/src/testing/testing.go:640 +0x6f github.com/ncw/rclone/fs/sync.TestAbort(0xc000ed4e00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1221 +0x111 testing.tRunner(0xc000ed4e00, 0x10cfe30) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 5651 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5613 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5614 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5652 [semacquire]: sync.runtime_Semacquire(0xc0003ab368) /usr/local/go/src/runtime/sema.go:56 +0x39 sync.(*WaitGroup).Wait(0xc0003ab360) /usr/local/go/src/sync/waitgroup.go:130 +0x64 github.com/ncw/rclone/backend/drive.(*Fs).ListR.func2(0xc0003ab360, 0xc0003b2a80, 0xc0003ab348, 0xc0002036c0) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1424 +0x2f created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1422 +0x393 goroutine 4922 [IO wait]: internal/poll.runtime_pollWait(0x7f91870dfd60, 0x72, 0xc0005a2760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000157d98, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000157d98, 0xc00033c000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000157d80, 0xc00033c000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000157d80, 0xc00033c000, 0x1000, 0x1000, 0xc00045a870, 0xc0001e6720, 0x1a2d520) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc00000e030, 0xc00033c000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc00033c000, 0x1000, 0x1000, 0x8, 0x8, 0xc000077880) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00030e020, 0xc0005a2950, 0xc00033c000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00030e020, 0xc00033c000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f91870a76e8) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0001d0180, 0x7f91870a76e8, 0xc00030e020, 0x5, 0xc00030e020, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0001cc380, 0x10d1417, 0xc0001cc4a0, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0001cc380, 0xc000483000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc00023c7e0, 0xc000483000, 0x1000, 0x1000, 0xc0005a2c70, 0x406865, 0xc000e3a060) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0000317a0) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0000317a0, 0x1, 0x0, 0x0, 0x1, 0xc000084c00, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc00023c7e0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 5617 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5616 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f goroutine 5650 [chan receive]: github.com/ncw/rclone/backend/drive.(*Fs).listRRunner(0xc0001c8600, 0xc0003ab360, 0xc0005e4660, 0xc0005e4720, 0xc0001e6870, 0x32) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1309 +0x36e created by github.com/ncw/rclone/backend/drive.(*Fs).ListR /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1420 +0x32f 2018/10/24 06:19:28 ------------------------------------------------------------ 2018/10/24 06:19:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Finished ERROR in 30m19.947970129s (try 2/5): exit status 2: Failed [] 2018/10/24 06:19:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Starting (try 3/5) 2018/10/24 06:45:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive:" - Finished OK in 26m47.900652396s (try 3/5) 2018/10/24 06:47:07 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestDrive: -fast-list" - Finished OK in 28m28.312309241s (try 3/5) 2018/10/24 06:49:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Expecting errors but got none: [] 2018/10/24 06:49:28 ------------------------------------------------------------ 2018/10/24 06:49:28 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" ---- 2018/10/24 06:49:28 2018/10/24 06:19:38 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 06:22:12 ERROR : : error listing: directory not found 2018/10/24 06:26:29 NOTICE: empty space: Not copying as --dry-run 2018/10/24 06:29:09 NOTICE: potato2: Not copying as --dry-run 2018/10/24 06:29:09 NOTICE: potato: Not deleting as --dry-run 2018/10/24 06:32:19 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-dobijul6qerofoj9lakiset8': not deleting files as there were IO errors 2018/10/24 06:32:19 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-dobijul6qerofoj9lakiset8': not deleting directories as there were IO errors 2018/10/24 06:37:28 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-dobijul6qerofoj9lakiset8': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:37:39 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-dobijul6qerofoj9lakiset8': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:40:24 ERROR : : error listing: directory not found 2018/10/24 06:40:24 purge failed: directory not found 2018/10/24 06:44:09 ERROR : : error listing: directory not found 2018/10/24 06:44:09 ERROR : : error listing: directory not found 2018/10/24 06:44:18 purge failed: directory not found panic: test timed out after 30m0s goroutine 4970 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc0001be200, 0x108b92d, 0x11, 0x10cffe8, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc0001be400) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc0001be400, 0xc000299dc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0003f0600, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc000158c00, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc000158c00) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc000158c00) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4808 [semacquire]: sync.runtime_Semacquire(0xc0001573b8) /usr/local/go/src/runtime/sema.go:56 +0x39 sync.(*WaitGroup).Wait(0xc0001573b0) /usr/local/go/src/sync/waitgroup.go:130 +0x64 github.com/ncw/rclone/fs/walk.walk(0x11f20c0, 0xc000283ae0, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0xc00039c7a0, 0x10cfd68, 0xc0001b5500, 0x1) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:178 +0x317 github.com/ncw/rclone/fs/walk.walkListDirSorted(0x11f20c0, 0xc000283ae0, 0x0, 0x0, 0xc000df7a01, 0xffffffffffffffff, 0xc00039c7a0, 0xf78e60, 0x1029d01) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:75 +0x7f github.com/ncw/rclone/fs/walk.Walk(0x11f20c0, 0xc000283ae0, 0x0, 0x0, 0xc0001be201, 0xffffffffffffffff, 0xc00039c7a0, 0x780fe0, 0xc0001640c0) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:68 +0xa3 github.com/ncw/rclone/fs/walk.GetAll(0x11f20c0, 0xc000283ae0, 0x0, 0x0, 0x1, 0xffffffffffffffff, 0x0, 0x1, 0x1, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:511 +0xe0 github.com/ncw/rclone/fstest.CheckListingWithPrecision(0xc0001be200, 0x11f20c0, 0xc000283ae0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0xf4240) /home/rclone/go/src/github.com/ncw/rclone/fstest/fstest.go:286 +0x206 github.com/ncw/rclone/fstest.CheckItems(0xc0001be200, 0x11f20c0, 0xc000283ae0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fstest/fstest.go:346 +0xeb github.com/ncw/rclone/fs/sync.TestSyncImmutable(0xc0001be200) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1193 +0x21e testing.tRunner(0xc0001be200, 0x10cffe8) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 4487 [IO wait]: internal/poll.runtime_pollWait(0x7f768d2cba20, 0x72, 0xc00005f760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000ea5798, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000ea5798, 0xc0013d2000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000ea5780, 0xc0013d2000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000ea5780, 0xc0013d2000, 0x1000, 0x1000, 0xc00041a830, 0xc000150ba0, 0xc000128000) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc00000e028, 0xc0013d2000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0013d2000, 0x1000, 0x1000, 0x8, 0x8, 0x40) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00138c020, 0xc00005f950, 0xc0013d2000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00138c020, 0xc0013d2000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f768d1f7000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000141200, 0x7f768d1f7000, 0xc00138c020, 0x5, 0xc00138c020, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0002e9c00, 0x10d1417, 0xc0002e9d20, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0002e9c00, 0xc0013d3000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000e7200, 0xc0013d3000, 0x1000, 0x1000, 0xc00005fc70, 0x406865, 0xc000209020) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000079aa0) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000079aa0, 0x1, 0x0, 0x0, 0x1, 0xc000090600, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000e7200) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4964 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4488 [select]: net/http.(*persistConn).writeLoop(0xc0000e7200) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4726 [IO wait]: internal/poll.runtime_pollWait(0x7f768d2cbc90, 0x72, 0xc00005d760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc00011cf98, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc00011cf98, 0xc0003a0000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc00011cf80, 0xc0003a0000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc00011cf80, 0xc0003a0000, 0x1000, 0x1000, 0xc0003e63a0, 0xc000139470, 0xc0001b4380) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc000154010, 0xc0003a0000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0003a0000, 0x1000, 0x1000, 0x8, 0x8, 0xc000594580) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc00039c000, 0xc00005d950, 0xc0003a0000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc00039c000, 0xc0003a0000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f768d1f7000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000138660, 0x7f768d1f7000, 0xc00039c000, 0x5, 0xc00039c000, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0001db180, 0x10d1417, 0xc0001db2a0, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0001db180, 0xc0003a1000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000e6900, 0xc0003a1000, 0x1000, 0x1000, 0xc00005dc70, 0x406865, 0xc000208600) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0004d6540) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0004d6540, 0x1, 0x0, 0x0, 0x1, 0xc0000915c0, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000e6900) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4966 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4963 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4817 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4962 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4965 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4727 [select]: net/http.(*persistConn).writeLoop(0xc0000e6900) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4967 [select]: github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:123 +0x119 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 goroutine 4968 [select]: net/http.(*persistConn).roundTrip(0xc0000e7200, 0xc0001f6c00, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/transport.go:2101 +0x56a net/http.(*Transport).roundTrip(0xc0001b6120, 0xc0001be500, 0xa, 0xc000036610, 0x10) /usr/local/go/src/net/http/transport.go:465 +0x9b1 net/http.(*Transport).RoundTrip(0xc0001b6120, 0xc0001be500, 0xa, 0xc000036610, 0x10) /usr/local/go/src/net/http/roundtrip.go:17 +0x35 github.com/ncw/rclone/fs/fshttp.(*Transport).RoundTrip(0xc0001389c0, 0xc0001be500, 0xc0001be500, 0x0, 0xc000e772e0) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:282 +0xff github.com/ncw/rclone/vendor/golang.org/x/oauth2.(*Transport).RoundTrip(0xc000139b00, 0xc0001be300, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/golang.org/x/oauth2/transport.go:56 +0xfb net/http.send(0xc0001be300, 0x11df2e0, 0xc000139b00, 0x0, 0x0, 0x0, 0xc000e7c0b0, 0xc000e77478, 0xc000e77468, 0x1) /usr/local/go/src/net/http/client.go:250 +0x14b net/http.(*Client).send(0xc000139b60, 0xc0001be300, 0x0, 0x0, 0x0, 0xc000e7c0b0, 0x0, 0x1, 0x40d97f) /usr/local/go/src/net/http/client.go:174 +0xfa net/http.(*Client).do(0xc000139b60, 0xc0001be300, 0x0, 0x0, 0x0) /usr/local/go/src/net/http/client.go:641 +0x2a8 net/http.(*Client).Do(0xc000139b60, 0xc0001be300, 0x1088e13, 0xf, 0x1a4b720) /usr/local/go/src/net/http/client.go:509 +0x35 github.com/ncw/rclone/vendor/google.golang.org/api/gensupport.SendRequest(0x0, 0x0, 0xc000139b60, 0xc0001be300, 0x0, 0x0, 0xc0001be300) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/gensupport/send.go:43 +0x24b github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesListCall).doRequest(0xc000077a40, 0x107e151, 0x4, 0x0, 0x6b, 0xc0004e0230) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:5244 +0x428 github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3.(*FilesListCall).Do(0xc000077a40, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/vendor/google.golang.org/api/drive/v3/drive-gen.go:5256 +0x85 github.com/ncw/rclone/backend/drive.(*Fs).list.func1(0xc000136d20, 0x40d97f, 0xc0001f6a80) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:588 +0x98 github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000136d20, 0xc0001f6a80, 0xa, 0xfcea40, 0xc0004e0201) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:383 +0x16a github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000136d20, 0xc0001f6a80, 0xc0005efbe0, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc00136ed80, 0xc0005efcc8, 0x1, 0x1, 0x0, 0x0, 0x0, 0xc000e77cd8, 0x0, 0x1, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).List(0xc00136ed80, 0x0, 0x0, 0x0, 0x0, 0x0, 0xc000078b88, 0xc0004dbe8c) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1281 +0x18f github.com/ncw/rclone/backend/crypt.(*Fs).List(0xc000283ae0, 0x0, 0x0, 0xc00047ce08, 0xc00047ceb8, 0xc0004dbf20, 0xc0003f2480, 0xc00003c000) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:286 +0x7c github.com/ncw/rclone/fs/list.DirSorted(0x11f20c0, 0xc000283ae0, 0x1, 0x0, 0x0, 0x3, 0xc0004dbea0, 0x4072c0, 0xc00044dc80, 0x3) /home/rclone/go/src/github.com/ncw/rclone/fs/list/list.go:23 +0x63 github.com/ncw/rclone/fs/walk.walk.func2(0xc0001573a0, 0xc0004d67e0, 0x10cfd68, 0x11f20c0, 0xc000283ae0, 0x11e8501, 0xc0001572d8, 0xc00039c7a0, 0xc0001573b0, 0xc0001f6a20, ...) /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:128 +0x1b7 created by github.com/ncw/rclone/fs/walk.walk /home/rclone/go/src/github.com/ncw/rclone/fs/walk/walk.go:120 +0x252 2018/10/24 06:49:28 ------------------------------------------------------------ 2018/10/24 06:49:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Finished ERROR in 30m19.735128848s (try 3/5): exit status 2: Failed [] 2018/10/24 06:49:28 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Starting (try 4/5) 2018/10/24 06:49:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Expecting errors but got none: [] 2018/10/24 06:49:48 ------------------------------------------------------------ 2018/10/24 06:49:48 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" ---- 2018/10/24 06:49:48 2018/10/24 06:19:49 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 06:23:50 ERROR : : error listing: directory not found 2018/10/24 06:27:49 NOTICE: empty space: Not copying as --dry-run 2018/10/24 06:30:48 NOTICE: potato2: Not copying as --dry-run 2018/10/24 06:30:48 NOTICE: potato: Not deleting as --dry-run 2018/10/24 06:34:44 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-lequfod2jazipih9riwujud8': not deleting files as there were IO errors 2018/10/24 06:34:44 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-lequfod2jazipih9riwujud8': not deleting directories as there were IO errors 2018/10/24 06:38:30 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-lequfod2jazipih9riwujud8': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:38:47 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-lequfod2jazipih9riwujud8': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 06:42:48 purge failed: directory not found 2018/10/24 06:45:22 purge failed: directory not found panic: test timed out after 30m0s goroutine 5224 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive, 1 minutes]: testing.(*T).Run(0xc00058d000, 0x10982f4, 0x1b, 0x10cff50, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc00031e400) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc00031e400, 0xc00053bdc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0003e4a40, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc000156b80, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc000156b80) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc000156b80) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4331 [IO wait]: internal/poll.runtime_pollWait(0x7f2b2e83ebc0, 0x72, 0xc0013b9760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc000587518, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc000587518, 0xc00052e400, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc000587500, 0xc00052e400, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc000587500, 0xc00052e400, 0x400, 0x400, 0x1, 0x3, 0xc0013b9890) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc00000e278, 0xc00052e400, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc00052e400, 0x400, 0x400, 0x8, 0x8, 0xc00020c2c0) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc000391420, 0xc0013b9950, 0xc00052e400, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc000391420, 0xc00052e400, 0x400, 0x400, 0xfea4c0, 0x0, 0x7f2b2e7ab800) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0013926c0, 0x7f2b2e7ab800, 0xc000391420, 0x5, 0xc000391420, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc0001d2000, 0x10d1417, 0xc0001d2120, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc0001d2000, 0xc000e3d000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000e6120, 0xc000e3d000, 0x1000, 0x1000, 0xc0013b9c70, 0x406865, 0xc00019f2c0) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000566a80) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000566a80, 0x1, 0x0, 0x0, 0x1, 0xc00045a180, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000e6120) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4332 [select]: net/http.(*persistConn).writeLoop(0xc0000e6120) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 5223 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc000031440) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000031440, 0xc00014c7e0, 0xa, 0xfcea40, 0xc0003ca101) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000031440, 0xc00014c7e0, 0xc0005ecc18, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc000447380, 0xc0005ecd00, 0x1, 0x1, 0xc00042c9e0, 0x1a, 0xc000000100, 0xc0005ecd10, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).getRemoteInfoWithExport(0xc000447380, 0xc00042c9e0, 0x1a, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:2265 +0x2ad github.com/ncw/rclone/backend/drive.(*Fs).NewObject(0xc000447380, 0xc00042c9e0, 0x1a, 0xc00042c9e0, 0x1a, 0x2, 0x2) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1067 +0x4f github.com/ncw/rclone/backend/crypt.(*Fs).NewObject(0xc0000c5cc0, 0xc00038a1e0, 0x9, 0x107db69, 0x4, 0xc00038a1e0, 0x9) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:321 +0x7c github.com/ncw/rclone/fs/operations.DeleteFileWithBackupDir(0x11f2140, 0xc0003902c0, 0x11f20c0, 0xc0000c5cc0, 0xc00139df00, 0x11ddd20) /home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.go:441 +0x520 github.com/ncw/rclone/fs/operations.DeleteFilesWithBackupDir.func1(0xc00038a0b0, 0xc000030660, 0x11f20c0, 0xc0000c5cc0, 0xc00038a08c, 0xc00038a0c0) /home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.go:480 +0xb6 created by github.com/ncw/rclone/fs/operations.DeleteFilesWithBackupDir /home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.go:477 +0xe9 goroutine 5094 [semacquire]: sync.runtime_Semacquire(0xc00038a0b8) /usr/local/go/src/runtime/sema.go:56 +0x39 sync.(*WaitGroup).Wait(0xc00038a0b0) /usr/local/go/src/sync/waitgroup.go:130 +0x64 github.com/ncw/rclone/fs/operations.DeleteFilesWithBackupDir(0xc000030660, 0x11f20c0, 0xc0000c5cc0, 0xc0004f5b00, 0xc000030660) /home/rclone/go/src/github.com/ncw/rclone/fs/operations/operations.go:493 +0x144 github.com/ncw/rclone/fs/sync.(*syncCopyMove).deleteFiles(0xc0001101c0, 0x0, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:416 +0xd6 github.com/ncw/rclone/fs/sync.(*syncCopyMove).run(0xc0001101c0, 0xc00016dcc0, 0x11f2940) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:679 +0x520 github.com/ncw/rclone/fs/sync.runSyncCopyMove(0x11f20c0, 0xc00016dcc0, 0x11f2940, 0xc000146b00, 0xc000000003, 0x10d1468, 0xc0001600c0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:860 +0x142 github.com/ncw/rclone/fs/sync.Sync(0x11f20c0, 0xc00016dcc0, 0x11f2940, 0xc000146b00, 0x0, 0x0) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.go:865 +0x5f github.com/ncw/rclone/fs/sync.testSyncBackupDir(0xc00058d000, 0x107db69, 0x4) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1113 +0x7cd github.com/ncw/rclone/fs/sync.TestSyncBackupDirWithSuffix(0xc00058d000) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1147 +0x40 testing.tRunner(0xc00058d000, 0x10cff50) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 5214 [sleep]: time.Sleep(0x1f3af10e3) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000031440, 0x1f3af10e3) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c goroutine 5189 [chan receive]: github.com/ncw/rclone/fs/march.(*March).Run.func2(0xc000146c00, 0xc000167440, 0xc0001525a0) /home/rclone/go/src/github.com/ncw/rclone/fs/march/march.go:174 +0x4e created by github.com/ncw/rclone/fs/march.(*March).Run /home/rclone/go/src/github.com/ncw/rclone/fs/march/march.go:172 +0x240 2018/10/24 06:49:48 ------------------------------------------------------------ 2018/10/24 06:49:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Finished ERROR in 30m19.781265652s (try 3/5): exit status 2: Failed [] 2018/10/24 06:49:48 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Starting (try 4/5) 2018/10/24 07:19:10 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive: -fast-list" - Finished OK in 29m22.342650269s (try 4/5) 2018/10/24 07:19:47 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Expecting errors but got none: [] 2018/10/24 07:19:47 ------------------------------------------------------------ 2018/10/24 07:19:47 ---- "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" ---- 2018/10/24 07:19:47 2018/10/24 06:49:48 NOTICE: sub dir/hello world: Not copying as --dry-run 2018/10/24 06:52:50 ERROR : : error listing: directory not found 2018/10/24 06:57:21 NOTICE: empty space: Not copying as --dry-run 2018/10/24 06:59:39 NOTICE: potato2: Not copying as --dry-run 2018/10/24 06:59:39 NOTICE: potato: Not deleting as --dry-run 2018/10/24 07:02:28 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-xacaqeq7wafurus5resiqoq0': not deleting files as there were IO errors 2018/10/24 07:02:28 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-xacaqeq7wafurus5resiqoq0': not deleting directories as there were IO errors 2018/10/24 07:07:10 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-xacaqeq7wafurus5resiqoq0': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 07:07:29 ERROR : Encrypted drive 'TestCryptDrive:rclone-test-xacaqeq7wafurus5resiqoq0': Ignoring --track-renames as the source and destination do not have a common hash 2018/10/24 07:11:09 ERROR : : error listing: directory not found 2018/10/24 07:11:09 purge failed: directory not found 2018/10/24 07:14:46 ERROR : : error listing: directory not found 2018/10/24 07:14:47 ERROR : : error listing: directory not found 2018/10/24 07:14:47 purge failed: directory not found panic: test timed out after 30m0s goroutine 4824 [running]: testing.(*M).startAlarm.func1() /usr/local/go/src/testing/testing.go:1296 +0xfd created by time.goFunc /usr/local/go/src/time/sleep.go:172 +0x44 goroutine 1 [chan receive]: testing.(*T).Run(0xc000190700, 0x1089386, 0xf, 0x10d0000, 0x48f601) /usr/local/go/src/testing/testing.go:879 +0x37a testing.runTests.func1(0xc000190100) /usr/local/go/src/testing/testing.go:1119 +0x78 testing.tRunner(0xc000190100, 0xc00032bdc8) /usr/local/go/src/testing/testing.go:827 +0xbf testing.runTests(0xc0013f44c0, 0x1a23e80, 0x2d, 0x2d, 0x14) /usr/local/go/src/testing/testing.go:1117 +0x2aa testing.(*M).Run(0xc00015eb80, 0x0) /usr/local/go/src/testing/testing.go:1034 +0x165 github.com/ncw/rclone/fstest.TestMain(0xc00015eb80) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:65 +0x40 github.com/ncw/rclone/fs/sync.TestMain(0xc00015eb80) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:31 +0x2b main.main() _testmain.go:128 +0x13d goroutine 5 [syscall, 30 minutes]: os/signal.signal_recv(0x0) /usr/local/go/src/runtime/sigqueue.go:139 +0x9c os/signal.loop() /usr/local/go/src/os/signal/signal_unix.go:23 +0x22 created by os/signal.init.0 /usr/local/go/src/os/signal/signal_unix.go:29 +0x41 goroutine 4751 [chan receive]: github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall(0xc000335c20) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:212 +0x3c github.com/ncw/rclone/lib/pacer.(*Pacer).call(0xc000335c20, 0xc0002f3d40, 0xa, 0xfcea40, 0xc000158301) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:382 +0x15d github.com/ncw/rclone/lib/pacer.(*Pacer).Call(0xc000335c20, 0xc0002f3d40, 0xc000329688, 0x1) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:406 +0x66 github.com/ncw/rclone/backend/drive.(*Fs).list(0xc00012a900, 0xc000329770, 0x1, 0x1, 0xc0003fd5e0, 0x1a, 0xc000000100, 0xc000329780, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:587 +0x840 github.com/ncw/rclone/backend/drive.(*Fs).getRemoteInfoWithExport(0xc00012a900, 0xc0003fd5e0, 0x1a, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:2265 +0x2ad github.com/ncw/rclone/backend/drive.(*Fs).NewObject(0xc00012a900, 0xc0003fd5e0, 0x1a, 0x40a7eb, 0xc000329920, 0x40d97f, 0xc0003cc660) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1067 +0x4f github.com/ncw/rclone/backend/drive.(*Fs).Put(0xc00012a900, 0x11df4e0, 0xc0003cc640, 0x11eec80, 0xc0003cc660, 0x0, 0x0, 0x0, 0x20, 0xfb8900, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/drive/drive.go:1517 +0x5a github.com/ncw/rclone/fs.Fs.Put-fm(0x11df4e0, 0xc0003cc640, 0x11eec80, 0xc0003cc660, 0x0, 0x0, 0x0, 0xc0003fd401, 0xc000414680, 0xc000329ac0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:231 +0x88 github.com/ncw/rclone/backend/crypt.(*Fs).put(0xc0004181e0, 0x11ddd20, 0xc0001b8310, 0x11eee00, 0xc00001c2d0, 0x0, 0x0, 0x0, 0xc000329b08, 0xfb6ec0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:357 +0x194 github.com/ncw/rclone/backend/crypt.(*Fs).Put(0xc0004181e0, 0x11ddd20, 0xc0001b8310, 0x11eee00, 0xc00001c2d0, 0x0, 0x0, 0x0, 0x70, 0xfb6ec0, ...) /home/rclone/go/src/github.com/ncw/rclone/backend/crypt/crypt.go:389 +0xd5 github.com/ncw/rclone/fs.Fs.Put-fm(0x11ddd20, 0xc0001b8310, 0x11eee00, 0xc00001c2d0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:231 +0x88 github.com/ncw/rclone/fstest.(*Run).WriteObjectTo(0xc000078c60, 0x11f20c0, 0xc0004181e0, 0x10821db, 0x8, 0x108cdb3, 0x12, 0x75bcd15, 0xec689154f, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:243 +0x24c github.com/ncw/rclone/fstest.(*Run).WriteObject(0xc000078c60, 0x10821db, 0x8, 0x108cdb3, 0x12, 0x75bcd15, 0xec689154f, 0x0, 0x0, 0x0, ...) /home/rclone/go/src/github.com/ncw/rclone/fstest/run.go:260 +0xe1 github.com/ncw/rclone/fs/sync.TestSyncUTFNorm(0xc000190700) /home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync_test.go:1167 +0x421 testing.tRunner(0xc000190700, 0x10d0000) /usr/local/go/src/testing/testing.go:827 +0xbf created by testing.(*T).Run /usr/local/go/src/testing/testing.go:878 +0x353 goroutine 4437 [IO wait]: internal/poll.runtime_pollWait(0x7f0261c69e30, 0x72, 0xc000296760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc00015f918, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc00015f918, 0xc000371000, 0x1000, 0x1000) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc00015f900, 0xc000371000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc00015f900, 0xc000371000, 0x1000, 0x1000, 0xc00014a020, 0xc0001da090, 0xc000053880) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc00014c078, 0xc000371000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc000371000, 0x1000, 0x1000, 0x8, 0x8, 0xc000076040) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc0003cc440, 0xc000296950, 0xc000371000, 0x1000, 0x1000, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc0003cc440, 0xc000371000, 0x1000, 0x1000, 0xfea4c0, 0x0, 0x7f0261bd5000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc000585e60, 0x7f0261bd5000, 0xc0003cc440, 0x5, 0xc0003cc440, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc00007a700, 0x10d1417, 0xc00007a820, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc00007a700, 0xc000e3c000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc0000e65a0, 0xc000e3c000, 0x1000, 0x1000, 0xc000296c70, 0x406865, 0xc0001dd140) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc0013af0e0) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc0013af0e0, 0x1, 0x0, 0x0, 0x1, 0xc0001dc120, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc0000e65a0) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 goroutine 4665 [select]: net/http.(*persistConn).writeLoop(0xc000354360) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4438 [select]: net/http.(*persistConn).writeLoop(0xc0000e65a0) /usr/local/go/src/net/http/transport.go:1885 +0x113 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1339 +0x966 goroutine 4809 [sleep]: time.Sleep(0x1fe558102) /usr/local/go/src/runtime/time.go:105 +0x14f github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall.func1(0xc000335c20, 0x1fe558102) /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:221 +0x2b created by github.com/ncw/rclone/lib/pacer.(*Pacer).beginCall /home/rclone/go/src/github.com/ncw/rclone/lib/pacer/pacer.go:219 +0x7c goroutine 4664 [IO wait]: internal/poll.runtime_pollWait(0x7f0261c69d60, 0x72, 0xc00005f760) /usr/local/go/src/runtime/netpoll.go:173 +0x66 internal/poll.(*pollDesc).wait(0xc0004f4218, 0x72, 0xffffffffffffff00, 0x11e1900, 0x19cc780) /usr/local/go/src/internal/poll/fd_poll_runtime.go:85 +0x9a internal/poll.(*pollDesc).waitRead(0xc0004f4218, 0xc0003e9c00, 0x400, 0x400) /usr/local/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d internal/poll.(*FD).Read(0xc0004f4200, 0xc0003e9c00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/internal/poll/fd_unix.go:169 +0x179 net.(*netFD).Read(0xc0004f4200, 0xc0003e9c00, 0x400, 0x400, 0x40, 0xc0001dabd0, 0xc00005f8a8) /usr/local/go/src/net/fd_unix.go:202 +0x4f net.(*conn).Read(0xc000140018, 0xc0003e9c00, 0x400, 0x400, 0x0, 0x0, 0x0) /usr/local/go/src/net/net.go:177 +0x68 net.Conn.Read-fm(0xc0003e9c00, 0x400, 0x400, 0x8, 0x8, 0xc00052a880) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x4d github.com/ncw/rclone/fs/fshttp.(*timeoutConn).readOrWrite(0xc000310000, 0xc00005f950, 0xc0003e9c00, 0x400, 0x400, 0x40a7eb, 0xc00001e000, 0xf1f380) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:69 +0x48 github.com/ncw/rclone/fs/fshttp.(*timeoutConn).Read(0xc000310000, 0xc0003e9c00, 0x400, 0x400, 0xfea4c0, 0x0, 0x7f0261bd5000) /home/rclone/go/src/github.com/ncw/rclone/fs/fshttp/http.go:81 +0x8a crypto/tls.(*block).readFromUntil(0xc0002f32c0, 0x7f0261bd5000, 0xc000310000, 0x5, 0xc000310000, 0x44ca91) /usr/local/go/src/crypto/tls/conn.go:492 +0x89 crypto/tls.(*Conn).readRecord(0xc000128700, 0x10d1417, 0xc000128820, 0x45a5d0) /usr/local/go/src/crypto/tls/conn.go:593 +0xdd crypto/tls.(*Conn).Read(0xc000128700, 0xc000348000, 0x1000, 0x1000, 0x0, 0x0, 0x0) /usr/local/go/src/crypto/tls/conn.go:1145 +0xf1 net/http.(*persistConn).Read(0xc000354360, 0xc000348000, 0x1000, 0x1000, 0xc00005fc70, 0x406865, 0xc0001dc000) /usr/local/go/src/net/http/transport.go:1497 +0x75 bufio.(*Reader).fill(0xc000148660) /usr/local/go/src/bufio/bufio.go:100 +0x106 bufio.(*Reader).Peek(0xc000148660, 0x1, 0x0, 0x0, 0x1, 0xc0001dc540, 0x0) /usr/local/go/src/bufio/bufio.go:132 +0x3f net/http.(*persistConn).readLoop(0xc000354360) /usr/local/go/src/net/http/transport.go:1645 +0x1a2 created by net/http.(*Transport).dialConn /usr/local/go/src/net/http/transport.go:1338 +0x941 2018/10/24 07:19:47 ------------------------------------------------------------ 2018/10/24 07:19:47 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Finished ERROR in 30m18.669205571s (try 4/5): exit status 2: Failed [] 2018/10/24 07:19:47 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Starting (try 5/5) 2018/10/24 07:47:09 "/home/rclone/go/src/github.com/ncw/rclone/fs/sync/sync.test -test.timeout 30m0s -remote TestCryptDrive:" - Finished OK in 27m22.503630681s (try 5/5) 2018/10/24 07:47:09 SUMMARY 2018/10/24 07:47:09 PASS: All tests finished OK in 2h28m44.272077955s