[Support] Djoss - CrashPlan PRO (aka CrashPlan for Small Business)


Recommended Posts

9 hours ago, cromwell said:

 

Yes, however yesterday it did something different. It managed to finish the block sync and started backing up files again (yay!). but after maybe 60 minutes it started doing a block sync again and has been doing it since

Try reaching out to CrashPlan customer support. They were able to help me when I had a similar problem.

  • Like 1
Link to comment

My Crashplan has not been backing up for the past 3 days.

It states "Unable to connect to destination for 3.3 days"

ui.log shows...
 

2022-06-01T12:37:23.525Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 15
2022-06-01T12:37:24.529Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 16
2022-06-01T12:37:25.533Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 17
2022-06-01T12:37:26.537Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 18
2022-06-01T12:37:27.540Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 19
2022-06-01T12:37:29.370Z - info: Setting locale after receiving loginSetup to AUTOMATIC_LOCALE
2022-06-01T12:37:29.372Z - info: Connected to service, show main window
2022-06-01T12:37:30.371Z - info: Closing splash screen
2022-06-01T12:37:30.338Z - info JS Console: Init browser console logging
2022-06-01T12:37:30.339Z - info main: Starting application initialization
2022-06-01T12:37:30.345Z - info ServiceInterface: init() with https://127.0.0.1:4244
2022-06-01T12:37:30.374Z - info: Showing main window
2022-06-01T12:37:30.342Z - info main: Initialized ipc listeners to main process
2022-06-01T12:37:30.344Z - info main: Initialized push event interface to service
2022-06-01T12:37:30.345Z - info main: Initialized https interface to service
2022-06-01T12:37:30.376Z - info main: Performed localization loading, attempting to connect to the service
2022-06-01T12:37:30.589Z - info main: Connected to the service
2022-06-01T12:37:30.589Z - info main: Attempting to fetch customizations for un-authenticated state
2022-06-01T12:37:30.591Z - info main: System locale en_US did not match the locale returned from the service AUTOMATIC_LOCALE, requesting the correct locale
2022-06-01T12:37:30.629Z - info main: Attempting to auto-login
2022-06-01T12:37:30.630Z - info main: A token is available, test if the token is valid
2022-06-01T12:37:30.639Z - info main: Auto login - token is valid
2022-06-01T12:37:30.639Z - info main: Authenticate with push event interface and set logged in state
2022-06-01T12:37:30.639Z - info main: Requesting initial application data
2022-06-01T12:37:30.639Z - info AppInit: Set application state to be logged in
2022-06-01T12:37:30.645Z - info AppInit: Register authenticated event handlers
2022-06-01T12:37:30.645Z - info AppInit: Requesting all application initialization data
2022-06-01T12:37:30.815Z - info main: Starting main routing
2022-06-01T12:37:30.822Z - warn JS Console: Attempting to access localized key before initialization.
2022-06-01T12:37:30.822Z - warn JS Console: Attempting to access localized key before initialization.
2022-06-01T12:37:30.822Z - warn JS Console: Attempting to access localized key before initialization.
2022-06-01T12:37:31.089Z - info routes.js: onChange: previousPath=/devices/612667590995083266 with params={"deviceId":"612667590995083266"}
2022-06-01T12:37:31.278Z - warn RestAdapter: Error making service request to https://127.0.0.1:4244/v1/CustomDataKey : Bad Request
2022-06-01T12:37:31.089Z - info routes.js: onChange: nextPath=/devices/612667590995083266 with params={"deviceId":"612667590995083266"}



engine.error.log shows this, not sure if it's related...

 

WARNING: Illegal reflective access by com.code42.crypto.jce.ec.EcCurveLookup (file:/usr/local/crashplan/lib/c42-crypto-impl-15.2.4.jar) to method sun.security.util.CurveDB.lookup(java.security.spec.ECParameterSpec)
WARNING: Please consider reporting this to the maintainers of com.code42.crypto.jce.ec.EcCurveLookup
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release


Any Ideas?

 

Link to comment
On 6/1/2022 at 8:51 AM, NeoDude said:

My Crashplan has not been backing up for the past 3 days.

It states "Unable to connect to destination for 3.3 days"

ui.log shows...
 

2022-06-01T12:37:23.525Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 15
2022-06-01T12:37:24.529Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 16
2022-06-01T12:37:25.533Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 17
2022-06-01T12:37:26.537Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 18
2022-06-01T12:37:27.540Z - info: Retrying communication with service connect ECONNREFUSED 127.0.0.1:4244 attempt 19
2022-06-01T12:37:29.370Z - info: Setting locale after receiving loginSetup to AUTOMATIC_LOCALE
2022-06-01T12:37:29.372Z - info: Connected to service, show main window
2022-06-01T12:37:30.371Z - info: Closing splash screen
2022-06-01T12:37:30.338Z - info JS Console: Init browser console logging
2022-06-01T12:37:30.339Z - info main: Starting application initialization
2022-06-01T12:37:30.345Z - info ServiceInterface: init() with https://127.0.0.1:4244
2022-06-01T12:37:30.374Z - info: Showing main window
2022-06-01T12:37:30.342Z - info main: Initialized ipc listeners to main process
2022-06-01T12:37:30.344Z - info main: Initialized push event interface to service
2022-06-01T12:37:30.345Z - info main: Initialized https interface to service
2022-06-01T12:37:30.376Z - info main: Performed localization loading, attempting to connect to the service
2022-06-01T12:37:30.589Z - info main: Connected to the service
2022-06-01T12:37:30.589Z - info main: Attempting to fetch customizations for un-authenticated state
2022-06-01T12:37:30.591Z - info main: System locale en_US did not match the locale returned from the service AUTOMATIC_LOCALE, requesting the correct locale
2022-06-01T12:37:30.629Z - info main: Attempting to auto-login
2022-06-01T12:37:30.630Z - info main: A token is available, test if the token is valid
2022-06-01T12:37:30.639Z - info main: Auto login - token is valid
2022-06-01T12:37:30.639Z - info main: Authenticate with push event interface and set logged in state
2022-06-01T12:37:30.639Z - info main: Requesting initial application data
2022-06-01T12:37:30.639Z - info AppInit: Set application state to be logged in
2022-06-01T12:37:30.645Z - info AppInit: Register authenticated event handlers
2022-06-01T12:37:30.645Z - info AppInit: Requesting all application initialization data
2022-06-01T12:37:30.815Z - info main: Starting main routing
2022-06-01T12:37:30.822Z - warn JS Console: Attempting to access localized key before initialization.
2022-06-01T12:37:30.822Z - warn JS Console: Attempting to access localized key before initialization.
2022-06-01T12:37:30.822Z - warn JS Console: Attempting to access localized key before initialization.
2022-06-01T12:37:31.089Z - info routes.js: onChange: previousPath=/devices/612667590995083266 with params={"deviceId":"612667590995083266"}
2022-06-01T12:37:31.278Z - warn RestAdapter: Error making service request to https://127.0.0.1:4244/v1/CustomDataKey : Bad Request
2022-06-01T12:37:31.089Z - info routes.js: onChange: nextPath=/devices/612667590995083266 with params={"deviceId":"612667590995083266"}



engine.error.log shows this, not sure if it's related...

 

WARNING: Illegal reflective access by com.code42.crypto.jce.ec.EcCurveLookup (file:/usr/local/crashplan/lib/c42-crypto-impl-15.2.4.jar) to method sun.security.util.CurveDB.lookup(java.security.spec.ECParameterSpec)
WARNING: Please consider reporting this to the maintainers of com.code42.crypto.jce.ec.EcCurveLookup
WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
WARNING: All illegal access operations will be denied in a future release


Any Ideas?
 

 

Check in service.log.0 instead.

Link to comment
1 hour ago, Djoss said:

 

Check in service.log.0 instead.


Just this repeating...

 

[06.02.22 18:55:58.587 INFO  re-event-2-1 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0xf4a74296], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1062744289189997773, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.02.22 18:55:58.587 INFO  re-event-2-1                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0xf4a74296]
[06.02.22 18:57:06.798 INFO  DefaultGroup .code42.messaging.peer.PeerGroup] PG::DefaultGroup DONE Managing connected remote peers. numConnected=1, numFailedConnectedCheck=0, duration(ms)=0

 

Link to comment
On 6/2/2022 at 2:03 PM, NeoDude said:


Just this repeating...

 

[06.02.22 18:55:58.587 INFO  re-event-2-1 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0xf4a74296], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1062744289189997773, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.02.22 18:55:58.587 INFO  re-event-2-1                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0xf4a74296]
[06.02.22 18:57:06.798 INFO  DefaultGroup .code42.messaging.peer.PeerGroup] PG::DefaultGroup DONE Managing connected remote peers. numConnected=1, numFailedConnectedCheck=0, duration(ms)=0

 

 

I assume the container image is up-to-date ?

 

You can also try to re-login: double click on the Code42 logo and enter the "deauthorize" command.

Link to comment
On 6/6/2022 at 10:12 PM, Djoss said:

 

I assume the container image is up-to-date ?

 

You can also try to re-login: double click on the Code42 logo and enter the "deauthorize" command.

You assume correctly :)

I'm now logged out and it won't let me log back in. "Unable to sign in. Can't connect to server"

😕

Link to comment
On 6/10/2022 at 5:44 PM, Djoss said:

What are the errors now in service.log.0 ?


 

[06.13.22 07:34:10.870 INFO  er1WeDftWkr0 ging.direct.DirectNetworkChannel] Channel became inactive. closedBy=THIS_SIDE, reason='Session close', channel=DIRECT
[06.13.22 07:34:12.869 INFO  re-event-2-1 abre.SabrePendingChannelListener] SABRE::Channel connect failed for guid 4200, cause=java.net.UnknownHostException: central.crashplanpro.com
[06.13.22 07:34:12.869 INFO  re-event-2-1 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0xcc3fd3a9], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1064270167411230650, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.13.22 07:34:12.870 INFO  re-event-2-1                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0xcc3fd3a9]
[06.13.22 07:34:31.891 INFO  re-event-2-2 abre.SabrePendingChannelListener] SABRE::Channel connect failed for guid 4200, cause=java.net.UnknownHostException: central.crashplanpro.com
[06.13.22 07:34:31.891 INFO  re-event-2-2 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0x6153b7cb], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1064270199321495482, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.13.22 07:34:31.891 INFO  re-event-2-2                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0x6153b7cb]
[06.13.22 07:34:33.893 INFO  re-event-2-3 abre.SabrePendingChannelListener] SABRE::Channel connect failed for guid 4200, cause=java.net.UnknownHostException: central.crashplanpro.com
[06.13.22 07:34:33.893 INFO  re-event-2-3 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0x9697aaa7], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1064270202676938682, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.13.22 07:34:33.893 INFO  re-event-2-3                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0x9697aaa7]

 

Link to comment
5 hours ago, NeoDude said:


 

[06.13.22 07:34:10.870 INFO  er1WeDftWkr0 ging.direct.DirectNetworkChannel] Channel became inactive. closedBy=THIS_SIDE, reason='Session close', channel=DIRECT
[06.13.22 07:34:12.869 INFO  re-event-2-1 abre.SabrePendingChannelListener] SABRE::Channel connect failed for guid 4200, cause=java.net.UnknownHostException: central.crashplanpro.com
[06.13.22 07:34:12.869 INFO  re-event-2-1 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0xcc3fd3a9], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1064270167411230650, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.13.22 07:34:12.870 INFO  re-event-2-1                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0xcc3fd3a9]
[06.13.22 07:34:31.891 INFO  re-event-2-2 abre.SabrePendingChannelListener] SABRE::Channel connect failed for guid 4200, cause=java.net.UnknownHostException: central.crashplanpro.com
[06.13.22 07:34:31.891 INFO  re-event-2-2 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0x6153b7cb], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1064270199321495482, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.13.22 07:34:31.891 INFO  re-event-2-2                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0x6153b7cb]
[06.13.22 07:34:33.893 INFO  re-event-2-3 abre.SabrePendingChannelListener] SABRE::Channel connect failed for guid 4200, cause=java.net.UnknownHostException: central.crashplanpro.com
[06.13.22 07:34:33.893 INFO  re-event-2-3 .handler.ChannelLifecycleHandler] SABRE:: Channel became inactive. closedBy=THIS_SIDE, channel=[id: 0x9697aaa7], sessionState=RemotePeer-[guid=4200, state=CONNECTING]; Session-[localID=1064270202676938682, remoteID=0, closed=false, expiration=null, remoteIdentity=ENDPOINT, local=null, remote=null]
[06.13.22 07:34:33.893 INFO  re-event-2-3                      SabreLogger] Channel failed to reach state for app protocol start. reason=javax.net.ssl.SSLHandshakeException: SslHandler removed before handshake completed, channel=[id: 0x9697aaa7]

 

 

Looks like a network issue.  Can you run the following command to see if the DNS resolution works from the container:

 

docker exec CrashPlanPRO nslookup central.crashplanpro.com

 

Link to comment
27 minutes ago, Djoss said:

 

Looks like a network issue.  Can you run the following command to see if the DNS resolution works from the container:

 

docker exec CrashPlanPRO nslookup central.crashplanpro.com

 


Nope. Says no servers can be found. But the same lookup done directly from UnRaid or my PfSense box works fine.

 

Edited by NeoDude
Link to comment
2 minutes ago, NeoDude said:


Nope. Says no servers can be found. But the same lookup done directly from UnRaid or my PfSense box works fine.

 

How did you configure the container ?  Are you using the default Bridge network mode ?

Link to comment

Ok, I fixed it. The issue was due to the fact I had a Static IP mapping entry for the Container in PfSense. The MAC address must've changed (possibly when I changed from MACVLAN to IPVLAN?). Deleted it and all is good with the world.

Thanks for steering me in the right direction :)

Link to comment
  • 1 month later...
  • 1 month later...

[SOLVED, found it myself]

 

First of all, thanks for a great plugin. I just had a disk crash and some of the files where lost. My plan was to restore to a separate folder and then manually compare and copy the restored files to the original location. But regardless how I do it, I get the same error creating directory, Read only file system. What am I missing, how do I allow the Crashplan write access to my unraid server?

 

Thx!

Edited by Lidde
Link to comment
  • 4 weeks later...

So apparantly I was posting this in the wrong "container" support.  I am having an issue restoring folders.  See below:

 

I've had CrashplanPro installed for some time and it seems to be working fine from a "backup" perspective.  Due to a disk error, I am at a point now where I need to recover some data and I am struggling big time.  Upon the first couple tries, I was "hard-stuck" at the "Preparing Data".  I let it sit overnight to no avail.  Everything seems to be online and appropriate.  Now a day later, I keep getting this error when trying to backup:

image.png.b73e3ba7fc5fd7538cf9ce87af2b9bb8.png

The error doesn't seem to go away regardless of what options I choose from the dropdowns.  It no longer allows me to put anything in queue.  What could I be doing wrong?

 

I can access my service.log file however, I am not sure what information is sensitive vs. useful.  I am seeing this: 

[09.27.22 01:07:04.206 INFO  REDACT-229 backup42.service.ui.UIController] UI:: UserActionRequest: RestoreCheckRequestMessage[REDACT] 
[09.27.22 01:07:04.206 INFO  REDACT-229 backup42.service.ui.UIController] UI:: UserActionResponse: RestoreCheckResponseMessage[restoreId=REDACTfileName=/tmp/REDACT.txt]
[09.27.22 01:07:04.216 INFO  REDACT-284 tore.BackupClientRestoreDelegate] BC::stopRestore(): idPair=REDACT>41, selectedForRestore=true, event=STOP_REQUESTED canceled=true
[09.27.22 01:07:04.216 WARN  REDACT-284 .service.backup.BackupController] Unable to start restore. null
[09.27.22 01:07:07.390 INFO  ackupConnWrk rvice.backup.BackupConnectWorker] BCW:: Connecting REDACT>41 - peer to backup client.
[09.27.22 01:07:07.391 INFO  ackupConnWrk tore.BackupClientRestoreDelegate] BC::stopRestore(): idPair=REDACT>41, selectedForRestore=true, event=STOP_REQUESTED canceled=false
[09.27.22 01:07:07.391 WARN  ackupConnWrk .service.backup.BackupController] Problem connection backup!, java.lang.NullPointerException
STACKTRACE:: java.lang.NullPointerException
	at com.code42.backup.restore.BackupClientRestoreDelegate.clearPendingRestores(BackupClientRestoreDelegate.java:1493)
	at com.code42.backup.restore.BackupClientRestoreDelegate.restoreStoppedImpl(BackupClientRestoreDelegate.java:1074)
	at com.code42.backup.restore.BackupClientRestoreDelegate.lambda$initializeEventStateMachine$3(BackupClientRestoreDelegate.java:459)
	at com.code42.utils.EventStateMachine.processEvents(EventStateMachine.java:173)
	at com.code42.backup.restore.BackupClientRestoreDelegate.stopRestore(BackupClientRestoreDelegate.java:309)
	at com.code42.backup.BackupClientV1.stopRestore(BackupClientV1.java:1253)
	at com.code42.backup.BackupClientV1.stop(BackupClientV1.java:481)
	at com.code42.backup.BackupClientV1.reset(BackupClientV1.java:255)
	at com.code42.backup.BackupEntityV1.connectToSession(BackupEntityV1.java:260)
	at com.code42.backup.BackupClientV1.connectToSession(BackupClientV1.java:278)
	at com.code42.backup.BackupManager.connectClient(BackupManager.java:1508)
	at com.backup42.service.backup.BackupController.connectClient(BackupController.java:2814)
	at com.backup42.service.backup.BackupConnectWorker.connect(BackupConnectWorker.java:112)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.accept(Unknown Source)
	at java.base/java.util.stream.ReferencePipeline$2$1.accept(Unknown Source)
	at java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(Unknown Source)
	at java.base/java.util.stream.AbstractPipeline.copyInto(Unknown Source)
	at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(Unknown Source)
	at java.base/java.util.stream.ForEachOps$ForEachOp.evaluateSequential(Unknown Source)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(Unknown Source)
	at java.base/java.util.stream.AbstractPipeline.evaluate(Unknown Source)
	at java.base/java.util.stream.ReferencePipeline.forEach(Unknown Source)
	at com.backup42.service.backup.BackupConnectWorker.doWork(BackupConnectWorker.java:59)
	at com.code42.utils.AWorker.run(AWorker.java:153)
	at java.base/java.lang.Thread.run(Unknown Source)

 

 

Any thoughts?  I am running the most current version of CrashplanPro docker.

Edited by Hawkins12
Link to comment
21 hours ago, Hawkins12 said:

So apparantly I was posting this in the wrong "container" support.  I am having an issue restoring folders.  See below:

 

I've had CrashplanPro installed for some time and it seems to be working fine from a "backup" perspective.  Due to a disk error, I am at a point now where I need to recover some data and I am struggling big time.  Upon the first couple tries, I was "hard-stuck" at the "Preparing Data".  I let it sit overnight to no avail.  Everything seems to be online and appropriate.  Now a day later, I keep getting this error when trying to backup:

image.png.b73e3ba7fc5fd7538cf9ce87af2b9bb8.png

The error doesn't seem to go away regardless of what options I choose from the dropdowns.  It no longer allows me to put anything in queue.  What could I be doing wrong?

 

I can access my service.log file however, I am not sure what information is sensitive vs. useful.  I am seeing this: 

[09.27.22 01:07:04.206 INFO  REDACT-229 backup42.service.ui.UIController] UI:: UserActionRequest: RestoreCheckRequestMessage[REDACT] 
[09.27.22 01:07:04.206 INFO  REDACT-229 backup42.service.ui.UIController] UI:: UserActionResponse: RestoreCheckResponseMessage[restoreId=REDACTfileName=/tmp/REDACT.txt]
[09.27.22 01:07:04.216 INFO  REDACT-284 tore.BackupClientRestoreDelegate] BC::stopRestore(): idPair=REDACT>41, selectedForRestore=true, event=STOP_REQUESTED canceled=true
[09.27.22 01:07:04.216 WARN  REDACT-284 .service.backup.BackupController] Unable to start restore. null
[09.27.22 01:07:07.390 INFO  ackupConnWrk rvice.backup.BackupConnectWorker] BCW:: Connecting REDACT>41 - peer to backup client.
[09.27.22 01:07:07.391 INFO  ackupConnWrk tore.BackupClientRestoreDelegate] BC::stopRestore(): idPair=REDACT>41, selectedForRestore=true, event=STOP_REQUESTED canceled=false
[09.27.22 01:07:07.391 WARN  ackupConnWrk .service.backup.BackupController] Problem connection backup!, java.lang.NullPointerException
STACKTRACE:: java.lang.NullPointerException
	at com.code42.backup.restore.BackupClientRestoreDelegate.clearPendingRestores(BackupClientRestoreDelegate.java:1493)
	at com.code42.backup.restore.BackupClientRestoreDelegate.restoreStoppedImpl(BackupClientRestoreDelegate.java:1074)
	at com.code42.backup.restore.BackupClientRestoreDelegate.lambda$initializeEventStateMachine$3(BackupClientRestoreDelegate.java:459)
	at com.code42.utils.EventStateMachine.processEvents(EventStateMachine.java:173)
	at com.code42.backup.restore.BackupClientRestoreDelegate.stopRestore(BackupClientRestoreDelegate.java:309)
	at com.code42.backup.BackupClientV1.stopRestore(BackupClientV1.java:1253)
	at com.code42.backup.BackupClientV1.stop(BackupClientV1.java:481)
	at com.code42.backup.BackupClientV1.reset(BackupClientV1.java:255)
	at com.code42.backup.BackupEntityV1.connectToSession(BackupEntityV1.java:260)
	at com.code42.backup.BackupClientV1.connectToSession(BackupClientV1.java:278)
	at com.code42.backup.BackupManager.connectClient(BackupManager.java:1508)
	at com.backup42.service.backup.BackupController.connectClient(BackupController.java:2814)
	at com.backup42.service.backup.BackupConnectWorker.connect(BackupConnectWorker.java:112)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.accept(Unknown Source)
	at java.base/java.util.stream.ReferencePipeline$2$1.accept(Unknown Source)
	at java.base/java.util.ArrayList$ArrayListSpliterator.forEachRemaining(Unknown Source)
	at java.base/java.util.stream.AbstractPipeline.copyInto(Unknown Source)
	at java.base/java.util.stream.AbstractPipeline.wrapAndCopyInto(Unknown Source)
	at java.base/java.util.stream.ForEachOps$ForEachOp.evaluateSequential(Unknown Source)
	at java.base/java.util.stream.ForEachOps$ForEachOp$OfRef.evaluateSequential(Unknown Source)
	at java.base/java.util.stream.AbstractPipeline.evaluate(Unknown Source)
	at java.base/java.util.stream.ReferencePipeline.forEach(Unknown Source)
	at com.backup42.service.backup.BackupConnectWorker.doWork(BackupConnectWorker.java:59)
	at com.code42.utils.AWorker.run(AWorker.java:153)
	at java.base/java.lang.Thread.run(Unknown Source)

 

 

Any thoughts?  I am running the most current version of CrashplanPro docker.

 

You can try to restart the container if you think it is stuck.

 

Then,  try to restore just a single file.  Run "tail -f /mnt/user/appdata/CrashPlanPRO/log/service.log.0" before starting the restore process so we can get all related logs.

Link to comment
25 minutes ago, Djoss said:

 

You can try to restart the container if you think it is stuck.

 

Then,  try to restore just a single file.  Run "tail -f /mnt/user/appdata/CrashPlanPRO/log/service.log.0" before starting the restore process so we can get all related logs.

Thanks.  Took the steps (cleared the service.log.0 log) and tried to restore 1 of the 12 files in this folder.  I get this:

 

image.png.c9b41bac9b31c81e909f51e49fb29da6.png

 

Seems promising but nothing actually happens.  Getting tons of messages in the service.log.0 like this.  Any thoughts?

[09.27.22 22:38:54.153 INFO  er1WeDftWkr0 42.backup.handler.RestoreHandler] Problem restoring! backupData=BackupData[BackupBlock[SourceBlock[BlockRecord[blockNumber = 243812, bdfPosition = -1, state = 5, isRemoved = false], sourceLength = 110151, sourceWeakChecksum = REDACT, sourceStrongChecksum = REDACT, type = 21], backupLength = 102752, backupChecksumAsHex = REDACT], dataBuffer = java.nio.HeapByteBuffer[pos=59 lim=102811 cap=102811]], java.lang.NullPointerException; RestoreHandler@1775562762[ REDACT>41: fileId=REDACT, numBlocksToRestore=231, numBlocksTransferred=0, bytesRestored=0, progressCount=0, 1sec, BC[REDACT>41, sameOwner=f, backupConnected=t, authorized=t, usingForBackup=t, backupNotReadyCode=null, backingUp=f, validating=f, closing=f, keepBlockState=0, con=2022-09-27T22:38:11:008, val=2022-09-27T22:38:14:345, readyCheckTime=2022-09-27T22:38:11:008, MM[BT REDACT>41: openCount=0, initialized = true, dataFiles.open = false, /usr/local/crashplan/cache/41], session=REDACT, hosted=t, hostedCloud=t, replacing=f, selectedForBackup=f, selectedForRestore=t, validationNeeded=f, backupUsageTime=2022-09-27T22:38:52:035, cacheMaintenanceState=null, restoreUtil=BackupClientRestoreDelegate [restoreEnv=AgentRestoreEnv[restoreJob = RestoreJob@-1541681516[ REDACT, REDACT:41, userId=REDACT, acceptingGuid=REDACT, restorePath=null, problemCount=0, failedChecksumCount=0, overwrite=false, validate=true, allVersions=false, lastRestoreFileId=null, osUid=99, osGid=100, useOsUids=true, includeDeleted=false, resumeTime=0, completed=false, canceled=false, localRestore=false, restoreType=CLIENT, restoreSubType=STANDARD, numFilesToRestore=1, numBytesToRestore=30256711, numFilesRestored=0, numBytesRestored=0, sessionTimestamp=0, fileRestoreStrategyId=2, instrumentationStats=InstrumentationStats{totalTime=-41476ms, totalProcessingTime=0ms, readTime=0ms, messageSendTime=0ms, decryptTime=0ms, decompressTime=0ms, writeDataTime=0ms, writeMetaDataTime=0ms, checksumVerificationTime=0ms, startRestoreTime=1009ms, finishRestoreTime=0ms, createTempFileTime=0ms}, userName=app, isUsingRestoreTool=true, groups=[RestoreGroup[pathSet = [V3[cs]+/storage/REDACT/IMG_5874.MOV;w], timestamp = 0]]]], selectedForRestore=true, remoteRestoreStats=RestoreStats[BT sourceId = REDACT, targetId = 41, restoreId = REDACT, selectedForRestore = false, restoring = true, completed = false, completedTimeInMillis = null, stopwatch = 2sec, numFilesToRestore = 1, numBytesToRestore = 30256711, numFilesRestored = 0, numBytesRestored = 0, %complete = 0.00%, receiveRateInBytesPerSec(B/s) = 0.000, sendRateInBytesPerSec(B/s) = 0.000, estimatedTimeRemaining = 0.000, fileNumBytesToRestore = 30256711, fileNumBytesRestored = 0, %completeCurrentFile = 0.00%, numSessionFilesRestored = 0, numSessionBytesRestored = 0, problemCount = 0], pendingRestoresCount=0], BackupQueue[REDACT>41, running=f, #tasks=0, sets=[BackupFileTodoSet[backupSetId=1, guid=41, doneLoadingFiles=t, doneLoadingTasks=f, FileTodoSet@153768949[ path = /usr/local/crashplan/cache/cpft1_41, closed = false, dataSize = 61845, headerSize = 0], numTodos = 221, numBytes = 1662557334]], BackupFileTodoSet[backupSetId=REDACT, guid=41, doneLoadingFiles=t, doneLoadingTasks=f, FileTodoSet@2108449319[ path = /usr/local/crashplan/cache/cpft1020267959441_41, closed = false, dataSize = 12, headerSize = 0], numTodos = 0, numBytes = 0]], BackupFileTodoSet[backupSetId=REDACT, guid=41, doneLoadingFiles=t, doneLoadingTasks=f, FileTodoSet@275596035[ path = /usr/local/crashplan/cache/cpft102026802062_41, closed = false, dataSize = 2943, headerSize = 0], numTodos = 5, numBytes = 192760033]], BackupFileTodoSet[backupSetId=REDACT, guid=41, doneLoadingFiles=t, doneLoadingTasks=f, FileTodoSet@150632961[ path = /usr/local/crashplan/cache/cpft1020267917_41, closed = false, dataSize = 105803, headerSize = 0], numTodos = 723, numBytes = 2149226409]], BackupFileTodoSet[backupSetId=REDACT, guid=41, doneLoadingFiles=t, doneLoadingTasks=f, FileTodoSet@1668751860[ path = /usr/local/crashplan/cache/cpft102026810_41, closed = false, dataSize = 371341, headerSize = 0], numTodos = 2756, numBytes = 1796143574458]]], env=null, TodoWorker@1800907882[ threadName = BQTodoWkr-41, stopped = true, running = false, thread.isDaemon = false, thread.isAlive = false, thread = Thread[W566888887_BQTodoWkr-41,5,]], TaskWorker@752883223[ threadName = BQTaskWrk-41, stopped = true, running = false, thread.isDaemon = false, thread.isAlive = false, thread = Thread[W1145855976_BQTaskWrk-41,5,]]] ]], java.lang.NullPointerException
STACKTRACE:: java.lang.NullPointerException
[09.27.22 22:38:54.153 INFO  er1WeDftWkr0 tore.BackupClientRestoreDelegate] BC::Problem restoring! backupData=BackupData[BackupBlock[SourceBlock[BlockRecord[blockNumber = 243812, bdfPosition = -1, state = 5, isRemoved = false], sourceLength = 110151, sourceWeakChecksum = REDACT, sourceStrongChecksum = REDACT, type = 21], backupLength = 102752, backupChecksumAsHex = REDACT], dataBuffer = java.nio.HeapByteBuffer[pos=59 lim=102811 cap=102811]] e=null
[09.27.22 22:38:54.153 WARN  er1WeDftWkr0 2.messaging.MessageReceiverProxy] MessageReceiverProxy: InvocationTargetException receiving message=type=com.code42.backup.message.restore.RestoreBackupDataMessage, contents=RestoreBackupDataMessage@1382713588[ BackupData[BackupBlock[SourceBlock[BlockRecord[blockNumber = 243812, bdfPosition = -1, state = 5, isRemoved = false], sourceLength = 110151, sourceWeakChecksum = REDACT, sourceStron..., session=RemotePeer-[guid=41, state=CONNECTED]; Session-[localID=REDACT, remoteID=REDACT, layer=Peer::Sabre, closed=false, expiration=null, remoteIdentity=STORAGE, local=REDACT, remote=162.222.40.206:4287], e=java.lang.reflect.InvocationTargetException, cause=java.lang.NullPointerException, java.lang.NullPointerException
STACKTRACE:: java.lang.NullPointerException

 

Edited by Hawkins12
Link to comment
4 minutes ago, Hawkins12 said:

Thanks.  Took the steps (cleared the service.log.0 log) and tried to restore 1 of the 12 files in this folder.  I get this:

 

image.png.c9b41bac9b31c81e909f51e49fb29da6.png

 

Seems promising but nothing actually happens.  Getting tons of messages in the service.log.0 like this.  Any thoughts?

 

[09.27.22 22:38:54.153 INFO  er1WeDftWkr0 tore.BackupClientRestoreDelegate] BC::Problem restoring! backupData=BackupData[BackupBlock[SourceBlock[BlockRecord[blockNumber = 243812, bdfPosition = -1, state = 5, isRemoved = false], sourceLength = 110151, sourceWeakChecksum = REDACT, sourceStrongChecksum = REDACT, type = 21], backupLength = 102752, backupChecksumAsHex = REDACT], dataBuffer = java.nio.HeapByteBuffer[pos=59 lim=102811 cap=102811]] e=null
[09.27.22 22:38:54.153 WARN  er1WeDftWkr0 2.messaging.MessageReceiverProxy] MessageReceiverProxy: InvocationTargetException receiving message=type=com.code42.backup.message.restore.RestoreBackupDataMessage, contents=RestoreBackupDataMessage@1382713588[ BackupData[BackupBlock[SourceBlock[BlockRecord[blockNumber = 243812, bdfPosition = -1, state = 5, isRemoved = false], sourceLength = 110151, sourceWeakChecksum = -2040189618, sourceStron..., session=RemotePeer-[guid=41, state=CONNECTED]; Session-[localID=REDACT, remoteID=REDACT, layer=Peer::Sabre, closed=false, expiration=null, remoteIdentity=STORAGE, local=REDACT, remote=162.222.40.206:4287], e=java.lang.reflect.InvocationTargetException, cause=java.lang.NullPointerException, java.lang.NullPointerException
STACKTRACE:: java.lang.NullPointerException

 

What is following the "STACKTRACE:: java.lang.NullPointerException" message ?

Link to comment
41 minutes ago, Djoss said:

What is following the "STACKTRACE:: java.lang.NullPointerException" message ?

	at com.code42.backup.handler.RestoreHandler.restoreBackupData(RestoreHandler.java:401)
	at com.code42.backup.restore.BackupClientRestoreDelegate.restoreBackupDataImpl(BackupClientRestoreDelegate.java:1213)
	at com.code42.backup.restore.BackupClientRestoreDelegate.lambda$initializeEventStateMachine$21(BackupClientRestoreDelegate.java:519)
	at com.code42.utils.EventStateMachine.processEvents(EventStateMachine.java:173)
	at com.code42.backup.restore.BackupClientRestoreDelegate.restoreBackupData(BackupClientRestoreDelegate.java:379)
	at com.code42.backup.BackupClientV1.restoreBackupData(BackupClientV1.java:1280)
	at com.code42.backup.message.BackupClientMessageReceiver.receiveMessage(BackupClientMessageReceiver.java:99)
	at jdk.internal.reflect.GeneratedMethodAccessor24.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.base/java.lang.reflect.Method.invoke(Unknown Source)
	at com.code42.messaging.MessageReceiverProxy.invokeLegacyMessage(MessageReceiverProxy.java:186)
	at com.code42.messaging.MessageReceiverProxy.receiveMessage(MessageReceiverProxy.java:154)
	at com.code42.messaging.SessionImpl.receiveMessage(SessionImpl.java:527)
	at com.code42.messaging.mde.MessageWork.lambda$receiveMessage$0(MessageWork.java:89)
	at com.code42.messaging.telemetry.MessageHandlingTelemeter.monitorMessageProcessingEvent(MessageHandlingTelemeter.java:32)
	at com.code42.messaging.mde.MessageWork.receiveMessage(MessageWork.java:77)
	at com.code42.messaging.mde.MessageWork.handleWork(MessageWork.java:67)
	at com.code42.messaging.mde.UnitOfWork.processWork(UnitOfWork.java:163)
	at com.code42.messaging.mde.UnitOfWork.run(UnitOfWork.java:147)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
	at java.base/java.lang.Thread.run(Unknown Source)

 

Link to comment
8 hours ago, Hawkins12 said:
	at com.code42.backup.handler.RestoreHandler.restoreBackupData(RestoreHandler.java:401)
	at com.code42.backup.restore.BackupClientRestoreDelegate.restoreBackupDataImpl(BackupClientRestoreDelegate.java:1213)
	at com.code42.backup.restore.BackupClientRestoreDelegate.lambda$initializeEventStateMachine$21(BackupClientRestoreDelegate.java:519)
	at com.code42.utils.EventStateMachine.processEvents(EventStateMachine.java:173)
	at com.code42.backup.restore.BackupClientRestoreDelegate.restoreBackupData(BackupClientRestoreDelegate.java:379)
	at com.code42.backup.BackupClientV1.restoreBackupData(BackupClientV1.java:1280)
	at com.code42.backup.message.BackupClientMessageReceiver.receiveMessage(BackupClientMessageReceiver.java:99)
	at jdk.internal.reflect.GeneratedMethodAccessor24.invoke(Unknown Source)
	at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.base/java.lang.reflect.Method.invoke(Unknown Source)
	at com.code42.messaging.MessageReceiverProxy.invokeLegacyMessage(MessageReceiverProxy.java:186)
	at com.code42.messaging.MessageReceiverProxy.receiveMessage(MessageReceiverProxy.java:154)
	at com.code42.messaging.SessionImpl.receiveMessage(SessionImpl.java:527)
	at com.code42.messaging.mde.MessageWork.lambda$receiveMessage$0(MessageWork.java:89)
	at com.code42.messaging.telemetry.MessageHandlingTelemeter.monitorMessageProcessingEvent(MessageHandlingTelemeter.java:32)
	at com.code42.messaging.mde.MessageWork.receiveMessage(MessageWork.java:77)
	at com.code42.messaging.mde.MessageWork.handleWork(MessageWork.java:67)
	at com.code42.messaging.mde.UnitOfWork.processWork(UnitOfWork.java:163)
	at com.code42.messaging.mde.UnitOfWork.run(UnitOfWork.java:147)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)
	at java.base/java.lang.Thread.run(Unknown Source)

 

I don't see anything obvious from the provided logs.  For me the restore functionality works fine.

 

You should probably try the contact the CrashPlan's support team.  Provide the error messages and don't tell them that you are using CrashPlan in a Docker container, since they will tell you it's not supported.

Link to comment
7 hours ago, Djoss said:

 

I don't see anything obvious from the provided logs.  For me the restore functionality works fine.

 

You should probably try the contact the CrashPlan's support team.  Provide the error messages and don't tell them that you are using CrashPlan in a Docker container, since they will tell you it's not supported.

 

I am seeing this in the logs:  Failed to create directory at /storage/REDACT: mkdir /storage/REDACT: read-only file system read-only file system.

 

I tried

chmod 777 /usr/local/crashplan

But it didn't seem to work.  Any suggestions?   Thanks for taking the time to assist me!

Edited by Hawkins12
Link to comment
53 minutes ago, Hawkins12 said:

 

I am seeing this in the logs:  Failed to create directory at /storage/REDACT: mkdir /storage/REDACT: read-only file system read-only file system.

 

I tried

chmod 777 /usr/local/crashplan

But it didn't seem to work.  Any suggestions?   Thanks for taking the time to assist me!

 

Ah, from your screenshot you were restoring to /config.  This location should work since it is read/write.

 

/storage is read-only by default.  If you want to restore to /storage, you first need to change it to read/write.  This can be done in settings of the container.

Link to comment

Join the conversation

You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.

Guest
Reply to this topic...

×   Pasted as rich text.   Restore formatting

  Only 75 emoji are allowed.

×   Your link has been automatically embedded.   Display as a link instead

×   Your previous content has been restored.   Clear editor

×   You cannot paste images directly. Upload or insert images from URL.