Solved: srcp bug - 5300 OK

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Solved: srcp bug - 5300 OK

Post by Richard-TX » 07.06.2013, 15:16

I was running 5300 and there are no problems with that.

I upgraded to 5397 and when I start rocrail, messages scroll by at an insane rate.

Ultimately it crashes srcpd. I see rocrail is stuck in a loop.

here is an excerpt



.
.
.
102 INFO 1 LOCK GA 466 0
102 INFO 1 LOCK GA 467 0
102 INFO 1 LOCK GA 468 0
.
.
then srcpd crashes and rocrail says:

Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
.
.
.
.


I monitored the srcpd info channel and saw this:

1370612863.527 200 OK GO 4
1370612845.921 100 INFO 0 DESCRIPTION SESSION SERVER TIME GM
1370612845.531 101 INFO 0 TIME 0 0
1370612845.930 100 INFO 1 DESCRIPTION GA GL FB SM POWER LOCK DESCRIPTION
1370612845.930 100 INFO 1 POWER ON AUTO POWER ON
1370612848.485 101 INFO 1 GA 40 P
1370612848.485 100 INFO 1 GA 40 1 1
1370612845.930 100 INFO 2 DESCRIPTION GA GL FB SM POWER LOCK DESCRIPTION
1370612845.930 100 INFO 2 POWER ON AUTO POWER ON
1370612847.280 100 INFO 2 FB 1 1
1370612847.290 100 INFO 2 FB 8 1
1370612847.296 100 INFO 2 FB 12 1
1370612847.297 100 INFO 2 FB 13 1
1370612847.300 100 INFO 2 FB 15 1
1370612845.945 100 INFO 3 DESCRIPTION GA GL FB SM POWER LOCK DESCRIPTION
1370612845.945 100 INFO 3 POWER ON AUTO POWER ON
1370612845.947 100 INFO 4 DESCRIPTION GA GL FB SM POWER LOCK DESCRIPTION
1370612845.947 100 INFO 4 POWER ON AUTO POWER ON
1370612874.176 101 INFO 0 SESSION 5 INFO
1370612874.639 102 INFO 1 LOCK GA 1 0
1370612874.641 102 INFO 1 LOCK GA 2 0
1370612874.641 102 INFO 1 LOCK GA 3 0
1370612874.643 102 INFO 1 LOCK GA 4 0
1370612874.643 102 INFO 1 LOCK GA 5 0
1370612874.643 102 INFO 1 LOCK GA 6 0
1370612874.643 102 INFO 1 LOCK GA 7 0
1370612874.643 102 INFO 1 LOCK GA 8 0
1370612874.651 102 INFO 1 LOCK GA 9 0
1370612874.651 102 INFO 1 LOCK GA 10 0
1370612874.651 102 INFO 1 LOCK GA 11 0
.
.
.
.
.


Rob, if you need more data, let me know. The rocrail trace isn't too informative because it fills up (exceeds file size limit) so quickly.

Richard
Attachments
rocrail.ini
(2.56 KiB) Downloaded 85 times
plan.xml
(34.25 KiB) Downloaded 86 times
rocrail.002.zip
(3.79 KiB) Downloaded 83 times
Last edited by Richard-TX on 09.06.2013, 08:44, edited 1 time in total.

rjversluis
Site Admin
Posts: 39295
Joined: 10.04.2006, 08:48
Location: Speyer, Germany
Contact:

Post by rjversluis » 07.06.2013, 15:30

fixed in 5393.

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 07.06.2013, 15:35

Fantastic!!!!


Thanks Rob!

rjversluis
Site Admin
Posts: 39295
Joined: 10.04.2006, 08:48
Location: Speyer, Germany
Contact:

Post by rjversluis » 07.06.2013, 15:38

SRCP became two sub libraries: TCP and Serial.
So I touched a lot of things.
The serial connection support is for an Arduino project.


http://wiki.rocrail.net/doku.php?id=srcp-en
http://forum.rocrail.net/viewtopic.php?t=5930

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 07.06.2013, 23:26

I was wondering why anyone would use srcpd over serial lines. It would not be my choice.

Here is an interesting fact.

There are actually 3 I/O buses on the Raspberry Pi. One SPI bus and two I2C buses. With two I2c buses, that means that there could be almost 2000 ports that the Rpi can control.

Imagine 1,984 servos controlled by a single Rpi.

One can control an Arduino from a Raspberry using the SPI bus.

On my project I wil going to have one I2C bus controlling the switch servos and the other I2c bus controlling the lights.

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 08.06.2013, 05:13

Sorry Rob, not fixed.

1370612874.639 102 INFO 1 LOCK GA 1 0
1370612874.641 102 INFO 1 LOCK GA 2 0
1370612874.641 102 INFO 1 LOCK GA 3 0
1370612874.643 102 INFO 1 LOCK GA 4 0
1370612874.643 102 INFO 1 LOCK GA 5 0
1370612874.643 102 INFO 1 LOCK GA 6 0
.
.
.
.

rjversluis
Site Admin
Posts: 39295
Joined: 10.04.2006, 08:48
Location: Speyer, Germany
Contact:

Post by rjversluis » 08.06.2013, 06:07

What is wrong with those commands?
Its INFO.

I only fixed this:

Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
Other side has closed connection.
.
.
.


BTW: You forgot to attach traces.

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 08.06.2013, 13:25

here it is
Attachments
rocview.007.zip
(2.33 KiB) Downloaded 83 times

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 08.06.2013, 14:03

I finally got a trace that may be meaningful.

It was not easy. Grabbing the beginning of something that is generating 500 messages a second is a challenge.
Attachments
rocrail.008.zip
(4.83 KiB) Downloaded 86 times

rjversluis
Site Admin
Posts: 39295
Joined: 10.04.2006, 08:48
Location: Speyer, Germany
Contact:

Post by rjversluis » 08.06.2013, 14:14

I still do not get your point.
Which lines are offending?

BTW: Debug trace level should be switch off if you post trace files.

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 08.06.2013, 14:24

What is happening is that the lock messages are out of control.


In just a few seconds I am up to:


.
.
.

foreader: [1370697792.358 102 INFO 1 LOCK GA 1619 0 ]
inforeader: [1370697792.358 102 INFO 1 LOCK GA 1620 0 ]
inforeader: [1370697792.358 102 INFO 1 LOCK GA 1621 0 ]
inforeader: [1370697792.358 102 INFO 1 LOCK GA 1622 0 ]
inforeader: [1370697792.358 102 INFO 1 LOCK GA 1623 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1624 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1625 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1626 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1627 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1628 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1629 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1630 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1631 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1632 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1633 0 ]
inforeader: [1370697792.359 102 INFO 1 LOCK GA 1634 0 ]
.
.
.
.

rjversluis
Site Admin
Posts: 39295
Joined: 10.04.2006, 08:48
Location: Speyer, Germany
Contact:

Post by rjversluis » 08.06.2013, 14:28

where do these LOCK message come from? SRCPD?
Rocrail does not generate those lines.

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 08.06.2013, 14:31

rjversluis wrote:I still do not get your point.
Which lines are offending?

BTW: Debug trace level should be switch off if you post trace files.
Debug trace level is grayed out Rob. I have trace level "info" checked.

Richard-TX
Posts: 1320
Joined: 10.07.2012, 04:00
Location: Texas, USA
Contact:

Post by Richard-TX » 08.06.2013, 14:34

rjversluis wrote:where do these LOCK message come from? SRCPD?
Rocrail does not generate those lines.
Give me a few minutes for me find out what is really going on.

rjversluis
Site Admin
Posts: 39295
Joined: 10.04.2006, 08:48
Location: Speyer, Germany
Contact:

Post by rjversluis » 08.06.2013, 14:35

what triggers your srcpd to send "INFO 1 LOCK GA" ?

[EDIT]
I'm using the original srcpd and it does not send your LOCK lines...

Post Reply

Return to “srcp”