Jump to content

Plastic Client times out on start


gihrig

Recommended Posts

I have a PlasticSCM installation in use for about a year with no recent problems,

until client machines suddenly stopped connecting to the Plastic server. 

I'm not aware of any change on the server machine that might have caused this.

 

The only indication of a server problem, other than clients not connecting, is the 

following OS X console log entry:

 



com.apple.SystemStarter[39] Unable to start PlasticSCM server. Refer to the plastic.server.log file, placed in the plastic server location, for more details. FAILED


 

Server status looks good,

Manually stoping and starting the server seems OK, but does not change the condition.

I have restarted the computer (power cycle cold restart), still no change.

 

Based on the information below, can you suggest the next troubleshooting steps?

 

Thanks and best regards,

 

-Glen

 

OS X 10.6.8 - with recent updates

$ cm version 5.0.44.505  (community edition, two developers, four (?) projects)

DB: SqLite

 

Plastic GUI Client



Connection timed out Plastic client can't continue and will be closed


 

Plastic cli



$ cm li (~30 second delay)
Error: No channel found trying to connect to [dev:8087 (unreachable)]


Plastic server - From cli on the machine hosting the plastic server



$ plasticsd status
PlasticSCM server is started (pid 1466)
$ sudo plasticsd stop
Stopping PlasticSCM server: OK
$ sudo plasticsd start
Starting PlasticSCM server:  OK




$ cm li (~30 second delay)
Error: No channel found trying to connect to [dev:8087 (unreachable)]
$ cm li --server=dev:8087 (~30 second delay)
Error: Connection timed out


OS X Console log:



1/28/14 9:48:40 PM com.apple.SystemStarter[39] Plastic SCM starting up
1/28/14 9:48:40 PM com.apple.SystemStarter[39] Starting PlasticSCM server: 
1/28/14 9:48:41 PM com.apple.SystemStarter[39] /Applications/PlasticSCM/config/mono_setup: line 12: xmodmap: command not found
1/28/14 9:48:41 PM com.apple.SystemStarter[39] /Applications/PlasticSCM/config/mono_setup: line 14: xmodmap: command not found
1/28/14 9:48:41 PM com.apple.SystemStarter[39] /Applications/PlasticSCM/config/mono_setup: line 16: xmodmap: command not found
1/28/14 9:48:41 PM com.apple.SystemStarter[39] /Applications/PlasticSCM/config/mono_setup: line 18: xmodmap: command not found
1/28/14 9:48:44 PM com.apple.SystemStarter[39] Unable to start PlasticSCM server. Refer to the plastic.server.log file, placed in the plastic server location, for more details. FAILED


 

 

Plastic.server.log



2014-01-28 21:48:51,934 e6dfe80e-2a82-4f4b-9003-955e619f81bb root at Server:Dev-Mac-WS.local INFO  Transaction - Transaction timeout -> 120000ms
2014-01-28 21:48:55,636  root at  INFO  Channel - 'sinklevel' compression mode set. all metadata will be serialized
2014-01-28 21:48:55,637  root at  INFO  Channel - 'BufferPoolMax' is set to '10'. It sets the maximum number of available buffers for object data transfers
2014-01-28 21:48:55,637  root at  INFO  Channel - 'SerializationObjectsAtSink' is set to 'True'. True means SerializationBase descendants are  directly written into the response buffer
2014-01-28 21:48:55,679  root at  INFO  Channel - Start listening
2014-01-28 21:48:55,683  root at  INFO  Channel - 'sinklevel' compression mode set. all metadata will be serialized
2014-01-28 21:48:55,683  root at  INFO  Channel - 'BufferPoolMax' is set to '10'. It sets the maximum number of available buffers for object data transfers
2014-01-28 21:48:55,684  root at  INFO  Channel - 'SerializationObjectsAtSink' is set to 'True'. True means SerializationBase descendants are  directly written into the response buffer
2014-01-28 21:48:55,686  root at  INFO  Channel - Using certificate file ssl-certificate.pfx
2014-01-28 21:48:55,870  root at  INFO  Channel - Start listening

Link to comment
Share on other sites

Hi!

 

first thing we should check is if the server is really starting:

 

 

 

1/28/14 9:48:44 PM com.apple.SystemStarter[39] Unable to start PlasticSCM server. Refer to the plastic.server.log file, placed in the plastic server location, for more details. FAILED

 

That message seems to indicate that it's not starting properly. So let's go.

 

 

1) "plasticsd --console" will start the server in console mode, kill the server before running it and launch the console as root. You will see more info from the console output.

2) The log file "plastic.server.log" can give us more info about the problem, please post it to us.

 

 

The client error points to two different options:

1) The server is started but the network connection is not established... bad gateway.... port filters.... anti-virus....

2) The server is not really running so nobody is listening on the port.

Link to comment
Share on other sites

The server is not starting correctly even though status suggests otherwise.

$ sudo plasticsd stop
Stopping PlasticSCM server: OK
$ sudo plasticsd start --console      
Starting PlasticSCM server: Unable to start PlasticSCM server. Refer to the plastic.server.log file, placed in the plastic server location, for more details. FAILED
$ sudo plasticsd start          
PlasticSCM server is already started FAILED
$ sudo plasticsd status
PlasticSCM server is started (pid 4683)
plastic.server.log zipped and attached

plastic.server.log.zip

Link to comment
Share on other sites

Here's a short session covering the above command. It looks normal as far as I can tell. Attempting to start Plastic GUI still fails with a timeout message, as before.
$ sudo plasticsd start 
Starting PlasticSCM server:  OK
$ sudo plasticsd status
PlasticSCM server is started (pid 56365)
$ cm lrep localhost:8087
 7 iCrumz localhost:8087
 8 microscpe localhost:8087
 9 iCrumz.Meteor localhost:8087
 10 Yeoman-Marionette-demo localhost:8087
 12 Marionette-Gentle localhost:8087
 13 iCrumz.js localhost:8087
 14 iCrumz.yo-bb.js localhost:8087
 15 SPA-project localhost:8087
 16 iCrumz.native.SPA.js localhost:8087
$ 
$ sudo plasticsd stop  
Stopping PlasticSCM server: OK
$ sudo plasticsd start --console
Starting PlasticSCM server:  OK
$

What's different here is that the 'plasticsd start --console' command now results in an OK message, where previously is returned a FAILED message (above).

 

Here's the server log covering the above console activity:

2014-02-05 21:31:46,512 99f33c6d-2ad9-40f5-9436-556a70acf6f5 root at Server:Dev-Mac-WS.local INFO  Transaction - Transaction timeout -> 120000ms
2014-02-05 21:31:47,846  root at  INFO  Channel - 'sinklevel' compression mode set. all metadata will be serialized
2014-02-05 21:31:47,846  root at  INFO  Channel - 'BufferPoolMax' is set to '10'. It sets the maximum number of available buffers for object data transfers
2014-02-05 21:31:47,846  root at  INFO  Channel - 'SerializationObjectsAtSink' is set to 'True'. True means SerializationBase descendants are  directly written into the response buffer
2014-02-05 21:31:47,891  root at  INFO  Channel - Start listening
2014-02-05 21:31:47,895  root at  INFO  Channel - 'sinklevel' compression mode set. all metadata will be serialized
2014-02-05 21:31:47,895  root at  INFO  Channel - 'BufferPoolMax' is set to '10'. It sets the maximum number of available buffers for object data transfers
2014-02-05 21:31:47,895  root at  INFO  Channel - 'SerializationObjectsAtSink' is set to 'True'. True means SerializationBase descendants are  directly written into the response buffer
2014-02-05 21:31:47,897  root at  INFO  Channel - Using certificate file ssl-certificate.pfx
2014-02-05 21:31:48,060  root at  INFO  Channel - Start listening
2014-02-05 21:31:58,531 (null) root at (null) INFO  Channel - ThreadPool MaxThreads = 8
2014-02-05 21:31:58,531 (null) root at (null) INFO  Channel - ThreadPool MinThreads = 2
2014-02-05 21:31:58,577 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Operations - Retrieve repository list
2014-02-05 21:31:58,731 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 16. 1 ms
2014-02-05 21:31:58,732 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 16. 1 ms
2014-02-05 21:31:58,757 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 16. 25 ms
2014-02-05 21:31:58,759 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 16. 3 ms
2014-02-05 21:31:58,766 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 15. 0 ms
2014-02-05 21:31:58,767 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 15. 0 ms
2014-02-05 21:31:58,773 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 15. 6 ms
2014-02-05 21:31:58,773 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 15. 0 ms
2014-02-05 21:31:58,773 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 14. 1 ms
2014-02-05 21:31:58,773 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 14. 0 ms
2014-02-05 21:31:58,783 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 14. 9 ms
2014-02-05 21:31:58,783 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 14. 0 ms
2014-02-05 21:31:58,784 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 13. 0 ms
2014-02-05 21:31:58,784 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 13. 0 ms
2014-02-05 21:31:58,784 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 13. 0 ms
2014-02-05 21:31:58,784 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 13. 0 ms
2014-02-05 21:31:58,784 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 12. 0 ms
2014-02-05 21:31:58,784 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 12. 1 ms
2014-02-05 21:31:58,809 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 12. 24 ms
2014-02-05 21:31:58,809 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 12. 0 ms
2014-02-05 21:31:58,810 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 10. 0 ms
2014-02-05 21:31:58,810 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 10. 0 ms
2014-02-05 21:31:58,817 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 10. 7 ms
2014-02-05 21:31:58,817 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 10. 0 ms
2014-02-05 21:31:58,818 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 9. 0 ms
2014-02-05 21:31:58,818 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 9. 0 ms
2014-02-05 21:31:58,838 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 9. 21 ms
2014-02-05 21:31:58,838 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 9. 0 ms
2014-02-05 21:31:58,839 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 8. 0 ms
2014-02-05 21:31:58,839 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 8. 0 ms
2014-02-05 21:31:58,856 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 8. 18 ms
2014-02-05 21:31:58,874 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 8. 0 ms
2014-02-05 21:31:58,875 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acls for repository 7. 0 ms
2014-02-05 21:31:58,876 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl entries for repository 7. 0 ms
2014-02-05 21:31:58,885 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader reading all acl inheritance entries for repository 7. 9 ms
2014-02-05 21:31:58,886 00000000-0000-0000-0000-000000000000 root at Dev-Mac-WS.local INFO  Security - AllAclReader updating ACL cache for repository 7. 1 ms
2014-02-05 21:31:58,961  root at  INFO  ChannelCall - recb:   622|rect:  1|sentb:   958|sendt:  3|prt:     420|th:    2|dest:   0|mt:     347|sert:  19|zip:  18|       127.0.0.1|GetRepositoryList
2014-02-05 21:31:59,040 (null) root at (null) INFO  Channel - Tcp transport error. ReceiveMessageStatus Connection closed
2014-02-05 21:31:59,046 (null) root at (null) INFO  Channel - conn        0 Tcp transport error. Connection closed
2014-02-05 21:33:00,565 5b18976c-6d73-4cb0-bdf5-99d56f4eb77c root at Server:Dev-Mac-WS.local INFO  Transaction - Transaction timeout -> 120000ms

It looks like the 'cm lrep localhost:8087' command completed successfully, followed by 'Tcp transport errors' and connection closed. That was at 21:31:59 in the log. The attempt to start the Plastic GUI client is shown on the last line, at 21:33:00. I'm not sure what to think of this entry, but it doesn't look like an error.

 

---

 

To resolve the problem, how about if I just remove and reinstall? That's really not too hard and may be the shortest path to a working system.

 

My only concern about that is preserving my repository data.

 

Is it correct that all I need do is save the PlasticSCM/server/*.sqlite files and then copy them into the new installation?

 

Assuming the above is correct, and that I then want to convert to a MySql database is this page, the correct one to follow for a Mac installation?

 

http://www.plasticscm.com/infocenter/technical-articles/kb-how-to-configure-plastic-scm-with-mysql.aspx#

 

 

 

Link to comment
Share on other sites

Ok, so now we know the server is working fine and the cm client works too.

 

The issue must be only with the GUI. I don't this a re-installation will fix it, seems something regarding the configuration and config files are not removed at the uninstall.

 

First think to check -> run a plastic --configure and verify everything is correct.

Then try to open it again. If it fails we'll check the following.

Inside your home directory "/home/<user_name>/.plastic" your find a "profiles.conf" file, remove it and open the GUI.

You can also try removing more configuration files to check if the problem is with one of them, the candidates are:

guiclient.conf

guivisualstate.xml

jiraextension.conf (or any extension you can find)

mainform.conf

queries.conf

syncviews.conf

 

Tell me if it helps.

Link to comment
Share on other sites

I have resolved the problem. It was not related to Plastic!

 

After several hours of troubleshooting, including restoring older backups of ~/.plastic4 and /Applications/PlasticSCM folders, a final check to confirm the cli client and server still worked...

cm lrep localhost:8087 still shows all repos, but... 
cm lrep dev:8087 timed out! (a DNS problem?)
cm lrep 192.168.105:8087 timed out !!!

$ ping dev
PING dev (192.168.1.150) ...

.150!!! should have been .105
I turns out that the network configuration had been changed from "Using DHCP with manual address" to "using DHCP", with the result that the machine's IP had changed! But the local DNS had not caught the changed IP. After correcting that, Plastic is working again!
 
Thanks for all your help and suggestions, and sorry to pester you with a non-Plastic problem!
 
Best regards,
 
-Glen
Link to comment
Share on other sites

Archived

This topic is now archived and is closed to further replies.

×
×
  • Create New...