Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown.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.002537/0.002537, allocations: 80.55 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.003032/0.003032, allocations: 173.2 kB / 23.23 MB, free: 1.328 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.275/1.275, allocations: 158.6 MB / 185 MB, free: 2.875 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.7358/0.7358, allocations: 100.1 MB / 337.4 MB, free: 5.5 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.CCVSMDown,tolerance=1e-06,outputFormat="mat",numberOfIntervals=25000,variableFilter="time|measurements.IpPuSNom",fileNamePrefix="Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown")
translateModel(Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown,tolerance=1e-06,outputFormat="mat",numberOfIntervals=25000,variableFilter="time|measurements.IpPuSNom",fileNamePrefix="Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.362e-06/1.362e-06, allocations: 0 / 0.5125 GB, free: 10.08 MB / 442.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.101e-05/2.237e-05, allocations: 2.281 kB / 0.5125 GB, free: 10.08 MB / 442.7 MB
Notification: Performance of NFInst.instantiate(Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown): time 0.008919/0.008941, allocations: 9.64 MB / 0.5219 GB, free: 2.754 MB / 442.7 MB
Notification: Performance of NFInst.instExpressions: time 0.008455/0.0174, allocations: 4.664 MB / 0.5265 GB, free: 14.96 MB / 458.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002479/0.01988, allocations: 44.56 kB / 0.5265 GB, free: 14.94 MB / 458.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.001791/0.02167, allocations: 486.2 kB / 0.527 GB, free: 14.54 MB / 458.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.005207/0.02687, allocations: 1.577 MB / 0.5285 GB, free: 13.26 MB / 458.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.003516/0.03039, allocations: 1.36 MB / 0.5299 GB, free: 12.11 MB / 458.7 MB
Notification: Performance of NFFlatten.flatten: time 0.006124/0.03651, allocations: 3.948 MB / 0.5337 GB, free: 9.445 MB / 458.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002065/0.03858, allocations: 0.812 MB / 0.5345 GB, free: 8.582 MB / 458.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002386/0.04097, allocations: 1.305 MB / 0.5358 GB, free: 7.273 MB / 458.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002263/0.04323, allocations: 1.219 MB / 0.537 GB, free: 6.051 MB / 458.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0008229/0.04405, allocations: 436 kB / 0.5374 GB, free: 5.625 MB / 458.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001435/0.04549, allocations: 0.6559 MB / 0.538 GB, free: 4.969 MB / 458.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0008432/0.04633, allocations: 0.8137 MB / 0.5388 GB, free: 4.152 MB / 458.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001476/0.04781, allocations: 1.079 MB / 0.5399 GB, free: 3.07 MB / 458.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.007309/0.05511, allocations: 5.682 MB / 0.5454 GB, free: 13.37 MB / 474.7 MB
Notification: Performance of FrontEnd - DAE generated: time 9.147e-06/0.05512, allocations: 4 kB / 0.5454 GB, free: 13.37 MB / 474.7 MB
Notification: Performance of FrontEnd: time 1.493e-06/0.05512, allocations: 0 / 0.5454 GB, free: 13.37 MB / 474.7 MB
Notification: Performance of Transformations before backend: time 7.08e-05/0.0552, allocations: 4 kB / 0.5454 GB, free: 13.36 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.01098/0.06617, allocations: 3.69 MB / 0.549 GB, free: 9.609 MB / 474.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.294e-05/0.06621, allocations: 15.89 kB / 0.5491 GB, free: 9.594 MB / 474.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006519/0.07273, allocations: 1.006 MB / 0.55 GB, free: 8.586 MB / 474.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.003774/0.07651, allocations: 1.794 MB / 0.5518 GB, free: 6.758 MB / 474.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006948/0.0772, allocations: 0.6969 MB / 0.5525 GB, free: 5.938 MB / 474.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000392/0.07759, allocations: 195.9 kB / 0.5527 GB, free: 5.746 MB / 474.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004659/0.08225, allocations: 2.242 MB / 0.5549 GB, free: 3.445 MB / 474.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 3.717e-05/0.08229, allocations: 0 / 0.5549 GB, free: 3.445 MB / 474.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002109/0.0825, allocations: 83.98 kB / 0.5549 GB, free: 3.363 MB / 474.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.328e-05/0.08254, allocations: 44 kB / 0.555 GB, free: 3.32 MB / 474.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004097/0.08664, allocations: 1.841 MB / 0.5568 GB, free: 1.477 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.06233/0.149, allocations: 9.404 MB / 0.566 GB, free: 7.965 MB / 490.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.004197/0.1532, allocations: 1.717 MB / 0.5676 GB, free: 6.227 MB / 490.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001845/0.155, allocations: 0.9081 MB / 0.5685 GB, free: 5.309 MB / 490.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001684/0.1552, allocations: 25.31 kB / 0.5685 GB, free: 5.285 MB / 490.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002582/0.1578, allocations: 1.336 MB / 0.5698 GB, free: 3.926 MB / 490.7 MB
Notification: Performance of pre-optimization done (n=191): time 4.558e-06/0.1578, allocations: 0 / 0.5698 GB, free: 3.926 MB / 490.7 MB
Notification: Performance of matching and sorting (n=191): time 0.008854/0.1666, allocations: 3.284 MB / 0.5731 GB, free: 0.6367 MB / 490.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001274/0.1668, allocations: 140.8 kB / 0.5732 GB, free: 484 kB / 490.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.003388/0.1701, allocations: 1.908 MB / 0.5751 GB, free: 14.57 MB / 0.4948 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002068/0.1703, allocations: 73.69 kB / 0.5751 GB, free: 14.49 MB / 0.4948 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001051/0.1714, allocations: 0.9702 MB / 0.5761 GB, free: 13.51 MB / 0.4948 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0008487/0.1722, allocations: 0.6469 MB / 0.5767 GB, free: 12.86 MB / 0.4948 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007562/0.173, allocations: 384.3 kB / 0.5771 GB, free: 12.47 MB / 0.4948 GB
Notification: Performance of setup shared object (initialization): time 0.0001535/0.1732, allocations: 305.1 kB / 0.5774 GB, free: 12.17 MB / 0.4948 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002092/0.1752, allocations: 0.9025 MB / 0.5782 GB, free: 11.27 MB / 0.4948 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002248/0.1775, allocations: 1.228 MB / 0.5794 GB, free: 9.93 MB / 0.4948 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.02484/0.2023, allocations: 7.872 MB / 0.5871 GB, free: 1.949 MB / 0.4948 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.65e-05/0.2024, allocations: 4 kB / 0.5871 GB, free: 1.945 MB / 0.4948 GB
Notification: Performance of matching and sorting (n=298) (initialization): time 0.007563/0.2099, allocations: 2.961 MB / 0.59 GB, free: 14.97 MB / 0.5105 GB
Notification: Performance of prepare postOptimizeDAE: time 2.806e-05/0.2099, allocations: 12 kB / 0.59 GB, free: 14.96 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.833e-05/0.21, allocations: 8.062 kB / 0.59 GB, free: 14.95 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005959/0.2106, allocations: 300.7 kB / 0.5903 GB, free: 14.65 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003784/0.2143, allocations: 0.5187 MB / 0.5908 GB, free: 14.13 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004965/0.2193, allocations: 2.755 MB / 0.5935 GB, free: 11.28 MB / 0.5105 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002065/0.2214, allocations: 190.9 kB / 0.5937 GB, free: 11.09 MB / 0.5105 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002194/0.2216, allocations: 83.98 kB / 0.5938 GB, free: 11.01 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.001502/0.2231, allocations: 0.7161 MB / 0.5945 GB, free: 10.29 MB / 0.5105 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000517/0.2236, allocations: 226.6 kB / 0.5947 GB, free: 10.07 MB / 0.5105 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006176/0.2298, allocations: 2.47 MB / 0.5971 GB, free: 7.582 MB / 0.5105 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.469e-05/0.2298, allocations: 20 kB / 0.5971 GB, free: 7.562 MB / 0.5105 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.737e-06/0.2298, allocations: 0 / 0.5971 GB, free: 7.562 MB / 0.5105 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 3.849e-05/0.2299, allocations: 7.969 kB / 0.5971 GB, free: 7.555 MB / 0.5105 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01133/0.2412, allocations: 4.96 MB / 0.602 GB, free: 2.551 MB / 0.5105 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.386e-06/0.2412, allocations: 0 / 0.602 GB, free: 2.551 MB / 0.5105 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.003353/0.2446, allocations: 395.4 kB / 0.6024 GB, free: 2.164 MB / 0.5105 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0005616/0.2451, allocations: 292.7 kB / 0.6026 GB, free: 1.875 MB / 0.5105 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0002124/0.2453, allocations: 48.09 kB / 0.6027 GB, free: 1.828 MB / 0.5105 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.01199/0.2573, allocations: 10.74 MB / 0.6132 GB, free: 6.387 MB / 0.5261 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.334e-06/0.2573, allocations: 0 / 0.6132 GB, free: 6.387 MB / 0.5261 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.008789/0.2661, allocations: 3.877 MB / 0.617 GB, free: 2.492 MB / 0.5261 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0009848/0.2671, allocations: 310.3 kB / 0.6173 GB, free: 2.188 MB / 0.5261 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004075/0.2675, allocations: 55.98 kB / 0.6173 GB, free: 2.133 MB / 0.5261 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001441/0.269, allocations: 99.78 kB / 0.6174 GB, free: 2.035 MB / 0.5261 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005327/0.2695, allocations: 157.7 kB / 0.6176 GB, free: 1.879 MB / 0.5261 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001656/0.2697, allocations: 63.95 kB / 0.6176 GB, free: 1.816 MB / 0.5261 GB
Notification: Performance of sorting global known variables: time 0.00262/0.2723, allocations: 1.287 MB / 0.6189 GB, free: 0.5391 MB / 0.5261 GB
Notification: Performance of sort global known variables: time 4.41e-07/0.2723, allocations: 0 / 0.6189 GB, free: 0.5391 MB / 0.5261 GB
Notification: Performance of remove unused functions: time 0.002503/0.2748, allocations: 0.6123 MB / 0.6195 GB, free: 15.92 MB / 0.5417 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.0008113/0.2756, allocations: 251.5 kB / 0.6197 GB, free: 15.67 MB / 0.5417 GB
Notification: Performance of simCode: created initialization part: time 0.006538/0.2821, allocations: 2.348 MB / 0.622 GB, free: 13.31 MB / 0.5417 GB
Notification: Performance of simCode: created event and clocks part: time 7.354e-06/0.2821, allocations: 0 / 0.622 GB, free: 13.31 MB / 0.5417 GB
Notification: Performance of simCode: created simulation system equations: time 0.004915/0.287, allocations: 1.322 MB / 0.6233 GB, free: 11.98 MB / 0.5417 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003058/0.2901, allocations: 398.2 kB / 0.6237 GB, free: 11.61 MB / 0.5417 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01025/0.3004, allocations: 4.499 MB / 0.6281 GB, free: 7.062 MB / 0.5417 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001324/0.3017, allocations: 0.9428 MB / 0.629 GB, free: 6.082 MB / 0.5417 GB
Notification: Performance of simCode: alias equations: time 0.001371/0.3031, allocations: 235.1 kB / 0.6292 GB, free: 5.848 MB / 0.5417 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0007159/0.3038, allocations: 164.4 kB / 0.6294 GB, free: 5.688 MB / 0.5417 GB
Notification: Performance of SimCode: time 1.713e-06/0.3038, allocations: 3.938 kB / 0.6294 GB, free: 5.684 MB / 0.5417 GB
Notification: Performance of Templates: time 0.06212/0.3659, allocations: 31.79 MB / 0.6604 GB, free: 6.051 MB / 0.573 GB
"
[Timeout remaining time 660]
make -j1 -f Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown.makefile [Timeout 660]
(rm -f Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown.pipe ; mkfifo Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown.pipe ; head -c 1048576 < Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown.pipe >> ../files/Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown.sim & ./Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown  -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.CCVSMDown.pipe 2>&1) [Timeout 240]
diffSimulationResults("Dynawo_dev_Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/Dynawo/dynawo/sources/Models/Modelica/Dynawo/Examples/references/Dynawo.Examples.GridForming.Tests.SCRJump.CCVSMDown_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.061363508924842]