Rclone service doesn't start again

Fabio · July 12, 2023, 10:36am

After a month of operations, yesterday I restarted my Raspberry and since then rclone refused to start, exiting with error:

sudo service rcloneGmount status
● rcloneGmount.service - RClone GDrive mount
     Loaded: loaded (/etc/systemd/system/rcloneGmount.service; enabled; vendor preset: enabled)
     Active: activating (auto-restart) (Result: exit-code) since Wed 2023-07-12 12:33:57 CEST; 4s ago
    Process: 440 ExecStart=/usr/bin/rclone mount GDrive: /home/boss/GDrive --log-file=/var/log/rclone.log --l>
   Main PID: 440 (code=exited, status=2)
        CPU: 128ms

Jul 12 12:33:57 SaltyRest rclone[440]: goroutine 37 [GC worker (idle)]:
Jul 12 12:33:57 SaltyRest rclone[440]: runtime.gopark(0x1abe410, 0x30a6000, 0x1a, 0x14, 0x0)
Jul 12 12:33:57 SaltyRest rclone[440]:         runtime/proc.go:381 +0x100 fp=0x34ebf90 sp=0x34ebf7c pc=0x55fa8
Jul 12 12:33:57 SaltyRest rclone[440]: runtime.gcBgMarkWorker()
Jul 12 12:33:57 SaltyRest rclone[440]:         runtime/mgc.go:1275 +0x100 fp=0x34ebfec sp=0x34ebf90 pc=0x2f97c
Jul 12 12:33:57 SaltyRest rclone[440]: runtime.goexit()
Jul 12 12:33:57 SaltyRest rclone[440]:         runtime/asm_arm.s:831 +0x4 fp=0x34ebfec sp=0x34ebfec pc=0x90a60
Jul 12 12:33:57 SaltyRest rclone[440]: created by runtime.gcBgMarkStartWorkers
Jul 12 12:33:57 SaltyRest rclone[440]:         runtime/mgc.go:1199 +0x20
Jul 12 12:33:57 SaltyRest systemd[1]: Failed to start RClone GDrive mount.

Last lines of log, before the reboot:

2023/07/11 10:54:16 DEBUG : Google drive root '': Checking for changes on remote
2023/07/11 10:55:16 DEBUG : Google drive root '': Checking for changes on remote
2023/07/11 10:55:51 DEBUG : /home/boss/GDrive: Unmounted externally. Just exit now.
2023/07/11 10:55:52 INFO  : Signal received: terminated
2023/07/11 10:55:52 INFO  : Exiting...

kapitainsky · July 12, 2023, 10:42am

can you run:

rclone version

and then:

rclone mount GDrive: /home/boss/GDrive -vv

and post output here

Fabio · July 12, 2023, 10:59am

rclone version
unexpected fault address 0x33333c6b
fatal error: fault
[signal SIGSEGV: segmentation violation code=0x1 addr=0x33333c6b pc=0x36c54]

goroutine 1 [running, locked to thread]:
runtime.throw({0x196f191, 0x5})
	runtime/panic.go:1047 +0x4c fp=0x47dfa54 sp=0x47dfa40 pc=0x52790
runtime.sigpanic()
	runtime/signal_unix.go:851 +0x214 fp=0x47dfa70 sp=0x47dfa54 pc=0x6ce30
runtime.(*gcControllerState).startCycle(0x2c8ea10, 0x554f42ffdf5c, 0x4, {0x0, 0x0, 0x0})
	runtime/mgcpacer.go:428 +0x414 fp=0x47dfad8 sp=0x47dfa74 pc=0x36c54
runtime.gcStart({0x0, 0x0, 0x0})
	runtime/mgc.go:679 +0x4d0 fp=0x47dfb40 sp=0x47dfad8 pc=0x2d864
runtime.mallocgc(0x18, 0x1687598, 0x1)
	runtime/malloc.go:1172 +0x9f8 fp=0x47dfb78 sp=0x47dfb40 pc=0x1d82c
runtime.makeslice(0x1687598, 0x3, 0x3)
	runtime/slice.go:103 +0xac fp=0x47dfb8c sp=0x47dfb78 pc=0x6df88
regexp/syntax.(*Regexp).CapNames(0x4849540)
	regexp/syntax/regexp.go:308 +0x3c fp=0x47dfba8 sp=0x47dfb8c pc=0x1f77f8
regexp.compile({0x1a036d0, 0x35}, 0xd4, 0x0)
	regexp/regexp.go:179 +0x64 fp=0x47dfbf4 sp=0x47dfba8 pc=0x200260
regexp.Compile(...)
	regexp/regexp.go:137
regexp.MustCompile({0x1a036d0, 0x35})
	regexp/regexp.go:317 +0x34 fp=0x47dfc34 sp=0x47dfbf4 pc=0x200efc
github.com/rclone/rclone/backend/putio.init()
	github.com/rclone/rclone/backend/putio/putio.go:55 +0x6c fp=0x47dfc48 sp=0x47dfc34 pc=0xd40a28
runtime.doInit(0x2bb3110)
	runtime/proc.go:6506 +0x110 fp=0x47dfd70 sp=0x47dfc48 pc=0x651e0
runtime.doInit(0x2bb6af0)
	runtime/proc.go:6483 +0x54 fp=0x47dfe98 sp=0x47dfd70 pc=0x65124
runtime.doInit(0x2ba6bf0)
	runtime/proc.go:6483 +0x54 fp=0x47dffc0 sp=0x47dfe98 pc=0x65124
runtime.main()
	runtime/proc.go:233 +0x210 fp=0x47dffec sp=0x47dffc0 pc=0x55a28
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x47dffec sp=0x47dffec pc=0x90a60

goroutine 2 [force gc (idle)]:
runtime.gopark(0x1abe580, 0x2c791a0, 0x11, 0x14, 0x1)
	runtime/proc.go:381 +0x100 fp=0x4458fd8 sp=0x4458fc4 pc=0x55fa8
runtime.goparkunlock(...)
	runtime/proc.go:387
runtime.forcegchelper()
	runtime/proc.go:305 +0xd4 fp=0x4458fec sp=0x4458fd8 pc=0x55dc8
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x4458fec sp=0x4458fec pc=0x90a60
created by runtime.init.5
	runtime/proc.go:293 +0x1c

goroutine 3 [GC sweep wait]:
runtime.gopark(0x1abe580, 0x2c7a858, 0xc, 0x14, 0x1)
	runtime/proc.go:381 +0x100 fp=0x44597c8 sp=0x44597b4 pc=0x55fa8
runtime.goparkunlock(...)
	runtime/proc.go:387
runtime.bgsweep(0x4448080)
	runtime/mgcsweep.go:278 +0x94 fp=0x44597e4 sp=0x44597c8 pc=0x3cf3c
runtime.gcenable.func1()
	runtime/mgc.go:178 +0x28 fp=0x44597ec sp=0x44597e4 pc=0x2cd0c
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x44597ec sp=0x44597ec pc=0x90a60
created by runtime.gcenable
	runtime/mgc.go:178 +0x74

goroutine 4 [runnable]:
runtime.gopark(0x1abe580, 0x2c7ae60, 0xd, 0x14, 0x2)
	runtime/proc.go:381 +0x100 fp=0x4459fb4 sp=0x4459fa0 pc=0x55fa8
runtime.goparkunlock(...)
	runtime/proc.go:387
runtime.(*scavengerState).park(0x2c7ae60)
	runtime/mgcscavenge.go:400 +0x64 fp=0x4459fc8 sp=0x4459fb4 pc=0x3a2d8
runtime.bgscavenge(0x4448080)
	runtime/mgcscavenge.go:628 +0x3c fp=0x4459fe4 sp=0x4459fc8 pc=0x3ab9c
runtime.gcenable.func2()
	runtime/mgc.go:179 +0x28 fp=0x4459fec sp=0x4459fe4 pc=0x2ccb8
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x4459fec sp=0x4459fec pc=0x90a60
created by runtime.gcenable
	runtime/mgc.go:179 +0xbc

goroutine 5 [finalizer wait]:
runtime.gopark(0x1abe400, 0x2c8cc50, 0x10, 0x14, 0x1)
	runtime/proc.go:381 +0x100 fp=0x445878c sp=0x4458778 pc=0x55fa8
runtime.runfinq()
	runtime/mfinal.go:193 +0xfc fp=0x44587ec sp=0x445878c pc=0x2bbe4
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x44587ec sp=0x44587ec pc=0x90a60
created by runtime.createfing
	runtime/mfinal.go:163 +0x5c

goroutine 17 [select]:
runtime.gopark(0x1abe5b4, 0x0, 0x9, 0x18, 0x1)
	runtime/proc.go:381 +0x100 fp=0x445a6ec sp=0x445a6d8 pc=0x55fa8
runtime.selectgo(0x445a7cc, 0x445a7b8, 0x0, 0x0, 0x3, 0x1)
	runtime/select.go:327 +0xb70 fp=0x445a794 sp=0x445a6ec pc=0x6904c
go.opencensus.io/stats/view.(*worker).start(0x44bb2c0)
	go.opencensus.io@v0.24.0/stats/view/worker.go:292 +0xa0 fp=0x445a7e4 sp=0x445a794 pc=0x9448b4
go.opencensus.io/stats/view.init.0.func1()
	go.opencensus.io@v0.24.0/stats/view/worker.go:34 +0x28 fp=0x445a7ec sp=0x445a7e4 pc=0x943bf8
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x445a7ec sp=0x445a7ec pc=0x90a60
created by go.opencensus.io/stats/view.init.0
	go.opencensus.io@v0.24.0/stats/view/worker.go:34 +0xa0

goroutine 18 [GC worker (idle)]:
runtime.gopark(0x1abe410, 0x44d0cd8, 0x1a, 0x14, 0x0)
	runtime/proc.go:381 +0x100 fp=0x4454790 sp=0x445477c pc=0x55fa8
runtime.gcBgMarkWorker()
	runtime/mgc.go:1275 +0x100 fp=0x44547ec sp=0x4454790 pc=0x2f97c
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x44547ec sp=0x44547ec pc=0x90a60
created by runtime.gcBgMarkStartWorkers
	runtime/mgc.go:1199 +0x20

goroutine 19 [GC worker (idle)]:
runtime.gopark(0x1abe410, 0x44d0cf0, 0x1a, 0x14, 0x0)
	runtime/proc.go:381 +0x100 fp=0x4454f90 sp=0x4454f7c pc=0x55fa8
runtime.gcBgMarkWorker()
	runtime/mgc.go:1275 +0x100 fp=0x4454fec sp=0x4454f90 pc=0x2f97c
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x4454fec sp=0x4454fec pc=0x90a60
created by runtime.gcBgMarkStartWorkers
	runtime/mgc.go:1199 +0x20

goroutine 20 [GC worker (idle)]:
runtime.gopark(0x1abe410, 0x44d0d08, 0x1a, 0x14, 0x0)
	runtime/proc.go:381 +0x100 fp=0x4455790 sp=0x445577c pc=0x55fa8
runtime.gcBgMarkWorker()
	runtime/mgc.go:1275 +0x100 fp=0x44557ec sp=0x4455790 pc=0x2f97c
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x44557ec sp=0x44557ec pc=0x90a60
created by runtime.gcBgMarkStartWorkers
	runtime/mgc.go:1199 +0x20

goroutine 21 [GC worker (idle)]:
runtime.gopark(0x1abe410, 0x44d0d20, 0x1a, 0x14, 0x0)
	runtime/proc.go:381 +0x100 fp=0x4455f90 sp=0x4455f7c pc=0x55fa8
runtime.gcBgMarkWorker()
	runtime/mgc.go:1275 +0x100 fp=0x4455fec sp=0x4455f90 pc=0x2f97c
runtime.goexit()
	runtime/asm_arm.s:831 +0x4 fp=0x4455fec sp=0x4455fec pc=0x90a60
created by runtime.gcBgMarkStartWorkers
	runtime/mgc.go:1199 +0x20

kapitainsky · July 12, 2023, 11:02am

How did you install rclone?

Fabio · July 12, 2023, 11:04am

It was working before restart. I installed it with your install.sh script.

Since restart, my syslog is full of thousands of these messages:

Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/strs.(*Builder).AppendFullName(0x3ca67c0, {0x406d05f, 0xb}, {0x2c061c8, 0x1a})
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/strs/strings_unsafe.go:67 +0x68 fp=0x405f448 sp=0x405f3fc pc=0x4ca4f8
Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/filedesc.appendFullName(0x3ca67c0, {0x406d05f, 0xb}, {0x2c061c8, 0x1a, 0x2226})
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/filedesc/desc_init.go:470 +0x70 fp=0x405f47c sp=0x405f448 pc=0x4f3694
Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/filedesc.(*Message).unmarshalSeed(0x40944fc, {0x2c061c6, 0x6a, 0x2228}, 0x3ca67c0, 0x3cdf100, {0x1e9ec50, 0x3cdf100}, 0xb)
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/filedesc/desc_init.go:292 +0x270 fp=0x405f53c sp=0x405f47c pc=0x4f224c
Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/filedesc.(*File).unmarshalSeed(0x3cdf100, {0x2c054f0, 0x2efe, 0x2efe})
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/filedesc/desc_init.go:198 +0x106c fp=0x405f668 sp=0x405f53c pc=0x4f1738
Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/filedesc.newRawFile({{0x16b85ad, 0x3a}, {0x2c054f0, 0x2efe, 0x2efe}, 0xc, 0x31, 0x0, 0x0, {0x1e91ce0, ...}, ...})
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/filedesc/desc_init.go:29 +0x1d8 fp=0x405f6a8 sp=0x405f668 pc=0x4f0584
Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/filedesc.Builder.Build({{0x16b85ad, 0x3a}, {0x2c054f0, 0x2efe, 0x2efe}, 0xc, 0x31, 0x0, 0x0, {0x1e91ce0, ...}, ...})
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/filedesc/build.go:105 +0xb8 fp=0x405f6e4 sp=0x405f6a8 pc=0x4ed458
Jul 11 10:56:20 SaltyRest rclone[535]: google.golang.org/protobuf/internal/filetype.Builder.Build({{{0x16b85ad, 0x3a}, {0x2c054f0, 0x2efe, 0x2efe}, 0xc, 0x31, 0x0, 0x0, {0x0, ...}, ...}, ...})
Jul 11 10:56:20 SaltyRest rclone[535]: #011google.golang.org/protobuf@v1.28.1/internal/filetype/build.go:138 +0x13c fp=0x405f7e8 sp=0x405f6e4 pc=0x55d7ac
Jul 11 10:56:20 SaltyRest rclone[535]: github.com/colinmarc/hdfs/v2/internal/protocol/hadoop_hdfs.file_hdfs_proto_init()
Jul 11 10:56:20 SaltyRest rclone[535]: #011github.com/colinmarc/hdfs/v2@v2.3.0/internal/protocol/hadoop_hdfs/hdfs.pb.go:6290 +0x14c fp=0x405f8f0 sp=0x405f7e8 pc=0xb46300
Jul 11 10:56:20 SaltyRest rclone[535]: github.com/colinmarc/hdfs/v2/internal/protocol/hadoop_hdfs.file_AliasMa:

Don't know how to stop these messages and syslog is growing huge

kapitainsky · July 12, 2023, 11:07am

let's try to reinstall it

sudo rm /usr/bin/rclone
sudo -v ; curl https://rclone.org/install.sh | sudo bash

and try rclone version again

also post result of:

uname -a

Fabio · July 12, 2023, 11:10am

After reinstall:

rclone version
rclone v1.63.0
- os/version: raspbian 11.7
- os/kernel: 6.1.21-v7+ (armv7l)
- os/type: linux
- os/arch: arm (ARMv7 compatible)
- go/version: go1.20.5
- go/linking: static
- go/tags: none

uname -a
Linux SaltyRest 6.1.21-v7+ #1642 SMP Mon Apr  3 17:20:52 BST 2023 armv7l GNU/Linux

Fabio · July 12, 2023, 11:11am

It restarted!
It seems working

kapitainsky · July 12, 2023, 11:12am

so mount should work now too...

Maybe binary got corrupted? dying SD card? who knows.

Fabio · July 12, 2023, 11:12am

sudo service rcloneGmount status
● rcloneGmount.service - RClone GDrive mount
     Loaded: loaded (/etc/systemd/system/rcloneGmount.service; enabled; vendor preset: enabled)
     Active: active (running) since Wed 2023-07-12 13:09:30 CEST; 2min 56s ago
   Main PID: 2751 (rclone)
      Tasks: 10 (limit: 2057)
        CPU: 1.530s
     CGroup: /system.slice/rcloneGmount.service
             └─2751 /usr/bin/rclone mount GDrive: /home/boss/GDrive --log-file=/var/log/rclone.log --log-leve>

Jul 12 13:09:20 SaltyRest systemd[1]: rcloneGmount.service: Scheduled restart job, restart counter is at 407.
Jul 12 13:09:20 SaltyRest systemd[1]: Stopped RClone GDrive mount.
Jul 12 13:09:20 SaltyRest systemd[1]: Starting RClone GDrive mount...
Jul 12 13:09:22 SaltyRest rclone[2751]: 2023/07/12 13:09:22 DEBUG : Setting --config "/home/boss/.config/rclo>
Jul 12 13:09:30 SaltyRest systemd[1]: Started RClone GDrive mount.

Fabio · July 12, 2023, 11:13am

Isn't here any way to check the SD card?

kapitainsky · July 12, 2023, 11:16am

on other computer mount it and run non destructive badblocks

system · July 15, 2023, 11:16am

This topic was automatically closed 3 days after the last reply. New replies are no longer allowed.