"go test -v -timeout 1h0m0s -remote TestGooglePhotos: -verbose" - Starting (try 1/5)
=== RUN   TestNewAlbums
--- PASS: TestNewAlbums (0.00s)
=== RUN   TestAlbumsAdd
--- PASS: TestAlbumsAdd (0.00s)
=== RUN   TestAlbumsDel
--- PASS: TestAlbumsDel (0.00s)
=== RUN   TestAlbumsGet
--- PASS: TestAlbumsGet (0.00s)
=== RUN   TestAlbumsGetDirs
--- PASS: TestAlbumsGetDirs (0.00s)
=== RUN   TestIntegration
2025/08/16 01:21:51 DEBUG : Creating backend with remote "TestGooglePhotos:"
2025/08/16 01:21:51 DEBUG : Using config file from "/home/rclone/.rclone.conf"
2025/08/16 01:21:51 DEBUG : Creating backend with remote "testfiles"
=== RUN   TestIntegration/CreateAlbum
2025/08/16 01:21:51 DEBUG : TestGooglePhotos: Loaded invalid token from config file - ignoring
2025/08/16 01:21:52 DEBUG : Config file has changed externally - reloading
2025/08/16 01:21:52 DEBUG : Saving config "token" in section "TestGooglePhotos" of the config file
2025/08/16 01:21:52 DEBUG : TestGooglePhotos: Saved new token in config file
2025/08/16 01:22:05 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:22:05 DEBUG : pacer: Rate limited, increasing sleep to 1.945341935s
2025/08/16 01:22:20 DEBUG : pacer: low level retry 2/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:22:20 DEBUG : pacer: Rate limited, increasing sleep to 2.595777176s
2025/08/16 01:22:23 DEBUG : pacer: Reducing sleep to 0s
2025/08/16 01:23:19 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:23:19 DEBUG : pacer: Rate limited, increasing sleep to 1.588654824s
2025/08/16 01:23:23 DEBUG : pacer: Reducing sleep to 0s
=== RUN   TestIntegration/CreateAlbum/PutFile
2025/08/16 01:24:13 DEBUG : Google Photos path "": Adding "album/rclone-test-qitugun9boceceg3fuporut3/rclone-test-image1.jpg" to batch
2025/08/16 01:24:14 DEBUG : Google Photos path "": Batch idle for 1s so committing
2025/08/16 01:24:14 DEBUG : Google Photos path "": Committing sync batch length 1 starting with: album/rclone-test-qitugun9boceceg3fuporut3/rclone-test-image1.jpg
2025/08/16 01:24:15 DEBUG : Google Photos path "": Committed sync batch length 1 starting with: album/rclone-test-qitugun9boceceg3fuporut3/rclone-test-image1.jpg
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectFs
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectString
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectHash
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectSize
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectSetModTime
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectStorable
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectOpen
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckFileInAlbum
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckInByYear
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckInByMonth
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckInByDay
=== RUN   TestIntegration/CreateAlbum/PutFile/NewObject
=== RUN   TestIntegration/CreateAlbum/PutFile/NewObjectWithID
=== RUN   TestIntegration/CreateAlbum/PutFile/NewFsIsFile
2025/08/16 01:24:20 DEBUG : Creating backend with remote "TestGooglePhotos:album/rclone-test-qitugun9boceceg3fuporut3/rclone-test-image1.jpg"
2025/08/16 01:24:20 DEBUG : Config file has changed externally - reloading
2025/08/16 01:24:40 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:24:40 DEBUG : pacer: Rate limited, increasing sleep to 1.419340436s
2025/08/16 01:24:40 DEBUG : pacer: Reducing sleep to 0s
2025/08/16 01:24:56 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:24:56 DEBUG : pacer: Rate limited, increasing sleep to 1.343168015s
2025/08/16 01:25:06 DEBUG : pacer: low level retry 2/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:25:06 DEBUG : pacer: Rate limited, increasing sleep to 2.28679969s
2025/08/16 01:25:07 DEBUG : pacer: Reducing sleep to 0s
2025/08/16 01:25:33 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:25:33 DEBUG : pacer: Rate limited, increasing sleep to 1.761101615s
2025/08/16 01:25:35 DEBUG : pacer: Reducing sleep to 0s
2025/08/16 01:25:50 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:25:50 DEBUG : pacer: Rate limited, increasing sleep to 1.031740042s
2025/08/16 01:25:51 DEBUG : pacer: Reducing sleep to 0s
2025/08/16 01:27:49 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:27:49 DEBUG : pacer: Rate limited, increasing sleep to 1.020417316s
2025/08/16 01:27:49 DEBUG : pacer: low level retry 2/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:27:49 DEBUG : pacer: Rate limited, increasing sleep to 2.939690566s
2025/08/16 01:27:59 DEBUG : pacer: low level retry 3/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:27:59 DEBUG : pacer: Rate limited, increasing sleep to 4.539619037s
2025/08/16 01:28:04 DEBUG : pacer: Reducing sleep to 0s
2025/08/16 01:28:08 DEBUG : pacer: low level retry 1/10 (error Internal error encountered. (500 INTERNAL))
2025/08/16 01:28:08 DEBUG : pacer: Rate limited, increasing sleep to 1.588867245s
2025/08/16 01:28:09 DEBUG : pacer: Reducing sleep to 0s
=== RUN   TestIntegration/CreateAlbum/PutFile/RemoveFileFromAlbum
=== RUN   TestIntegration/UploadMkdir
=== RUN   TestIntegration/UploadMkdir/List
=== RUN   TestIntegration/UploadMkdir/Rmdir
=== RUN   TestIntegration/UploadMkdir/ListEmpty
=== RUN   TestIntegration/Upload
2025/08/16 01:28:24 DEBUG : pacer: low level retry 1/1 (error {
  "code": 8,
  "message": "Quota exceeded for quota metric \u0027Size of uploads\u0027 and limit \u0027Size of uploads per day\u0027 of service \u0027photoslibrary.googleapis.com\u0027 for consumer \u0027project_number:202264815644\u0027."
} (429 429 Too Many Requests))
2025/08/16 01:28:24 DEBUG : pacer: Rate limited, increasing sleep to 1.225754643s
    googlephotos_test.go:225: 
        	Error Trace:	/home/rclone/go/src/github.com/rclone/rclone/backend/googlephotos/googlephotos_test.go:225
        	Error:      	Received unexpected error:
        	            	couldn't upload file: {
        	            	  "code": 8,
        	            	  "message": "Quota exceeded for quota metric \u0027Size of uploads\u0027 and limit \u0027Size of uploads per day\u0027 of service \u0027photoslibrary.googleapis.com\u0027 for consumer \u0027project_number:202264815644\u0027."
        	            	} (429 429 Too Many Requests)
        	Test:       	TestIntegration/Upload
=== RUN   TestIntegration/Name
=== RUN   TestIntegration/Root
=== RUN   TestIntegration/String
=== RUN   TestIntegration/Features
=== RUN   TestIntegration/Precision
=== RUN   TestIntegration/Hashes
--- FAIL: TestIntegration (392.67s)
    --- PASS: TestIntegration/CreateAlbum (392.09s)
        --- PASS: TestIntegration/CreateAlbum/PutFile (251.59s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectFs (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectString (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectHash (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectSize (1.55s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectSetModTime (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectStorable (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectOpen (0.25s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckFileInAlbum (0.48s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckInByYear (0.38s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckInByMonth (0.48s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckInByDay (0.37s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/NewObject (0.44s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/NewObjectWithID (0.49s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/NewFsIsFile (239.15s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/RemoveFileFromAlbum (4.67s)
    --- PASS: TestIntegration/UploadMkdir (0.00s)
        --- PASS: TestIntegration/UploadMkdir/List (0.00s)
        --- PASS: TestIntegration/UploadMkdir/Rmdir (0.00s)
        --- PASS: TestIntegration/UploadMkdir/ListEmpty (0.00s)
    --- FAIL: TestIntegration/Upload (0.57s)
    --- PASS: TestIntegration/Name (0.00s)
    --- PASS: TestIntegration/Root (0.00s)
    --- PASS: TestIntegration/String (0.00s)
    --- PASS: TestIntegration/Features (0.00s)
    --- PASS: TestIntegration/Precision (0.00s)
    --- PASS: TestIntegration/Hashes (0.00s)
=== RUN   TestAddID
--- PASS: TestAddID (0.00s)
=== RUN   TestFileAddID
--- PASS: TestFileAddID (0.00s)
=== RUN   TestFindID
--- PASS: TestFindID (0.00s)
=== RUN   TestPatternMatch
=== RUN   TestPatternMatch/#0,root="",itemPath="",isFile=false
=== RUN   TestPatternMatch/#1,root="",itemPath="",isFile=true
=== RUN   TestPatternMatch/#2,root="upload",itemPath="",isFile=false
=== RUN   TestPatternMatch/#3,root="upload/dir",itemPath="",isFile=false
=== RUN   TestPatternMatch/#4,root="upload/file.jpg",itemPath="",isFile=true
=== RUN   TestPatternMatch/#5,root="media",itemPath="",isFile=false
=== RUN   TestPatternMatch/#6,root="",itemPath="media",isFile=false
=== RUN   TestPatternMatch/#7,root="media/all",itemPath="",isFile=false
=== RUN   TestPatternMatch/#8,root="media",itemPath="all",isFile=false
=== RUN   TestPatternMatch/#9,root="media/all",itemPath="file.jpg",isFile=true
=== RUN   TestPatternMatch/#10,root="",itemPath="feature",isFile=false
=== RUN   TestPatternMatch/#11,root="feature/favorites",itemPath="",isFile=false
=== RUN   TestPatternMatch/#12,root="feature",itemPath="favorites",isFile=false
=== RUN   TestPatternMatch/#13,root="feature/favorites",itemPath="file.jpg",isFile=true
--- PASS: TestPatternMatch (0.00s)
    --- PASS: TestPatternMatch/#0,root="",itemPath="",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#1,root="",itemPath="",isFile=true (0.00s)
    --- PASS: TestPatternMatch/#2,root="upload",itemPath="",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#3,root="upload/dir",itemPath="",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#4,root="upload/file.jpg",itemPath="",isFile=true (0.00s)
    --- PASS: TestPatternMatch/#5,root="media",itemPath="",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#6,root="",itemPath="media",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#7,root="media/all",itemPath="",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#8,root="media",itemPath="all",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#9,root="media/all",itemPath="file.jpg",isFile=true (0.00s)
    --- PASS: TestPatternMatch/#10,root="",itemPath="feature",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#11,root="feature/favorites",itemPath="",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#12,root="feature",itemPath="favorites",isFile=false (0.00s)
    --- PASS: TestPatternMatch/#13,root="feature/favorites",itemPath="file.jpg",isFile=true (0.00s)
=== RUN   TestPatternMatchToEntries
=== RUN   TestPatternMatchToEntries/#0,root="",itemPath=""
=== RUN   TestPatternMatchToEntries/#1,root="upload",itemPath=""
=== RUN   TestPatternMatchToEntries/#2,root="upload",itemPath="dir"
=== RUN   TestPatternMatchToEntries/#3,root="media",itemPath=""
=== RUN   TestPatternMatchToEntries/#4,root="media/all",itemPath=""
=== RUN   TestPatternMatchToEntries/#5,root="media",itemPath="all"
=== RUN   TestPatternMatchToEntries/#6,root="media/by-year",itemPath=""
=== RUN   TestPatternMatchToEntries/#7,root="media/by-year/2000",itemPath=""
=== RUN   TestPatternMatchToEntries/#8,root="media/by-month",itemPath=""
=== RUN   TestPatternMatchToEntries/#9,root="media/by-month/2001",itemPath=""
=== RUN   TestPatternMatchToEntries/#10,root="media/by-month/2001/2001-01",itemPath=""
=== RUN   TestPatternMatchToEntries/#11,root="media/by-day",itemPath=""
=== RUN   TestPatternMatchToEntries/#12,root="media/by-day/2001",itemPath=""
=== RUN   TestPatternMatchToEntries/#13,root="media/by-day/2001/2001-01-02",itemPath=""
=== RUN   TestPatternMatchToEntries/#14,root="album",itemPath=""
2025/08/16 01:28:24 DEBUG : &{t:0xc0002d8fc0 albums:0xc0004a0bd0 names:[file.jpg] uploaded:map[:[0xc001a663f0] upload:[upload/file1.jpg 0xc001a66460] upload/dir:[upload/dir/file2.jpg]]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
=== RUN   TestPatternMatchToEntries/#15,root="album/sub",itemPath=""
2025/08/16 01:28:24 DEBUG : &{t:0xc0002d8fc0 albums:0xc0004a0bd0 names:[file.jpg] uploaded:map[:[0xc001a663f0] upload:[upload/file1.jpg 0xc001a66460] upload/dir:[upload/dir/file2.jpg]]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
=== RUN   TestPatternMatchToEntries/#16,root="album/sub/one",itemPath=""
=== RUN   TestPatternMatchToEntries/#17,root="shared-album",itemPath=""
2025/08/16 01:28:24 DEBUG : &{t:0xc0002d8fc0 albums:0xc0004a0bd0 names:[file.jpg] uploaded:map[:[0xc001a663f0] upload:[upload/file1.jpg 0xc001a66460] upload/dir:[upload/dir/file2.jpg]]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
=== RUN   TestPatternMatchToEntries/#18,root="shared-album/sub",itemPath=""
2025/08/16 01:28:24 DEBUG : &{t:0xc0002d8fc0 albums:0xc0004a0bd0 names:[file.jpg] uploaded:map[:[0xc001a663f0] upload:[upload/file1.jpg 0xc001a66460] upload/dir:[upload/dir/file2.jpg]]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
=== RUN   TestPatternMatchToEntries/#19,root="shared-album/sub/one",itemPath=""
--- PASS: TestPatternMatchToEntries (0.00s)
    --- PASS: TestPatternMatchToEntries/#0,root="",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#1,root="upload",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#2,root="upload",itemPath="dir" (0.00s)
    --- PASS: TestPatternMatchToEntries/#3,root="media",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#4,root="media/all",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#5,root="media",itemPath="all" (0.00s)
    --- PASS: TestPatternMatchToEntries/#6,root="media/by-year",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#7,root="media/by-year/2000",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#8,root="media/by-month",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#9,root="media/by-month/2001",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#10,root="media/by-month/2001/2001-01",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#11,root="media/by-day",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#12,root="media/by-day/2001",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#13,root="media/by-day/2001/2001-01-02",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#14,root="album",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#15,root="album/sub",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#16,root="album/sub/one",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#17,root="shared-album",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#18,root="shared-album/sub",itemPath="" (0.00s)
    --- PASS: TestPatternMatchToEntries/#19,root="shared-album/sub/one",itemPath="" (0.00s)
=== RUN   TestPatternYears
--- PASS: TestPatternYears (0.00s)
=== RUN   TestPatternMonths
--- PASS: TestPatternMonths (0.00s)
=== RUN   TestPatternDays
--- PASS: TestPatternDays (0.00s)
=== RUN   TestPatternYearMonthDayFilter
--- PASS: TestPatternYearMonthDayFilter (0.00s)
=== RUN   TestPatternAlbumsToEntries
2025/08/16 01:28:24 DEBUG : &{t:0xc000594fc0 albums:0xc00020d2f0 names:[] uploaded:map[]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
2025/08/16 01:28:24 DEBUG : &{t:0xc000594fc0 albums:0xc00020d2f0 names:[file.jpg] uploaded:map[]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
--- PASS: TestPatternAlbumsToEntries (0.00s)
FAIL
exit status 1
FAIL	github.com/rclone/rclone/backend/googlephotos	392.699s
"go test -v -timeout 1h0m0s -remote TestGooglePhotos: -verbose" - Finished ERROR in 6m34.22966771s (try 1/5): exit status 1: Failed [TestIntegration/Upload]