Unity VCS would randomly not connect to the servers after startup and then reconnect at some random time later like 1-2 later. This has been happening over the last week or so. Any suggestions?
Are there any clues as to why this is happening in the log files when the connection is lost?
Windows: %localappdata%\plastic4\plasticx.debug.log
Mac: ~/.plastic4/plasticx.debug.log
Hope this helps!
Hello,
Here’s a snippet of the log below. I want to note that I am in an endless loop of it wanting to sign us in, we click sign in, it says sign in successful then 10 seconds later it keeps saying the same thing
2023-07-25 18:56:36,132 LAPTOP-DN081AEL\Alina DEBUG CmProxy - InvokeRemoteMethod failed. Server = xxxxxx@cloud. Error = The auth token expired. User: xxxxxxx@gmail.com.. retryOn = Authentication, HostUnreachable, ServerCertificate
2023-07-25 18:56:36,132 LAPTOP-DN081AEL\Alina DEBUG CmProxy - InvokeRemoteMethod. Token Expired for server:xxxxxx@cloud. user:xxxxx@gmail.com.
2023-07-25 18:56:36,137 LAPTOP-xxxxxx\Alina DEBUG WatcherFsNodeReader - FsWatcher. Event path:'c:\Users\Alina\Fitment\Library\PackageCache' type:Changed
2023-07-25 18:56:36,138 LAPTOP-xxxxxx\Alina DEBUG WatcherFsNodeReader - Watcher 'c:\Users\Alina\Fitment' - Processing change Changed for c:\Users\Alina\xxxxxx\Library\PackageCache
2023-07-25 18:56:36,138 LAPTOP-xxxxxx\Alina DEBUG WatcherFsNodeReader - Watcher: 'c:\Users\Alina\Fitment'. Cleaned '\Library' cached entries.
2023-07-25 18:56:36,138 LAPTOP-xxxxxx\Alina DEBUG WatcherFsNodeReader - FsWatcher 'c:\Users\Alina\Fitment'. Speed: 1 events/s
2023-07-25 18:56:36,148 LAPTOP-DN081AEL\Alina DEBUG ChannelCall - protocol:plasticproto sec:ssl sentb: 39|sendt: 0|recb: 464|rect: 86|prt: 86|sert: 0|dest: 0|zip: 0|xxxxxx@cloud|GetRepositoryInfoByName
2023-07-25 18:56:36,148 LAPTOP-DN081AEL\Alina DEBUG InvokeMethodRetry - HasToHandleSocketException. Exception is not a SocketException: The auth token expired. User:xxxxxxxxxxxxxxxxxx@gmail.com.. Aborting retry
2023-07-25 18:56:36,148 LAPTOP-DN081AEL\Alina DEBUG PlasticPipe - prt: 79|GetRepositoryInfoByName |xxxxxx@cloud
2023-07-25 18:56:36,149 LAPTOP-DN081AEL\Alina DEBUG CmProxy - Error invoking GetRepositoryInfoByName. The auth token expired. User:xxxxxx@gmail.com..
at PlasticPipe.PlasticProtocol.Client.ClientTransport.SendAndWait(PlasticServer plasticServer, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response, Boolean bRetryWriteMethod, FlexibleBufferPool lz4BufferPool, INegotiateActiveDirectory negotiateActiveDirectory, Int32 connTimeoutMsec, IResolveCloudServer resolveCloudServer)
at PlasticPipe.PlasticProtocol.Client.ClientTransport.SendAndWaitWithRetry(PlasticServer server, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response, FlexibleBufferPool lz4BufferPool, INegotiateActiveDirectory negotiateActiveDirectory, Int32 connTimeoutMsec, IResolveCloudServer resolveCloudServer)
at PlasticPipe.PlasticProtocol.Client.ClientTransport.InvokeMethod(PlasticServer server, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response)
at Codice.Client.Common.Connection.PlasticProtocolCmProxy.PlasticProtocolMethodInvoker.DoInvokeMethod(PlasticServer server, Credentials creds, Exception& ex)
2023-07-25 18:56:36,149 LAPTOPxxxxxx\Alina DEBUG CmProxy - InvokeRemoteMethod failed. Server = xxxxxx@cloud. Error = The auth token expired. User: xxxxxx@gmail.com.. retryOn = Authentication, HostUnreachable, ServerCertificate
2023-07-25 18:56:36,149 LAPTOP-DN081AEL\Alina DEBUG CmProxy - InvokeRemoteMethod. Token Expired for server:xxxxxx@cloud. user:xxxxxx@gmail.com.
2023-07-25 18:56:36,171 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - Process changes: 219
2023-07-25 18:56:36,171 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - -------ProcessChanges:--------
2023-07-25 18:56:36,172 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerProcessChange 0 ms, 42 times
2023-07-25 18:56:36,172 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerIsBinaryPath 0 ms, 40 times
2023-07-25 18:56:36,172 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerIsBinaryTypePredictor 0 ms, 0 times
2023-07-25 18:56:36,172 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerGetChangeTypes 16 ms, 18298 times
2023-07-25 18:56:36,172 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerGetIgnoredTypes 16 ms, 498 times
2023-07-25 18:56:36,173 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerGetCloakedTypes 0 ms, 0 times
2023-07-25 18:56:36,173 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerCheckChanged 0 ms, 0 times
2023-07-25 18:56:36,173 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerChangedCalcHash 0 ms, 0 times
2023-07-25 18:56:36,173 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerGetHiddenTypes 0 ms, 2 times
2023-07-25 18:56:36,173 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - timerGetFsNodes 187 ms, 1395 times
2023-07-25 18:56:36,173 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - GettingChanges: 219
2023-07-25 18:56:36,174 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - ProcessingMoved: 0 (downloading match candidates: 0, match overhead: 0)
2023-07-25 18:56:36,175 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus: DiskChangesSearcher - SplitModifiedMovedChanges: 0
2023-07-25 18:56:36,175 LAPTOP-xxxxxx\Alina DEBUG WorkspaceStatus - Search changes time - Total: 219 ms. Disk: 219 ms. Network: 0 ms. Overhead: 0 ms.
2023-07-25 18:56:36,175 LAPTOP-xxxxxx\Alina INFO GetStatus - Time to retrieve the pending changes: 219 ms (searching: 219 ms, processingmoved: 0 (downloading: 0 ms, matching: 0 ms), splitting: 0 ms)
2023-07-25 18:56:36,179 LAPTOP-xxxxxx\Alina DEBUG PendingChangesArea - PendingChangesViewTree.Selection_SelectionChanged invoked
2023-07-25 18:56:36,181 LAPTOP-xxxxxx\Alina DEBUG GlobalConfig - Getting the repository spec for workspacepath 'c:\Users\Alina\xxxxxx'
2023-07-25 18:56:36,182 LAPTOP-xxxxxx\Alina DEBUG GlobalConfig - Getting the server config for repository spec 'xxxxxx@xxxxxx@cloud', folder 'externaltools', config file 'externaltools.conf'
2023-07-25 18:56:36,183 LAPTOP-DN081AEL\Alina DEBUG GlobalConfig - Server config for repository spec 'xxxxxx@xxxxxx@cloud', folder 'externaltools', config file 'externaltools.conf': none
2023-07-25 18:56:36,255 LAPTOP-DN081AEL\Alina DEBUG ChannelCall - protocol:plasticproto sec:ssl sentb: 344|sendt: 0|recb: 1402|rect: 122|prt: 122|sert: 0|dest: 0|zip: 0|PlayFitment@cloud|GetSeid
2023-07-25 18:56:36,255 LAPTOP-DN081AEL\Alina DEBUG InvokeMethodRetry - HasToHandleSocketException. Exception is not a SocketException: Can't obtain a new token (Message: Invalid Refresh Token., Code: 132.104). Aborting retry
2023-07-25 18:56:36,255 LAPTOP-xxxxxx\Alina DEBUG PlasticPipe - prt: 125|GetSeid |xxxxxx@cloud
2023-07-25 18:56:36,256 LAPTOP-xxxxxx\Alina DEBUG CmProxy - Error invoking GetSeid. Can't obtain a new token (Message: Invalid Refresh Token., Code: 132.104).
at PlasticPipe.PlasticProtocol.Client.ClientTransport.SendAndWait(PlasticServer plasticServer, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response, Boolean bRetryWriteMethod, FlexibleBufferPool lz4BufferPool, INegotiateActiveDirectory negotiateActiveDirectory, Int32 connTimeoutMsec, IResolveCloudServer resolveCloudServer)
at PlasticPipe.PlasticProtocol.Client.ClientTransport.SendAndWaitWithRetry(PlasticServer server, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response, FlexibleBufferPool lz4BufferPool, INegotiateActiveDirectory negotiateActiveDirectory, Int32 connTimeoutMsec, IResolveCloudServer resolveCloudServer)
at PlasticPipe.PlasticProtocol.Client.ClientTransport.InvokeMethod(PlasticServer server, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response)
at Codice.Client.Common.Connection.PlasticProtocolCmProxy.PlasticProtocolMethodInvoker.DoInvokeMethod(PlasticServer server, Credentials creds, Exception& ex)
2023-07-25 18:56:36,256 LAPTOP-xxxxxx\Alina DEBUG CmProxy - InvokeRemoteMethod failed. Server = xxxxxx@cloud. Error = Can't obtain a new token (Message: Invalid Refresh Token., Code: 132.104). retryOn = HostUnreachable
2023-07-25 18:56:36,256 LAPTOP-DN081AEL\Alina DEBUG CmConnection - Error trying to renew token creds for xxxxxxxxxxxx@gmail.com. Can't obtain a new token (Message: Invalid Refresh Token., Code: 132.104)
2023-07-25 18:56:36,256 LAPTOP-DN081AEL\Alina DEBUG CmProxy - AskUserForCredentials. From recently asked. Server: xxxxxx@cloud. User: alina@xxxxxxxx.com
2023-07-25 18:56:36,256 LAPTOP-DN081AEL\Alina INFO ServerProfile - Temporary profile [xxxxxx@cloud_SSOWorkingMode_ef6b69a6-b143-472c-8d70-20b3d7a5f52e] to connect to [xxxxxx@cloud] was saved
2023-07-25 18:56:36,256 LAPTOP-DN081AEL\Alina INFO CmProxy - Invoking again the remote method with new credentials: [xxxxxx@gmail.com]
2023-07-25 18:56:36,281 LAPTOP-DN081AEL\Alina DEBUG ChannelCall - protocol:plasticproto sec:ssl sentb: 39|sendt: 0|recb: 464|rect: 96|prt: 96|sert: 0|dest: 0|zip: 0|xxxxxx@cloud|GetLockRule
2023-07-25 18:56:36,282 LAPTOP-DN081AEL\Alina DEBUG InvokeMethodRetry - HasToHandleSocketException. Exception is not a SocketException: The auth token expired. User: xxxxxx@gmail.com.. Aborting retry
2023-07-25 18:56:36,282 LAPTOP-xxxxxx\Alina DEBUG PlasticPipe - prt: 94|GetLockRule |xxxxxx@cloud
2023-07-25 18:56:36,282 LAPTOP-xxxxxx\Alina DEBUG CmProxy - Error invoking GetLockRule. The auth token expired. User: xxxxxx@gmail.com..
at PlasticPipe.PlasticProtocol.Client.ClientTransport.SendAndWait(PlasticServer plasticServer, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response, Boolean bRetryWriteMethod, FlexibleBufferPool lz4BufferPool, INegotiateActiveDirectory negotiateActiveDirectory, Int32 connTimeoutMsec, IResolveCloudServer resolveCloudServer)
at PlasticPipe.PlasticProtocol.Client.ClientTransport.SendAndWaitWithRetry(PlasticServer server, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response, FlexibleBufferPool lz4BufferPool, INegotiateActiveDirectory negotiateActiveDirectory, Int32 connTimeoutMsec, IResolveCloudServer resolveCloudServer)
at PlasticPipe.PlasticProtocol.Client.ClientTransport.InvokeMethod(PlasticServer server, PlasticMethods method, Credentials creds, INetworkMessage msg, INetworkMessage response)
at Codice.Client.Common.Connection.PlasticProtocolCmProxy.PlasticProtocolMethodInvoker.DoInvokeMethod(PlasticServer server, Credentials creds, Exception& ex)
Ahhh the auth token is expiring. I suspect that you have some invalid or outdated values in your profiles.conf and tokens.conf files. These files can be safely deleted, forcing re-authentication when you next start the application.
If this information still doesn’t resolve the problem. I would suggest logging a ticket with support so that we can take a deeper look into this.
Same issue here: since the use of UnityId, Plastic is unable to handle multi account. When switching account and connecting via Unity ID on web browser, same errors in the logs. When deleting profiles.conf and tokens.conf then retrying : the client is stuck on the SignIn popup
Cannot acces to my projects, give us back Plastic SCM WITHOUT UnityID ! It simply does not work, workdays lost
Does the issue still occur if you rename the entire plastic4 folder to something like plastic4_backup? Please log a support ticket with us and we will be happy to help out.
This solved my issue. I had changed username/email log-in and was getting infinite log-in pop-ups in my browser. Felt like I browsed into the wrong website back in the 90s.