jademonkee Posted May 27, 2022 Share Posted May 27, 2022 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. 1 Quote Link to comment
jfeeser Posted May 29, 2022 Share Posted May 29, 2022 On 5/24/2022 at 2:46 PM, Djoss said: It still tries to update? What CP version the UI is showing ? Sorry just getting back to this. UI is showing 8.7.1.32. Quote Link to comment
Djoss Posted May 30, 2022 Author Share Posted May 30, 2022 21 hours ago, jfeeser said: Sorry just getting back to this. UI is showing 8.7.1.32. Ok, this is not the latest version. You need to update the Docker container image. Quote Link to comment
NeoDude Posted June 1, 2022 Share Posted June 1, 2022 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? Quote Link to comment
Djoss Posted June 2, 2022 Author Share Posted June 2, 2022 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. Quote Link to comment
NeoDude Posted June 2, 2022 Share Posted June 2, 2022 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 Quote Link to comment
Djoss Posted June 6, 2022 Author Share Posted June 6, 2022 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. Quote Link to comment
NeoDude Posted June 8, 2022 Share Posted June 8, 2022 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" 😕 Quote Link to comment
Djoss Posted June 10, 2022 Author Share Posted June 10, 2022 On 6/8/2022 at 5:10 AM, NeoDude said: 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" 😕 What are the errors now in service.log.0 ? Quote Link to comment
NeoDude Posted June 13, 2022 Share Posted June 13, 2022 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] Quote Link to comment
Djoss Posted June 13, 2022 Author Share Posted June 13, 2022 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 Quote Link to comment
NeoDude Posted June 13, 2022 Share Posted June 13, 2022 (edited) 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 June 13, 2022 by NeoDude Quote Link to comment
Djoss Posted June 13, 2022 Author Share Posted June 13, 2022 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 ? Quote Link to comment
NeoDude Posted June 13, 2022 Share Posted June 13, 2022 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 Quote Link to comment
Gnomuz Posted July 15, 2022 Share Posted July 15, 2022 Hi, It seems a new version is available and an update of the docker image is required. Thanks in advance. Quote Link to comment
Djoss Posted July 15, 2022 Author Share Posted July 15, 2022 7 hours ago, Gnomuz said: Hi, It seems a new version is available and an update of the docker image is required. Thanks in advance. New image available! 3 Quote Link to comment
Lidde Posted September 3, 2022 Share Posted September 3, 2022 (edited) [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 September 4, 2022 by Lidde Quote Link to comment
Hawkins12 Posted September 27, 2022 Share Posted September 27, 2022 (edited) 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: 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 September 27, 2022 by Hawkins12 Quote Link to comment
Djoss Posted September 28, 2022 Author Share Posted September 28, 2022 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: 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. Quote Link to comment
Hawkins12 Posted September 28, 2022 Share Posted September 28, 2022 (edited) 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: 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 September 28, 2022 by Hawkins12 Quote Link to comment
Djoss Posted September 28, 2022 Author Share Posted September 28, 2022 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: 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 ? Quote Link to comment
Hawkins12 Posted September 28, 2022 Share Posted September 28, 2022 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) Quote Link to comment
Djoss Posted September 28, 2022 Author Share Posted September 28, 2022 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. Quote Link to comment
Hawkins12 Posted September 28, 2022 Share Posted September 28, 2022 (edited) 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 September 28, 2022 by Hawkins12 Quote Link to comment
Djoss Posted September 28, 2022 Author Share Posted September 28, 2022 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. Quote Link to comment
Recommended Posts
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.