Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001925/0.001925, allocations: 84.53 kB / 19.95 MB, free: 4.609 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.00204/0.00204, allocations: 169.2 kB / 23.23 MB, free: 1.332 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.264/1.264, allocations: 158.6 MB / 185 MB, free: 3.051 MB / 170.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 2.0.0-master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 2.0.0-master/package.mo): time 0.8004/0.8004, allocations: 100.1 MB / 337.3 MB, free: 5.531 MB / 314.7 MB
"
[Timeout remaining time 179]
Using package Dynawo with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 2.0.0-master/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp,tolerance=1e-06,outputFormat="mat",numberOfIntervals=25000,variableFilter="time|measurements.IpPuSNom",fileNamePrefix="Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp")
translateModel(Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp,tolerance=1e-06,outputFormat="mat",numberOfIntervals=25000,variableFilter="time|measurements.IpPuSNom",fileNamePrefix="Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.462e-06/1.462e-06, allocations: 0 / 0.5125 GB, free: 10.31 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.431e-05/2.577e-05, allocations: 3.656 kB / 0.5125 GB, free: 10.31 MB / 442.7 MB
Notification: Performance of NFInst.instantiate(Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp): time 0.009251/0.009277, allocations: 9.639 MB / 0.5219 GB, free: 2.984 MB / 442.7 MB
Notification: Performance of NFInst.instExpressions: time 0.009481/0.01876, allocations: 4.668 MB / 0.5265 GB, free: 15.19 MB / 458.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.00244/0.0212, allocations: 44.06 kB / 0.5265 GB, free: 15.17 MB / 458.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001619/0.02282, allocations: 481.6 kB / 0.527 GB, free: 14.77 MB / 458.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.005131/0.02795, allocations: 1.576 MB / 0.5285 GB, free: 13.49 MB / 458.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003191/0.03114, allocations: 1.355 MB / 0.5299 GB, free: 12.34 MB / 458.7 MB
Notification: Performance of NFFlatten.flatten: time 0.006307/0.03745, allocations: 3.953 MB / 0.5337 GB, free: 9.621 MB / 458.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002243/0.03969, allocations: 0.8081 MB / 0.5345 GB, free: 8.762 MB / 458.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.003/0.04269, allocations: 1.305 MB / 0.5358 GB, free: 7.453 MB / 458.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002975/0.04566, allocations: 1.219 MB / 0.537 GB, free: 6.23 MB / 458.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.001459/0.04712, allocations: 432 kB / 0.5374 GB, free: 5.809 MB / 458.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.002136/0.04926, allocations: 0.6599 MB / 0.538 GB, free: 5.148 MB / 458.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.001462/0.05072, allocations: 0.8137 MB / 0.5388 GB, free: 4.332 MB / 458.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.002714/0.05343, allocations: 1.079 MB / 0.5399 GB, free: 3.25 MB / 458.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.007905/0.06134, allocations: 5.686 MB / 0.5454 GB, free: 13.55 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.342e-06/0.06134, allocations: 0 / 0.5454 GB, free: 13.55 MB / 474.7 MB
Notification: Performance of FrontEnd: time 2.054e-06/0.06135, allocations: 0 / 0.5454 GB, free: 13.55 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 0.0001095/0.06146, allocations: 0 / 0.5454 GB, free: 13.55 MB / 474.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 470
 * Number of variables: 470
Notification: Performance of Generate backend data structure: time 0.0113/0.07276, allocations: 3.694 MB / 0.549 GB, free: 9.789 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.306e-05/0.0728, allocations: 11.89 kB / 0.5491 GB, free: 9.777 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.005898/0.0787, allocations: 1.01 MB / 0.55 GB, free: 8.766 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003701/0.0824, allocations: 1.797 MB / 0.5518 GB, free: 6.934 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007033/0.0831, allocations: 0.693 MB / 0.5525 GB, free: 6.117 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004558/0.08356, allocations: 195.9 kB / 0.5527 GB, free: 5.926 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004739/0.0883, allocations: 2.242 MB / 0.5548 GB, free: 3.625 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 4.859e-05/0.08835, allocations: 7.938 kB / 0.5549 GB, free: 3.617 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000208/0.08855, allocations: 79.98 kB / 0.5549 GB, free: 3.539 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.675e-05/0.0886, allocations: 48 kB / 0.555 GB, free: 3.492 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004024/0.09262, allocations: 1.837 MB / 0.5568 GB, free: 1.652 MB / 474.7 MB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.06433/0.157, allocations: 9.404 MB / 0.566 GB, free: 8.141 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.004524/0.1615, allocations: 1.723 MB / 0.5676 GB, free: 6.398 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002016/0.1635, allocations: 0.9043 MB / 0.5685 GB, free: 5.484 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001799/0.1637, allocations: 25.31 kB / 0.5685 GB, free: 5.461 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002743/0.1664, allocations: 1.336 MB / 0.5698 GB, free: 4.102 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=191): time 4.258e-06/0.1664, allocations: 0 / 0.5698 GB, free: 4.102 MB / 490.7 MB
Notification: Performance of matching and sorting (n=191): time 0.008927/0.1754, allocations: 3.284 MB / 0.5731 GB, free: 0.8125 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001279/0.1755, allocations: 140.7 kB / 0.5732 GB, free: 0.6484 MB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003747/0.1792, allocations: 1.908 MB / 0.5751 GB, free: 14.74 MB / 0.4948 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003237/0.1795, allocations: 73.69 kB / 0.5751 GB, free: 14.66 MB / 0.4948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001224/0.1808, allocations: 0.9702 MB / 0.5761 GB, free: 13.68 MB / 0.4948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0009455/0.1817, allocations: 0.647 MB / 0.5767 GB, free: 13.03 MB / 0.4948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.000766/0.1825, allocations: 388.3 kB / 0.5771 GB, free: 12.64 MB / 0.4948 GB
Notification: Performance of setup shared object (initialization): time 0.0001552/0.1826, allocations: 301.1 kB / 0.5774 GB, free: 12.35 MB / 0.4948 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002006/0.1846, allocations: 0.9025 MB / 0.5782 GB, free: 11.44 MB / 0.4948 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002438/0.1871, allocations: 1.232 MB / 0.5794 GB, free: 10.1 MB / 0.4948 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02544/0.2125, allocations: 7.872 MB / 0.5871 GB, free: 2.121 MB / 0.4948 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.647e-05/0.2125, allocations: 4 kB / 0.5871 GB, free: 2.117 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=298) (initialization): time 0.007607/0.2201, allocations: 2.96 MB / 0.59 GB, free: 15.14 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 2.863e-05/0.2202, allocations: 8 kB / 0.59 GB, free: 15.13 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.867e-05/0.2202, allocations: 8 kB / 0.59 GB, free: 15.12 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005881/0.2208, allocations: 309.3 kB / 0.5903 GB, free: 14.82 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003774/0.2246, allocations: 0.511 MB / 0.5908 GB, free: 14.31 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004706/0.2293, allocations: 2.755 MB / 0.5935 GB, free: 11.46 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001923/0.2312, allocations: 198.9 kB / 0.5937 GB, free: 11.26 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001921/0.2314, allocations: 75.98 kB / 0.5938 GB, free: 11.19 MB / 0.5105 GB
Warning: Assuming fixed start value for the following 31 variables:
         DynGFMCCVSM.Converter.RLTransformer.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = DynGFMCCVSM.Converter.RLTransformer.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         DynGFMCCVSM.Converter.RLCFilter.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = DynGFMCCVSM.Converter.RLCFilter.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Z2_Line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Z2_Line.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Z1_Line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Z1_Line.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         DynGFMCCVSM.Converter.injectorURI.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = DynGFMCCVSM.Converter.injectorURI.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         DynGFMCCVSM.Converter.injectorURI.running:DISCRETE(flow=false start = DynGFMCCVSM.Converter.injectorURI.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         DynGFMCCVSM.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = DynGFMCCVSM.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         DynGFMCCVSM.running:DISCRETE(flow=false start = DynGFMCCVSM.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Z1_Line.running:DISCRETE(flow=false start = Z1_Line.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Z2_Line.running:DISCRETE(flow=false start = Z2_Line.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         DynGFMCCVSM.Measurements.firstOrder1.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.QSEM.UqPcc0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.Measurements.firstOrder.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.QSEM.UdPcc0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.Measurements.firstOrder2.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.currentSaturation.IdPcc0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.Measurements.firstOrder21.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.currentSaturation.IqPcc0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.Converter.VSC.firstOrder.y:VARIABLE(flow=false start = DynGFMCCVSM.Converter.VSC.UdConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.Converter.VSC.firstOrder1.y:VARIABLE(flow=false start = DynGFMCCVSM.Converter.VSC.UqConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.Converter.RLCFilter.running:DISCRETE(flow=false start = DynGFMCCVSM.Converter.RLCFilter.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         DynGFMCCVSM.Converter.RLCFilter.terminal1.i.re:VARIABLE(flow=true start = DynGFMCCVSM.Converter.RLCFilter.iConv0Pu.re unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         DynGFMCCVSM.Converter.RLCFilter.terminal1.i.im:VARIABLE(flow=true start = DynGFMCCVSM.Converter.RLCFilter.iConv0Pu.im unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         DynGFMCCVSM.Converter.RLCFilter.terminal2.V.re:VARIABLE(flow=false start = DynGFMCCVSM.Converter.RLTransformer.uFilter0Pu.re unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         DynGFMCCVSM.Converter.RLCFilter.terminal2.V.im:VARIABLE(flow=false start = DynGFMCCVSM.Converter.RLTransformer.uFilter0Pu.im unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         DynGFMCCVSM.Converter.RLTransformer.running:DISCRETE(flow=false start = DynGFMCCVSM.Converter.RLTransformer.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         DynGFMCCVSM.Converter.RLTransformer.terminal1.i.re:VARIABLE(flow=true start = -DynGFMCCVSM.Converter.RLCFilter.iPcc0Pu.re unit = \"1\" fixed = true )  \"Real part of complex number\" type: Real
         DynGFMCCVSM.Converter.RLTransformer.terminal1.i.im:VARIABLE(flow=true start = -DynGFMCCVSM.Converter.RLCFilter.iPcc0Pu.im unit = \"1\" fixed = true )  \"Imaginary part of complex number\" type: Real
         DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder3.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder1.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder2.y:VARIABLE(flow=false start = DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         DynGFMCCVSM.ControlCC.currentSaturation.idFilteredConvRefPu:VARIABLE(start = DynGFMCCVSM.ControlCC.currentSaturation.IdConvRef0Pu unit = \"1\" fixed = true )  \"Value of the Current idConvRefPu after a first order filter \" type: Real
         DynGFMCCVSM.ControlCC.currentSaturation.iqFilteredConvRefPu:VARIABLE(start = DynGFMCCVSM.ControlCC.currentSaturation.IqConvRef0Pu unit = \"1\" fixed = true )  \"Value of the Current iqConvRefPu after a first order filter \" type: Real
         DynGFMCCVSM.ControlCC.currentSaturation.saturating:DISCRETE(start = DynGFMCCVSM.ControlCC.currentSaturation.IPcc0Pu > DynGFMCCVSM.ControlCC.currentSaturation.ImaxPu fixed = true )  \"Boolean, true when current is being satured, used in computations only\" type: Boolean
         DynGFMCCVSM.ControlCC.VICC.virtualImpedanceActive:DISCRETE(start = DynGFMCCVSM.ControlCC.VICC.VIActive0 fixed = true )  \"True while the virtual impedance correction is active (above IMaxVI+HysteresisPu)\" type: Boolean
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 33
 * Number of states: 0 ()
 * Number of discrete variables: 70 ($PRE.DynGFMCCVSM.Converter.RLTransformer.state,DynGFMCCVSM.Converter.RLTransformer.state,$PRE.DynGFMCCVSM.Converter.RLCFilter.state,DynGFMCCVSM.Converter.RLCFilter.state,$PRE.Z2_Line.state,Z2_Line.state,$PRE.Z1_Line.state,Z1_Line.state,$PRE.DynGFMCCVSM.Converter.injectorURI.state,DynGFMCCVSM.Converter.injectorURI.state,$PRE.DynGFMCCVSM.Converter.injectorURI.running,DynGFMCCVSM.Converter.injectorURI.running,$whenCondition5,$whenCondition4,$whenCondition6,$PRE.DynGFMCCVSM.state,DynGFMCCVSM.state,$PRE.DynGFMCCVSM.running,DynGFMCCVSM.running,$whenCondition2,$whenCondition1,$whenCondition3,$PRE.Z1_Line.running,$PRE.Z2_Line.running,$PRE.DynGFMCCVSM.Converter.RLCFilter.running,$PRE.DynGFMCCVSM.Converter.RLTransformer.running,$PRE.DynGFMCCVSM.ControlCC.currentSaturation.saturating,$PRE.DynGFMCCVSM.ControlCC.VICC.virtualImpedanceActive,DynGFMCCVSM.ControlCC.VICC.virtualImpedanceActive,DynGFMCCVSM.ControlCC.currentSaturation.saturating,DynGFMCCVSM.Converter.RLTransformer.running,DynGFMCCVSM.Converter.RLCFilter.running,Z2_Line.running,Z2_Line.switchOffSignal2,Z1_Line.running,$whenCondition8,$whenCondition7,$whenCondition9,$whenCondition11,$whenCondition10,$whenCondition13,$whenCondition12,$whenCondition15,$whenCondition14,$whenCondition16,$whenCondition18,$whenCondition17,$whenCondition19,$whenCondition21,$whenCondition20,$whenCondition23,$whenCondition22,Z1_Line.switchOffSignal2,Z1_Line.switchOffSignal1,DynGFMCCVSM.Converter.RLTransformer.switchOffSignal2,DynGFMCCVSM.Converter.RLTransformer.switchOffSignal1,DynGFMCCVSM.Converter.RLCFilter.switchOffSignal2,DynGFMCCVSM.Converter.RLCFilter.switchOffSignal1,DynGFMCCVSM.Converter.injectorURI.switchOffSignal3,DynGFMCCVSM.Converter.injectorURI.switchOffSignal2,DynGFMCCVSM.Converter.injectorURI.switchOffSignal1,DynGFMCCVSM.switchOffSignal3,DynGFMCCVSM.switchOffSignal2,DynGFMCCVSM.switchOffSignal1,DynGFMCCVSM.ControlCC.pll.limIntegrator.local_reset,DynGFMCCVSM.ControlCC.pll.integrator.local_reset,DynGFMCCVSM.ControlCC.currentLoop.integratorq.local_reset,DynGFMCCVSM.ControlCC.currentLoop.integratord.local_reset,DynGFMCCVSM.ControlCC.VSM.integrator1.local_reset,DynGFMCCVSM.ControlCC.VSM.integrator.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (289):
 * Single equations (assignments): 288
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(8,2,56.2%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001474/0.2328, allocations: 0.7247 MB / 0.5945 GB, free: 10.46 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0005119/0.2334, allocations: 226.6 kB / 0.5947 GB, free: 10.24 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006123/0.2395, allocations: 2.474 MB / 0.5971 GB, free: 7.75 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.552e-05/0.2395, allocations: 20 kB / 0.5971 GB, free: 7.73 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 1.054e-05/0.2395, allocations: 4 kB / 0.5972 GB, free: 7.727 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.828e-05/0.2395, allocations: 11.95 kB / 0.5972 GB, free: 7.715 MB / 0.5105 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01097/0.2505, allocations: 4.952 MB / 0.602 GB, free: 2.719 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.804e-06/0.2505, allocations: 0 / 0.602 GB, free: 2.719 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003397/0.2539, allocations: 391.5 kB / 0.6024 GB, free: 2.336 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0005381/0.2545, allocations: 296.7 kB / 0.6027 GB, free: 2.043 MB / 0.5105 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001638/0.2546, allocations: 47.97 kB / 0.6027 GB, free: 1.996 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01225/0.2669, allocations: 10.73 MB / 0.6132 GB, free: 6.562 MB / 0.5261 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.683e-06/0.2669, allocations: 0 / 0.6132 GB, free: 6.562 MB / 0.5261 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.008758/0.2756, allocations: 3.881 MB / 0.617 GB, free: 2.664 MB / 0.5261 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008781/0.2765, allocations: 314.1 kB / 0.6173 GB, free: 2.355 MB / 0.5261 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003586/0.2769, allocations: 55.94 kB / 0.6173 GB, free: 2.301 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001393/0.2783, allocations: 87.84 kB / 0.6174 GB, free: 2.215 MB / 0.5261 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0004574/0.2787, allocations: 173.6 kB / 0.6176 GB, free: 2.043 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001634/0.2789, allocations: 59.97 kB / 0.6176 GB, free: 1.984 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.002528/0.2814, allocations: 1.287 MB / 0.6189 GB, free: 0.707 MB / 0.5261 GB
Notification: Performance of sort global known variables: time 9e-08/0.2814, allocations: 0 / 0.6189 GB, free: 0.707 MB / 0.5261 GB
Notification: Performance of remove unused functions: time 0.00207/0.2835, allocations: 0.6121 MB / 0.6195 GB, free: 96 kB / 0.5261 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 3
 * Number of states: 24 (DynGFMCCVSM.Measurements.firstOrder1.y,DynGFMCCVSM.Measurements.firstOrder.y,DynGFMCCVSM.Measurements.firstOrder2.y,DynGFMCCVSM.Measurements.firstOrder21.y,DynGFMCCVSM.Converter.VSC.firstOrder.y,DynGFMCCVSM.Converter.VSC.firstOrder1.y,DynGFMCCVSM.Converter.RLCFilter.terminal1.i.re,DynGFMCCVSM.Converter.RLCFilter.terminal1.i.im,DynGFMCCVSM.Converter.RLCFilter.terminal2.V.re,DynGFMCCVSM.Converter.RLCFilter.terminal2.V.im,DynGFMCCVSM.Converter.RLTransformer.terminal1.i.re,DynGFMCCVSM.Converter.RLTransformer.terminal1.i.im,DynGFMCCVSM.ControlCC.VSM.integrator.y,DynGFMCCVSM.ControlCC.VSM.integrator1.y,DynGFMCCVSM.ControlCC.PLLFilter.y,DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder3.y,DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder1.y,DynGFMCCVSM.ControlCC.voltageReferenceControl.firstOrder2.y,DynGFMCCVSM.ControlCC.currentSaturation.idFilteredConvRefPu,DynGFMCCVSM.ControlCC.currentSaturation.iqFilteredConvRefPu,DynGFMCCVSM.ControlCC.currentLoop.integratord.y,DynGFMCCVSM.ControlCC.currentLoop.integratorq.y,DynGFMCCVSM.ControlCC.pll.integrator.y,DynGFMCCVSM.ControlCC.pll.limIntegrator.y)
 * Number of discrete variables: 38 ($whenCondition3,$whenCondition1,$whenCondition2,DynGFMCCVSM.running,DynGFMCCVSM.state,$whenCondition6,$whenCondition4,$whenCondition5,DynGFMCCVSM.Converter.injectorURI.running,DynGFMCCVSM.Converter.injectorURI.state,$whenCondition22,$whenCondition23,$whenCondition20,$whenCondition21,$whenCondition19,$whenCondition17,$whenCondition18,$whenCondition16,$whenCondition14,$whenCondition15,$whenCondition12,$whenCondition13,$whenCondition10,$whenCondition11,$whenCondition9,$whenCondition7,$whenCondition8,Z1_Line.running,Z1_Line.state,Z2_Line.switchOffSignal2,Z2_Line.running,Z2_Line.state,DynGFMCCVSM.Converter.RLCFilter.running,DynGFMCCVSM.Converter.RLCFilter.state,DynGFMCCVSM.Converter.RLTransformer.running,DynGFMCCVSM.Converter.RLTransformer.state,DynGFMCCVSM.ControlCC.currentSaturation.saturating,DynGFMCCVSM.ControlCC.VICC.virtualImpedanceActive)
 * Number of discrete states: 6 (DynGFMCCVSM.Converter.RLTransformer.running,DynGFMCCVSM.Converter.RLCFilter.running,Z1_Line.running,Z2_Line.running,DynGFMCCVSM.Converter.injectorURI.running,DynGFMCCVSM.running)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (188):
 * Single equations (assignments): 167
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 14
 * If-equations: 0
 * Equation systems (not torn): 6
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 6 systems
   {1, 1, 1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 1 system
   {(8,2,56.2%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.0005816/0.2841, allocations: 241.5 kB / 0.6197 GB, free: 15.85 MB / 0.5417 GB
Notification: Performance of simCode: created initialization part: time 0.005518/0.2896, allocations: 2.352 MB / 0.622 GB, free: 13.48 MB / 0.5417 GB
Notification: Performance of simCode: created event and clocks part: time 4.218e-06/0.2896, allocations: 0 / 0.622 GB, free: 13.48 MB / 0.5417 GB
Notification: Performance of simCode: created simulation system equations: time 0.004547/0.2941, allocations: 1.326 MB / 0.6233 GB, free: 12.14 MB / 0.5417 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002307/0.2964, allocations: 387.5 kB / 0.6237 GB, free: 11.78 MB / 0.5417 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.009337/0.3058, allocations: 4.489 MB / 0.6281 GB, free: 7.25 MB / 0.5417 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001254/0.307, allocations: 0.9467 MB / 0.629 GB, free: 6.266 MB / 0.5417 GB
Notification: Performance of simCode: alias equations: time 0.001221/0.3083, allocations: 235.1 kB / 0.6292 GB, free: 6.031 MB / 0.5417 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0006739/0.3089, allocations: 160.5 kB / 0.6294 GB, free: 5.875 MB / 0.5417 GB
Notification: Performance of SimCode: time 1.162e-06/0.3089, allocations: 4 kB / 0.6294 GB, free: 5.871 MB / 0.5417 GB
Notification: Performance of Templates: time 0.06106/0.37, allocations: 31.8 MB / 0.6604 GB, free: 6.227 MB / 0.573 GB
"
[Timeout remaining time 660]
make -j1 -f Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.makefile [Timeout 660]
(rm -f Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.pipe ; mkfifo Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.pipe ; head -c 1048576 < Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.pipe >> ../files/Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.sim & ./Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp  -lv=LOG_STDOUT,LOG_ASSERT,LOG_STATS -s=dassl -variableFilter=.* -abortSlowSimulation -alarm=240  -emit_protected -ignoreHideResult -lv LOG_STATS > Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp.pipe 2>&1) [Timeout 240]
diffSimulationResults("Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/Dynawo/dynawo/sources/Models/Modelica/Dynawo/Examples/references/Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMUp_res.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 660]
Reference file matches
[Calling sys.exit(0), Time elapsed: 8.037083067931235]