"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
2021/02/08 05:11:25 DEBUG : Creating backend with remote "TestGooglePhotos:"
2021/02/08 05:11:25 DEBUG : Creating backend with remote "testfiles"
=== RUN   TestIntegration/CreateAlbum
2021/02/08 05:11:25 DEBUG : Google Photos path "": Mkdir: dir="album/rclone-test-letofam2zosagop3hozimum9"
2021/02/08 05:11:25 DEBUG : TestGooglePhotos: Loaded invalid token from config file - ignoring
2021/02/08 05:11:26 DEBUG : TestGooglePhotos: Saved new token in config file
2021/02/08 05:11:49 DEBUG : Google Photos path "": >Mkdir: err=<nil>
=== RUN   TestIntegration/CreateAlbum/PutFile
2021/02/08 05:11:49 DEBUG : Google Photos path "": Put: src=rclone-test-image1.jpg
2021/02/08 05:11:49 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: Update: src=rclone-test-image1.jpg
2021/02/08 05:11:52 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: >Update: err=<nil>
2021/02/08 05:11:52 DEBUG : Google Photos path "": >Put: 
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectFs
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectString
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectHash
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectSize
2021/02/08 05:11:52 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: Size: 
2021/02/08 05:11:52 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: >Size: 
2021/02/08 05:11:52 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: Size: 
2021/02/08 05:11:52 DEBUG : Google Photos path "": List: dir="album/rclone-test-letofam2zosagop3hozimum9"
2021/02/08 05:11:53 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:11:53 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: >Size: 
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectSetModTime
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectStorable
=== RUN   TestIntegration/CreateAlbum/PutFile/ObjectOpen
2021/02/08 05:11:53 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: Open: 
2021/02/08 05:11:53 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: >Open: 
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckFileInAlbum
2021/02/08 05:11:53 DEBUG : Google Photos path "": List: dir="album/rclone-test-letofam2zosagop3hozimum9"
2021/02/08 05:11:54 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:11:54 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: ModTime: 
2021/02/08 05:11:54 DEBUG : album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg: >ModTime: 
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckInByYear
2021/02/08 05:11:54 DEBUG : Google Photos path "": List: dir="media/by-year/2013"
2021/02/08 05:11:55 DEBUG : Google Photos path "": >List: err=<nil>
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckInByMonth
2021/02/08 05:11:55 DEBUG : Google Photos path "": List: dir="media/by-month/2013/2013-07"
2021/02/08 05:11:56 DEBUG : Google Photos path "": >List: err=<nil>
=== RUN   TestIntegration/CreateAlbum/PutFile/CheckInByDay
2021/02/08 05:11:56 DEBUG : Google Photos path "": List: dir="media/by-day/2013/2013-07-26"
2021/02/08 05:11:56 DEBUG : Google Photos path "": >List: err=<nil>
=== RUN   TestIntegration/CreateAlbum/PutFile/NewObject
2021/02/08 05:11:56 DEBUG : Google Photos path "": NewObject: remote="album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg"
2021/02/08 05:11:56 DEBUG : Google Photos path "": List: dir="album/rclone-test-letofam2zosagop3hozimum9"
2021/02/08 05:11:57 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:11:57 DEBUG : Google Photos path "": >NewObject: 
=== RUN   TestIntegration/CreateAlbum/PutFile/NewObjectWithID
2021/02/08 05:11:57 DEBUG : Google Photos path "": NewObject: remote="album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1 {ALOKJiij1W7NqR8m4fVOFogqyQjeUEMc14c2GWrK6P8tHiQttTrTvBblBfG2LUsKRCml_8syA24ZupQSng98A1G0agXejH5DSw}.jpg"
2021/02/08 05:11:58 DEBUG : Google Photos path "": >NewObject: 
=== RUN   TestIntegration/CreateAlbum/PutFile/NewFsIsFile
2021/02/08 05:11:58 DEBUG : Creating backend with remote "TestGooglePhotos:album/rclone-test-letofam2zosagop3hozimum9/rclone-test-image1.jpg"
2021/02/08 05:11:58 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": NewObject: remote="rclone-test-image1.jpg"
2021/02/08 05:11:58 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": List: dir=""
2021/02/08 05:12:18 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": >List: err=<nil>
2021/02/08 05:12:18 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": >NewObject: 
2021/02/08 05:12:18 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": NewObject: remote="rclone-test-image1.jpg"
2021/02/08 05:12:18 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": List: dir=""
2021/02/08 05:12:19 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": >List: err=<nil>
2021/02/08 05:12:19 DEBUG : Google Photos path "album/rclone-test-letofam2zosagop3hozimum9": >NewObject: 
=== RUN   TestIntegration/CreateAlbum/PutFile/RemoveFileFromAlbum
2021/02/08 05:12:22 DEBUG : Google Photos path "": List: dir="album/rclone-test-letofam2zosagop3hozimum9"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:12:23 DEBUG : Google Photos path "": Rmdir: dir=%!q(MISSING)
2021/02/08 05:12:23 DEBUG : Google Photos path "": >Rmdir: err=google photos API does not implement deleting albums
=== RUN   TestIntegration/UploadMkdir
2021/02/08 05:12:23 DEBUG : Google Photos path "": Mkdir: dir="upload/dir"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >Mkdir: err=<nil>
2021/02/08 05:12:23 DEBUG : Google Photos path "": Mkdir: dir="upload/dir/subdir"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >Mkdir: err=<nil>
=== RUN   TestIntegration/UploadMkdir/List
2021/02/08 05:12:23 DEBUG : Google Photos path "": List: dir="upload"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:12:23 DEBUG : Google Photos path "": List: dir="upload/dir"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >List: err=<nil>
=== RUN   TestIntegration/UploadMkdir/Rmdir
2021/02/08 05:12:23 DEBUG : Google Photos path "": Rmdir: dir=%!q(MISSING)
2021/02/08 05:12:23 DEBUG : Google Photos path "": >Rmdir: err=<nil>
2021/02/08 05:12:23 DEBUG : Google Photos path "": Rmdir: dir=%!q(MISSING)
2021/02/08 05:12:23 DEBUG : Google Photos path "": >Rmdir: err=<nil>
=== RUN   TestIntegration/UploadMkdir/ListEmpty
2021/02/08 05:12:23 DEBUG : Google Photos path "": List: dir="upload"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:12:23 DEBUG : Google Photos path "": List: dir="upload/dir"
2021/02/08 05:12:23 DEBUG : Google Photos path "": >List: err=directory not found
=== RUN   TestIntegration/Upload
2021/02/08 05:12:23 DEBUG : Google Photos path "": Put: src=rclone-test-image2.jpg
2021/02/08 05:12:23 DEBUG : upload/dir/subdir/rclone-test-image2.jpg: Update: src=rclone-test-image2.jpg
2021/02/08 05:12:24 DEBUG : upload/dir/subdir/rclone-test-image2.jpg: >Update: err=<nil>
2021/02/08 05:12:24 DEBUG : Google Photos path "": >Put: 
=== RUN   TestIntegration/Upload/List
2021/02/08 05:12:24 DEBUG : Google Photos path "": List: dir="upload/dir/subdir"
2021/02/08 05:12:24 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:12:24 DEBUG : upload/dir/subdir/rclone-test-image2.jpg: ModTime: 
2021/02/08 05:12:24 DEBUG : Google Photos path "": List: dir="upload/dir/subdir"
2021/02/08 05:12:24 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:12:24 DEBUG : upload/dir/subdir/rclone-test-image2.jpg: >ModTime: 
=== RUN   TestIntegration/Upload/NewObject
2021/02/08 05:12:24 DEBUG : Google Photos path "": NewObject: remote="upload/dir/subdir/rclone-test-image2.jpg"
2021/02/08 05:12:24 DEBUG : Google Photos path "": List: dir="upload/dir/subdir"
2021/02/08 05:12:24 DEBUG : Google Photos path "": >List: err=<nil>
2021/02/08 05:12:24 DEBUG : Google Photos path "": >NewObject: 
=== RUN   TestIntegration/Upload/NewObjectWithID
2021/02/08 05:12:24 DEBUG : Google Photos path "": NewObject: remote="upload/dir/subdir/rclone-test-image2 {ALOKJii7XWCj_vIu4ceOSuEIDk5JEZn8ypYEUqOZktpK248TRnFljSsuAXX25ExXLgpFqJhYkTRORq5zNgke4rdqS13zO1Kd7A}.jpg"
2021/02/08 05:12:25 DEBUG : Google Photos path "": >NewObject: 
=== RUN   TestIntegration/Name
=== RUN   TestIntegration/Root
=== RUN   TestIntegration/String
=== RUN   TestIntegration/Features
=== RUN   TestIntegration/Precision
=== RUN   TestIntegration/Hashes
--- PASS: TestIntegration (59.46s)
    --- PASS: TestIntegration/CreateAlbum (57.47s)
        --- PASS: TestIntegration/CreateAlbum/PutFile (33.65s)
            --- 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.16s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectSetModTime (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectStorable (0.00s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/ObjectOpen (0.01s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckFileInAlbum (1.15s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckInByYear (0.74s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckInByMonth (0.71s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/CheckInByDay (0.65s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/NewObject (0.98s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/NewObjectWithID (0.59s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/NewFsIsFile (21.49s)
            --- PASS: TestIntegration/CreateAlbum/PutFile/RemoveFileFromAlbum (3.73s)
    --- PASS: TestIntegration/UploadMkdir (0.00s)
        --- PASS: TestIntegration/UploadMkdir/List (0.00s)
        --- PASS: TestIntegration/UploadMkdir/Rmdir (0.00s)
        --- PASS: TestIntegration/UploadMkdir/ListEmpty (0.00s)
    --- PASS: TestIntegration/Upload (1.99s)
        --- PASS: TestIntegration/Upload/List (0.00s)
        --- PASS: TestIntegration/Upload/NewObject (0.00s)
        --- PASS: TestIntegration/Upload/NewObjectWithID (0.56s)
    --- 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=""
2021/02/08 05:12:25 DEBUG : &{0xc000875c80 0xc0001f07e0 [file.jpg] map[:[0xc000328050] upload:[upload/file1.jpg 0xc0003280a0] upload/dir:[upload/dir/file2.jpg]]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
=== RUN   TestPatternMatchToEntries/#15,root="album/sub",itemPath=""
2021/02/08 05:12:25 DEBUG : &{0xc000875c80 0xc0001f07e0 [file.jpg] map[:[0xc000328050] upload:[upload/file1.jpg 0xc0003280a0] 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=""
2021/02/08 05:12:25 DEBUG : &{0xc000875c80 0xc0001f07e0 [file.jpg] map[:[0xc000328050] upload:[upload/file1.jpg 0xc0003280a0] upload/dir:[upload/dir/file2.jpg]]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
=== RUN   TestPatternMatchToEntries/#18,root="shared-album/sub",itemPath=""
2021/02/08 05:12:25 DEBUG : &{0xc000875c80 0xc0001f07e0 [file.jpg] map[:[0xc000328050] upload:[upload/file1.jpg 0xc0003280a0] 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
2021/02/08 05:12:25 DEBUG : &{0xc000276300 0xc0001f83c0 [] map[]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
2021/02/08 05:12:25 DEBUG : &{0xc000276300 0xc0001f83c0 [file.jpg] map[]}: Error reading media count: strconv.ParseInt: parsing "": invalid syntax
--- PASS: TestPatternAlbumsToEntries (0.00s)
PASS
ok  	github.com/rclone/rclone/backend/googlephotos	59.474s
"go test -v -timeout 1h0m0s -remote TestGooglePhotos: -verbose" - Finished OK in 1m5.758077912s (try 1/5)