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

cromwell · May 27

I got this error when trying to log in to container today. not sure if it's related to the above mentioned issue, but I increased inotify to 750000 from about 524000. hopefully that helps...

jademonkee · May 27

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.

jfeeser · May 29

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.

Djoss · May 30

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.

NeoDude · June 1

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?

Djoss · June 2

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.

NeoDude · June 2

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

Djoss · June 6

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.

NeoDude · June 8

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"

😕

Djoss · June 10

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 ?

NeoDude · June 13

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]

Djoss · June 13

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

NeoDude · June 13

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 by NeoDude

Djoss · June 13

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 ?

NeoDude · June 13

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

Gnomuz · July 15

Hi,
It seems a new version is available and an update of the docker image is required.
Thanks in advance.

Djoss · July 15

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!

Sign In

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

Recommended Posts

Link to comment

Top Posters In This Topic

Popular Days

Top Posters In This Topic

Popular Days

Popular Posts

Djoss

Djoss

Djoss

Posted Images

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Link to comment

Join the conversation