So now, I really have a problem...
The backup is still stuck to the "preparing to backup" stage, while history tells me scanning ended twice yesterday and this morning.
I 10/10/22 10:21PM [PRO Online Backup Set] Scanning for files completed in 5.8 hours: 1,246,605 files (2.60TB) found
I 10/11/22 01:04AM Code42 started, version 10.2.1, GUID XXXXXXXXXXXXXXX
I 10/11/22 03:00AM [PRO Online Backup Set] Scanning for files to back up
I 10/11/22 09:15AM [PRO Online Backup Set] Scanning for files completed in 6.2 hours: 1,246,623 files (2.60TB) found
I've browsed the logs and it seems there's errors in engine_error.log, service.log.0 and ui_error.log (see below)
Looks like it's something to do with some Java classes not implemented o_O
I doubt it is related to the UI error, but just in case I added the log to the post.
I don't know if it could have something to do with it, but I'm still using Unraid 6.9 from which I had a notification that it wouldn't be supported anymore. I wanted to back up before upgrading...
But I think it shouldn't be the issue as it doesn't impact the container version, does it ?
Any thought on this ?
Thanks, Kam
/mnt/user/appdata/CrashPlanPRO/log/engine_error.log
WARNING: An illegal reflective access operation has occurred
WARNING: Illegal reflective access by com.code42.crypto.jce.ec.EcCurveLookup (file:/usr/local/crashplan/lib/c42-crypto-impl-15.2.7.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
/mnt/user/appdata/CrashPlanPRO/log/service.log.0
[10.11.22 15:10:29.932 INFO 83686783-129 .storage.UniversalStorageService] SM:: Saved ServiceModel of 48602 bytes to UDB in 2.749 ms.
[10.11.22 15:10:29.938 INFO 83686783-128 e.ui.ws.WebSocketEventController] WS:: Handling request=LoginTokenRequestMessage{token=****}'
[10.11.22 15:10:29.945 INFO 83686783-130 backup42.service.ui.UIController] UI:: UserActionRequest: StatusQueryMessage[1081704676279664543] null
[10.11.22 15:10:29.946 WARN 83686783-130 rvice.ui.message.AppStateMessage] Problem getting app state, 'void org.json.JSONWriter.<init>(java.io.Writer)', java.lang.NoSuchMethodError: 'void org.json.JSONWriter.<init>(java.io.Writer)'
STACKTRACE:: java.lang.NoSuchMethodError: 'void org.json.JSONWriter.<init>(java.io.Writer)'
at com.backup42.common.Computer.toJSONString(Computer.java:1130)
at org.json.JSONWriter.valueToString(JSONWriter.java:331)
at org.json.JSONWriter.value(JSONWriter.java:412)
at com.backup42.service.ui.message.AppStateMessage.toJSONString(AppStateMessage.java:110)
at com.backup42.service.ui.message.AppStateMessage.buildMessage(AppStateMessage.java:46)
at com.backup42.service.ui.UIController.sendAppStateToTray(UIController.java:2483)
at com.backup42.service.ui.UIController.modelChanged(UIController.java:1327)
at com.backup42.service.model.Model.notifyModelChanged(Model.java:65)
at java.base/java.lang.Iterable.forEach(Unknown Source)
at com.backup42.service.model.Model.notifyObservers(Model.java:61)
at com.backup42.service.model.ServiceModel.save(ServiceModel.java:279)
at com.backup42.service.ui.UIController.receiveMessage(UIController.java:869)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.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.backup42.service.ui.http.LegacyHttpHandler.invokeMessageReceiver(LegacyHttpHandler.java:211)
at com.backup42.service.ui.http.LegacyHttpHandler.getResponse(LegacyHttpHandler.java:84)
at com.backup42.service.ui.http.AHttpHandler.service(AHttpHandler.java:65)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:750)
at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:550)
at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
at org.eclipse.jetty.server.Server.handle(Server.java:516)
at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400)
at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645)
at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392)
at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
at org.eclipse.jetty.io.ssl.SslConnection$DecryptedEndPoint.onFillable(SslConnection.java:555)
at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:410)
at org.eclipse.jetty.io.ssl.SslConnection$2.succeeded(SslConnection.java:164)
at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
at java.base/java.lang.Thread.run(Unknown Source)
[10.11.22 15:10:29.948 INFO 83686783-130 .storage.UniversalStorageService] SM:: Saved ServiceModel of 48602 bytes to UDB in 2.014 ms.
[10.11.22 15:10:29.953 INFO 83686783-130 backup42.service.ui.UIController] UI:: UserActionResponse: StatusResponseMessage@595426261[ session=1081704676279664543 ][ User@-493561161[ userId=28247113, userUid=1080552143039004986, name=Camille,
[email protected] ], AuthorityLocation@1205044493[ location=central.crashplanpro.com:4287, addressHidden=true, addressLocked=true ], orgType=BUSINESS, upgrading=false, UpdateLicenseMessage[] [ authenticated=true, updateConfigPassword=false,
[email protected], securityKeyType=AccountPassword, dataKeyExists=true, secureDataKeyExists=true, secureDataKeyUpdateRequired=false, secureDataKeyQA=null, authorizeRules=AuthorizeRules [minPasswordLength=5, usernameIsAnEmail=true, deferredAllowed=true], blocked=false, licensedFeatures=[] ], locale=AUTOMATIC_LOCALE, websiteHost=https://console.us2.crashplanpro.com, defaultRestoreFolder=/config, os=Linux, dtrServiceType=LAN_WAN ]
[10.11.22 15:10:29.973 INFO 83686783-628 zation.CustomizationApiClientCmd] ClientCustomization:: Getting postAuthenticated customizations
[10.11.22 15:10:29.973 INFO 83686783-628 CustomizationAuthorizedClientCmd] ClientCustomization:: Getting post-authentication customizations
[10.11.22 15:10:30.026 INFO 1WeDftWkr465 com.code42.messaging.SessionImpl] **** No receiver assigned for message for type com.code42.protos.shared.authority.PlanAdministrationMessages$DestinationsGetResponse, remoteGuid=4200, RemotePeer-[guid=4200, state=CONNECTED]; Session-[localID=1081697776767025055, remoteID=1081697776684262330, layer=Peer::Sabre, closed=false, expiration=null, remoteIdentity=AUTHORITY, local=172.17.0.2:46348, remote=64.207.222.171:443]
[10.11.22 15:10:59.143 INFO Thread-50 om.backup42.service.AppLogWriter] WRITE app.log in 84ms
[10.11.22 15:14:46.469 INFO DefaultGroup .code42.messaging.peer.PeerGroup] PG::DefaultGroup DONE Managing connected remote peers. numConnected=3, numFailedConnectedCheck=0, duration(ms)=1
[10.11.22 15:15:55.266 INFO 83686783-128 backup42.service.ui.UIController] UI:: UserActionRequest: GetHistoryLogMessage[1081705222092832671]
[10.11.22 15:15:55.267 INFO 83686783-128 backup42.service.ui.UIController] UI:: Retrieve History; file=history.log.0, exists=true, length=1180, path=/usr/local/crashplan/log/history.log.0
[10.11.22 15:15:55.268 INFO 83686783-128 backup42.service.ui.UIController] UI:: UserActionResponse: GetHistoryLogResponseMessage@346261857[ session=1081705222092832671 ]1.2KB
[10.11.22 15:18:27.882 INFO 83686783-628 backup42.service.ui.UIController] UI:: UserActionRequest: GetHistoryLogMessage[1081705478146703263]
[10.11.22 15:18:27.883 INFO 83686783-628 backup42.service.ui.UIController] UI:: Retrieve History; file=history.log.0, exists=true, length=1180, path=/usr/local/crashplan/log/history.log.0
[10.11.22 15:18:27.883 INFO 83686783-628 backup42.service.ui.UIController] UI:: UserActionResponse: GetHistoryLogResponseMessage@812038901[ session=1081705478146703263 ]1.2KB
[10.11.22 15:19:46.470 INFO DefaultGroup .code42.messaging.peer.PeerGroup] PG::DefaultGroup DONE Managing connected remote peers. numConnected=3, numFailedConnectedCheck=0, duration(ms)=0
[10.11.22 15:19:46.688 INFO ogStatsTimer fileactivity.FileActivityHandler] V3E::FileActivityHandlerStats (15min) [totalEvents=0, interestingEvents=0, scanEvents=0, pathsEnqueued=0, sessionsEnqueued=0]
[10.11.22 15:24:46.471 INFO DefaultGroup .code42.messaging.peer.PeerGroup] PG::DefaultGroup DONE Managing connected remote peers. numConnected=3, numFailedConnectedCheck=0, duration(ms)=0
/mnt/user/appdata/CrashPlanPRO/log/ui_error.log
(node:7582) Warning: Accessing non-existent property 'padLevels' of module exports inside circular dependency
(Use `code42 --trace-warnings ...` to show where the warning was created)
[7582:1011/010450.097350:ERROR:bus.cc(392)] Failed to connect to the bus: Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
[7582:1011/010450.098961:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/010450.099056:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[9603:1011/010450.115593:ERROR:gl_implementation.cc(441)] Failed to load /usr/local/crashplan/electron/swiftshader/libGLESv2.so: /usr/local/crashplan/electron/swiftshader/libGLESv2.so: cannot open shared object file: No such file or directory
[9603:1011/010450.117000:ERROR:viz_main_impl.cc(161)] Exiting GPU process due to errors during initialization
[7582:1011/010450.120398:ERROR:bus.cc(392)] Failed to connect to the bus: Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
[7582:1011/010450.120490:ERROR:bus.cc(392)] Failed to connect to the bus: Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
[7582:1011/010450.120609:ERROR:bus.cc(392)] Failed to connect to the bus: Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
[7582:1011/010450.120693:ERROR:bus.cc(392)] Failed to connect to the bus: Failed to connect to socket /var/run/dbus/system_bus_socket: No such file or directory
[9651:1011/010450.121991:ERROR:gpu_init.cc(453)] Passthrough is not supported, GL is disabled, ANGLE is
[7582:1011/010450.134937:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/010450.135762:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/010450.157532:ERROR:object_proxy.cc(642)] Failed to call method: org.freedesktop.login1.Manager.Inhibit: object_path= /org/freedesktop/login1: unknown error type:
[7582:1011/010450.248944:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/010450.257949:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/010450.331674:ERROR:nss_util.cc(286)] After loading Root Certs, loaded==false: libnssckbi.so: cannot open shared object file: No such file or directory
[7582:1011/010450.375934:ERROR:cert_issuer_source_aia.cc(104)] AiaRequest::OnFetchCompleted got error -379
[7582:1011/010450.400278:ERROR:cert_issuer_source_aia.cc(104)] AiaRequest::OnFetchCompleted got error -379
[7582:1011/010450.423969:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/010450.424957:ERROR:cert_issuer_source_aia.cc(104)] AiaRequest::OnFetchCompleted got error -379
[9718:1011/010450.426044:ERROR:ssl_client_socket_impl.cc(981)] handshake failed; returned -1, SSL error code 1, net_error -202
(node:9746) Warning: Accessing non-existent property 'padLevels' of module exports inside circular dependency
(Use `exe --trace-warnings ...` to show where the warning was created)
(node:10451) Warning: Accessing non-existent property 'padLevels' of module exports inside circular dependency
(Use `exe --trace-warnings ...` to show where the warning was created)
[7582:1011/151029.940311:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151029.985027:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151029.991086:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151030.061679:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151030.076541:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151030.100084:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151555.004942:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151555.006902:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
(node:11840) Warning: Accessing non-existent property 'padLevels' of module exports inside circular dependency
(Use `exe --trace-warnings ...` to show where the warning was created)
[7582:1011/151827.625193:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
[7582:1011/151827.627063:ERROR:bus.cc(392)] Failed to connect to the bus: Could not parse server address: Unknown address type (examples of valid types are "tcp" and on UNIX "unix")
(node:12342) Warning: Accessing non-existent property 'padLevels' of module exports inside circular dependency
(Use `exe --trace-warnings ...` to show where the warning was created)