This is just doing my head in... So high on the success of blocks 37, 7, 8 & 43 now working, I started wiring up the rest of the station. I have removed anything not configured, and made sure all points and sensors respond correctly and match up on the CS3 and in Rocrail.
Both basic and extended plan health check run clean now. I have tried using the router but now trying to setup routes manually.
What is just so odd is that router or manual, 44 to 4 always works, 44 to anything else (or back) doesn't. Even odder, dragging a loco from 44 to say, 47, it runs the loco to 4 and back to 44, then sets all the switches to go to 5 and changes direction on the loco, and then nothing happens! Ever...
I've spent a week on this... I'm pulling my hair out and just don't get how this software ever became to popular?!
Links to plan, ini and occ files here:
http://www.morsing.cc/Trains/plan.xmlhttp://www.morsing.cc/Trains/occ.xmlhttp://www.morsing.cc/Trains/rocrail.iniTrace from loco deciding where to go but not going is below here. I'm hoping someone can help figure out what I am doing wrong.
20200323.121522.121 r9999I MY1121 OLoc 2347 placing for [MY1121] set to [FWD]
20200323.121522.121 r9999a MY1121 OModel 7500 occ: set loco [MY1121] in block [bk44]
20200323.121522.125 r9999I MY1121 OModel 7577 writing occupancy file [occ.xml]
20200323.121522.180 r9999I MY1121 OLoc 6383 set block[-] enterside for [MY1121] to [+], placing to [fwd]
20200323.121522.180 r9999a MY1121 OModel 7500 occ: set loco [MY1121] in block [bk44]
20200323.121522.184 r9999I MY1121 OModel 7577 writing occupancy file [occ.xml]
20200323.121527.850 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121527.850 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121527.850 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121532.303 r9999a MY1121 OLoc 4171 Loco [MY1121] mode=idle prevmode=wait reason= waittime=0
20200323.121532.304 r9999a 1450A700 OLcDrive 0064 Setting state for "MY1121" from LC_TIMER to LC_IDLE. timer=0 run=true
20200323.121532.304 r4201a 1450A700 OLcDrive 0068 next1Block for [MY1121] is [bk44]
20200323.121532.405 r9999a MY1121 OBlock 2067 block [bk44]: use random wait time 7
20200323.121532.405 r9999a MY1121 OModel 5851 try to find location for block [bk44]
20200323.121532.405 r9999a MY1121 OLoc 4171 Loco [MY1121] mode=auto prevmode=idle reason= waittime=0
20200323.121532.406 r4201a 1450A700 OLcDrive 0195 Setting state for "MY1121" from LC_IDLE to LC_FINDDEST.
20200323.121532.406 r4201a 1450A700 OLcDrive 0198 Finding destination for "MY1121", current block "bk44"...
20200323.121532.506 r2012a MY1121 OModel 6848 Using saved loco block enter side [+].
20200323.121532.506 r2011a MY1121 OModel 6859 Find destination from block [bk44]. Select the shortest=[False]
20200323.121532.506 r9999a MY1121 ORoute 2008 route 5-to-loco1 is not locked
20200323.121532.507 r9999a MY1121 ORoute 1000 blockid [bk44] in route [Maint-to-5] is --from--
20200323.121532.507 r2014a MY1121 OModel 6991 Evaluating route [Maint-to-5]...
20200323.121532.507 r9999a MY1121 OBlock 1939 Block [bk5] is free [-]
20200323.121532.507 r9999a MY1121 OModel 6524 check blockgroup for [bk5]
20200323.121532.507 r9999a MY1121 OModel 5851 try to find location for block [bk5]
20200323.121532.508 r2016a MY1121 OModel 7056 Check for the goto block [bk47]: Destination is [bk5][-].
20200323.121532.508 r2020a MY1121 OModel 7108 Found a BEST suited block [bk5] for loco [MY1121].
20200323.121532.508 r9999a MY1121 ORoute 1000 blockid [bk44] in route [Maint-to-4] is --from--
20200323.121532.508 r2014a MY1121 OModel 6991 Evaluating route [Maint-to-4]...
20200323.121532.508 r9999a MY1121 OBlock 1939 Block [bk4] is free [-]
20200323.121532.508 r9999a MY1121 OModel 6524 check blockgroup for [bk4]
20200323.121532.509 r9999a MY1121 OModel 5851 try to find location for block [bk4]
20200323.121532.509 r2016a MY1121 OModel 7056 Check for the goto block [bk47]: Destination is [bk4][-].
20200323.121532.509 r2020a MY1121 OModel 7108 Found a BEST suited block [bk4] for loco [MY1121].
20200323.121532.509 r9999a MY1121 OModel 5851 try to find location for block [bk5]
20200323.121532.510 r9999a MY1121 OModel 6781 Block [bk5][Maint-to-5] is suited for [MY1121] and picked from [2] choices; randChoice=0, total RandomRate=0 userandomrate=0
20200323.121532.510 r4210I 1450A700 OLcDrive 0138 Found destination for [MY1121]: [bk5] by route [Maint-to-5]
20200323.121532.511 r4211a 1450A700 OLcDrive 0145 Setting state for "MY1121" from LC_FINDDEST to LC_INITDEST.
20200323.121532.611 r4201a 1450A700 OLcDrive 0031 Init destination for [MY1121]...
20200323.121532.611 r9999a MY1121 ORoute 1000 blockid [bk44] in route [Maint-to-5] is --from--
20200323.121532.611 r4201a 1450A700 OLcDrive 0047 loco direction for [MY1121] is [forwards], route direction [fromTo], swappost[false]
20200323.121532.611 r9999a MY1121 OModel 6524 check blockgroup for [bk5]
20200323.121532.611 r9999a 1450A700 OLcDrive 0140 initialized blockgroup [-] for [MY1121]
20200323.121532.612 r9999a MY1121 OBlock 1939 Block [bk5] is free [-]
20200323.121532.612 r9999a MY1121 OBlock 3004 block bk5 locked for [MY1121][MY1121][bk44] in [normal] direction, indelay=0
20200323.121532.612 r9999a MY1121 OBlock 3154 Block [bk5] reset counters and event triggers
20200323.121532.612 r9999a MY1121 ORoute 0356 status of route Maint-to-5 is 1, locked by MY1121
20200323.121532.612 r9999a MY1121 OBlock 1296 [bk5] CAR COUNT = 0
20200323.121532.612 r9999a MY1121 OFBack 0408 [fb14] CAR COUNT = 0
20200323.121532.612 r9999a MY1121 OFBack 0408 [fb14] CAR COUNT = 0
20200323.121532.612 r9999a MY1121 OFBack 0408 [fb12] CAR COUNT = 0
20200323.121532.612 r9999a MY1121 OFBack 0408 [fb12] CAR COUNT = 0
20200323.121532.612 r9999a MY1121 ORoute 2165 Route [Maint-to-5] locked by [MY1121]
20200323.121532.612 r9999a MY1121 ORoute 2005 route [Maint-to-5] is locked by [MY1121]
20200323.121532.612 r9999a MY1121 ORoute 2005 route [Maint-to-5] is locked by [MY1121]
20200323.121532.612 r9999I MY1121 ORoute 0831 set route [Maint-to-5] for [MY1121]
20200323.121532.612 r9999a MY1121 OSwitch 0940 Switch "sw28" is locked by "MY1121".
20200323.121532.613 r9999I MY1121 OSwitch 1414 Switch [sw28] will be set to [turnout,0,0]
20200323.121532.613 r9999I 1450A700 OMBUS 0821 command=sw:turnout
20200323.121532.613 r9999c 1450A700 OMBUS 0630 set switch 3865 0:102.0 to turnout(1) flat=101
20200323.121532.613 r9999a MY1121 OSwitch 1684 broadcasting switch[sw28] state [turnout]
20200323.121532.613 r9999I MY1121 OSwitch 0180 CTC LED: m1=1 m2=0
20200323.121532.623 r9999a MY1121 OSwitch 0940 Switch "sw9" is locked by "MY1121".
20200323.121532.623 r9999I MY1121 OSwitch 1763 switch[sw9] starting command thread...
20200323.121532.623 r9999I tid0x000 OSwitch 1716 async command for switch[sw9] pause=1000
20200323.121532.623 r9999I tid0x000 OSwitch 1723 delay command for switch[sw9] 1000ms
20200323.121532.629 r9999c mbusrdr8 OMBUS 2142 accessory response UID=0x3865 flat=101 26:2 aspect=0 current=1 value=25
20200323.121532.629 r9999a EFFBE700 OModel 5401 trying to match switch event: 0:26:2:0 type=0 uidname=[]
20200323.121532.630 r9999I EFFBE700 OSwitch 2012 switch [sw28] reported addr=26 port=2 state=turnout (addr1=26(0) port1=2(102) addr2=13(98) port2=3(0))
20200323.121532.630 r9999I EFFBE700 OSwitch 2022 switch [sw28] fieldState1=1
20200323.121532.630 r9999I EFFBE700 OSwitch 2033 switch [sw28] fieldState1=1, fieldState2=0
20200323.121532.630 r9999I EFFBE700 OSwitch 2108 switch [sw28] field event: state=left(field:turnout) gatevalue=2 inv=0 fieldstate=turnout
20200323.121532.630 r9999I EFFBE700 OSwitch 0180 CTC LED: m1=0 m2=0
20200323.121532.631 r9999a EFFBE700 OModel 3788 no more switches found by address [26,2]
20200323.121532.633 r9999a MY1121 ORoute 0356 status of route Maint-to-5 is 1, locked by MY1121
20200323.121532.634 r9999a MY1121 OLoc 4741 <lc dir="true" V="0"/>
20200323.121532.634 r4201a 1450A700 OLcDrive 0119 Setting state for "MY1121" from LC_INITDEST to LC_CHECKROUTE.
20200323.121532.634 r9999a MY1121 OLoc 2948 more messages available...
20200323.121532.644 r9999a MY1121 OLoc 2886 new message 117:command nrruns=639849
20200323.121532.645 r9999a MY1121 OLoc 2135 Sending command...V=0 dir=fwd secaddr=false
20200323.121532.645 r9999I 1450A700 OMBUS 0821 command=lc:-
20200323.121532.645 r9999I 1450A700 OMBUS 0317 slot exist for MY1121
20200323.121532.645 r9999c 1450A700 OMBUS 0743 loco [MY1121] addr=0x4005 speed=0, dir=1 lights=1
20200323.121532.661 r9999I mbusrdr8 OMBUS 0261 slot found for MY1121 by address 5
20200323.121532.661 r9999c mbusrdr8 OMBUS 1995 loco 5 direction UID=0x4005 dir=1 forwards
20200323.121532.661 r9999a EFFBE700 OModel 5316 get loco/car by address -:5
20200323.121532.661 r9999a EFFBE700 OModel 5330 loco event for [MY1121]
20200323.121532.661 r9999a EFFBE700 OLoc 0864 event for loco MY1121: lc
20200323.121532.661 r9999a EFFBE700 OLoc 0907 ignore field event for [MY1121] while running in auto mode
20200323.121532.702 r9999I mbusrdr8 OMBUS 0261 slot found for MY1121 by address 5
20200323.121532.702 r9999c mbusrdr8 OMBUS 2029 loco 5 UID=0x4005 speed=0
20200323.121532.702 r9999a EFFBE700 OModel 5316 get loco/car by address -:5
20200323.121532.702 r9999a EFFBE700 OModel 5330 loco event for [MY1121]
20200323.121532.702 r9999a EFFBE700 OLoc 0864 event for loco MY1121: lc
20200323.121532.702 r9999a EFFBE700 OLoc 0907 ignore field event for [MY1121] while running in auto mode
20200323.121532.702 r9999c mbusrdr8 OMBUS 1959 loco 5 function UID=0x4005 f=0 value=1
20200323.121532.702 r9999a EFFBE700 OModel 5316 get loco/car by address -:5
20200323.121532.702 r9999a EFFBE700 OModel 5330 loco event for [MY1121]
20200323.121532.702 r9999a EFFBE700 OLoc 0864 event for loco MY1121: fn
20200323.121532.702 r9999a EFFBE700 OLoc 0907 ignore field event for [MY1121] while running in auto mode
20200323.121533.624 r9999I tid0x000 OSwitch 1414 Switch [sw9] will be set to [turnout,0,0]
20200323.121533.638 r9999I 14447700 OMBUS 0821 command=sw:turnout
20200323.121533.639 r9999c 14447700 OMBUS 0630 set switch 3864 0:101.0 to turnout(1) flat=100
20200323.121533.639 r9999a tid0x000 OSwitch 1684 broadcasting switch[sw9] state [turnout]
20200323.121533.639 r9999I tid0x000 OSwitch 0180 CTC LED: m1=1 m2=0
20200323.121533.670 r9999c mbusrdr8 OMBUS 2142 accessory response UID=0x3864 flat=100 26:1 aspect=0 current=1 value=25
20200323.121533.670 r9999a EFFBE700 OModel 5401 trying to match switch event: 0:26:1:0 type=0 uidname=[]
20200323.121533.671 r9999I EFFBE700 OSwitch 2012 switch [sw9] reported addr=26 port=1 state=turnout (addr1=26(0) port1=1(101) addr2=11(84) port2=2(0))
20200323.121533.671 r9999I EFFBE700 OSwitch 2022 switch [sw9] fieldState1=1
20200323.121533.671 r9999I EFFBE700 OSwitch 2033 switch [sw9] fieldState1=1, fieldState2=0
20200323.121533.671 r9999I EFFBE700 OSwitch 2108 switch [sw9] field event: state=left(field:turnout) gatevalue=2 inv=0 fieldstate=turnout
20200323.121533.671 r9999I EFFBE700 OSwitch 0180 CTC LED: m1=0 m2=0
20200323.121533.671 r9999a EFFBE700 OModel 3788 no more switches found by address [26,1]
20200323.121537.847 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121537.847 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121537.848 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121547.842 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121547.842 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121547.842 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121557.849 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121557.849 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121557.849 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121601.238 r9999a makeWeat OWeather 0879 weather time is 12:16
20200323.121607.842 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121607.843 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121607.843 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121610.836 r9999I EFEFB700 OMBUS 0821 command=clock:sync
20200323.121610.836 r9999I EFEFB700 OMBUS 0804 fast clock: 12:16 factor=60
20200323.121617.431 r9999c mbusrdr8 OMBUS 1883 verify loco [] UID=0x00000000 to SID=0 with QoS=0 (id=0x00078729)
20200323.121617.845 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121617.845 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121617.845 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121627.849 r9999I mbusrdr8 OMBUS 1090 ping request from hash B37C
20200323.121627.849 r9999I mbusrdr8 OMBUS 1116 ping uid=6373750E version=12.62 typeid=0x0050 (Booster (GFP3))
20200323.121627.849 r9999I EFFBE700 OControl 1989 Program event 26: cv0=0 addr=1668510990 type=6
20200323.121630.758 r9999I cmdrD000 OControl 1459 DataReq trace filename=[-]
20200323.121630.758 r9999I cmdrD000 OControl 0831 read trace file [/opt/rocrail/work//rocrail.003.trc] size=36581