Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.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.0008226/0.0008226, allocations: 96.36 kB / 19.72 MB, free: 0.5508 MB / 13.93 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.0008424/0.0008424, allocations: 220.8 kB / 23.02 MB, free: 3.051 MB / 13.93 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 0.873/0.873, allocations: 207.7 MB / 233.9 MB, free: 11.47 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/package.mo): time 0.4207/0.4207, allocations: 120.7 MB / 406.8 MB, free: 1.359 MB / 330.7 MB
"
[Timeout remaining time 179]
Using package Dynawo with version 1.8.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo 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)
Ignoring simflag  -noemit -noHomotopyOnFirstTry=() since it seems broken on HelloWorld
Ignoring simflag  -noemit -noRestart=() since it seems broken on HelloWorld
Ignoring simflag  -noemit -noRootFinding=() since it seems broken on HelloWorld
Running command: "" <> buildModelFMU(Dynawo.Examples.Wind.WECC.WTG4ACurrentSource,fileNamePrefix="Dynawo_dev_Dynawo_Examples_Wind_WECC_WTG4ACurrentSource",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Dynawo.Examples.Wind.WECC.WTG4ACurrentSource,fileNamePrefix="Dynawo_dev_Dynawo_Examples_Wind_WECC_WTG4ACurrentSource",fmuType="me",version="2.0",platforms={"static"}) [Timeout 660]
"Notification: Modelica requested package Complex of version 3.2.3. Complex 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.001143/0.001143, allocations: 79.88 kB / 0.5774 GB, free: 10.4 MB / 490.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3417/0.3428, allocations: 80.37 MB / 0.6559 GB, free: 22.56 MB / 0.4948 GB
Notification: Performance of NFInst.instantiate(Dynawo.Examples.Wind.WECC.WTG4ACurrentSource): time 0.01231/0.3551, allocations: 14.32 MB / 0.6699 GB, free: 11.74 MB / 0.4948 GB
Notification: Performance of NFInst.instExpressions: time 0.01168/0.3668, allocations: 6.98 MB / 0.6767 GB, free: 6.086 MB / 0.4948 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004175/0.371, allocations: 98.06 kB / 0.6768 GB, free: 6.047 MB / 0.4948 GB
Notification: Performance of NFTyping.typeComponents: time 0.00285/0.3738, allocations: 0.9734 MB / 0.6777 GB, free: 5.262 MB / 0.4948 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/Utilities/Strings.mo:139:3-175:14:writable] Warning: Pure function 'Modelica.Utilities.Strings.isEmpty' contains a call to impure function 'Modelica.Utilities.Strings.Advanced.skipWhiteSpace'.
Notification: Performance of NFTyping.typeBindings: time 0.005151/0.379, allocations: 1.542 MB / 0.6792 GB, free: 4 MB / 0.4948 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:61:5-65:11:writable] Warning: In relation WTG4A.HvTfo.terminal1.V.re == 0.0, == on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:61:5-65:11:writable] Warning: In relation WTG4A.HvTfo.terminal1.V.im == 0.0, == on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:66:5-70:11:writable] Warning: In relation WTG4A.HvTfo.terminal2.V.re == 0.0, == on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:66:5-70:11:writable] Warning: In relation WTG4A.HvTfo.terminal2.V.im == 0.0, == on Real numbers is only allowed inside functions.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Controls/WECC/BaseClasses_INIT/WECCPlantCurrentSource_INIT.mo:112:3-112:90:writable] Warning: In relation Modelica.ComplexMath.'abs'(wTG4CurrentSource_INIT.s0Pu) == 0.0, == on Real numbers is only allowed inside functions.
Notification: Performance of NFTyping.typeClassSections: time 0.005571/0.3846, allocations: 3.139 MB / 0.6823 GB, free: 1.477 MB / 0.4948 GB
Notification: Performance of NFFlatten.flatten: time 0.007757/0.3923, allocations: 5.678 MB / 0.6879 GB, free: 13.8 MB / 0.5105 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002857/0.3952, allocations: 1.279 MB / 0.6891 GB, free: 12.84 MB / 0.5105 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.003213/0.3984, allocations: 2.113 MB / 0.6912 GB, free: 11.38 MB / 0.5105 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00318/0.4016, allocations: 2.045 MB / 0.6932 GB, free: 9.82 MB / 0.5105 GB
Notification: Performance of NFPackage.collectConstants: time 0.00159/0.4032, allocations: 0.5952 MB / 0.6937 GB, free: 9.82 MB / 0.5105 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002084/0.4052, allocations: 0.9218 MB / 0.6947 GB, free: 9.035 MB / 0.5105 GB
Notification: Performance of NFScalarize.scalarize: time 0.00116/0.4064, allocations: 1.257 MB / 0.6959 GB, free: 7.773 MB / 0.5105 GB
Notification: Performance of NFVerifyModel.verify: time 0.002604/0.409, allocations: 1.857 MB / 0.6977 GB, free: 5.906 MB / 0.5105 GB
Notification: Performance of NFConvertDAE.convert: time 0.008052/0.4171, allocations: 7.394 MB / 0.7049 GB, free: 14.48 MB / 0.5261 GB
Notification: Performance of FrontEnd - DAE generated: time 6.803e-06/0.4171, allocations: 0 / 0.7049 GB, free: 14.48 MB / 0.5261 GB
Notification: Performance of FrontEnd: time 2.044e-06/0.4171, allocations: 0 / 0.7049 GB, free: 14.48 MB / 0.5261 GB
Notification: Performance of Transformations before backend: time 0.0002432/0.4173, allocations: 4 kB / 0.7049 GB, free: 14.48 MB / 0.5261 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 765
 * Number of variables: 765
Notification: Performance of Generate backend data structure: time 0.013/0.4303, allocations: 6.136 MB / 0.7109 GB, free: 8.254 MB / 0.5261 GB
Notification: Performance of prepare preOptimizeDAE: time 4.845e-05/0.4304, allocations: 12.03 kB / 0.7109 GB, free: 8.242 MB / 0.5261 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.0004719/0.4308, allocations: 425.7 kB / 0.7113 GB, free: 7.816 MB / 0.5261 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003313/0.4341, allocations: 1.114 MB / 0.7124 GB, free: 6.699 MB / 0.5261 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004529/0.4387, allocations: 2.841 MB / 0.7152 GB, free: 3.816 MB / 0.5261 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006602/0.4393, allocations: 1.127 MB / 0.7163 GB, free: 2.484 MB / 0.5261 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.000445/0.4398, allocations: 315.9 kB / 0.7166 GB, free: 2.176 MB / 0.5261 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005464/0.4452, allocations: 4.149 MB / 0.7206 GB, free: 13.75 MB / 0.5417 GB
Notification: Performance of preOpt findStateOrder (simulation): time 4.39e-05/0.4453, allocations: 3.938 kB / 0.7206 GB, free: 13.74 MB / 0.5417 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000214/0.4455, allocations: 136 kB / 0.7208 GB, free: 13.61 MB / 0.5417 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.004e-05/0.4455, allocations: 80 kB / 0.7209 GB, free: 13.53 MB / 0.5417 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005171/0.4507, allocations: 3.164 MB / 0.7239 GB, free: 10.36 MB / 0.5417 GB
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.01578/0.4665, allocations: 11.62 MB / 0.7353 GB, free: 14.52 MB / 0.5573 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.004554/0.471, allocations: 3.018 MB / 0.7382 GB, free: 11.51 MB / 0.5573 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001855/0.4729, allocations: 1.292 MB / 0.7395 GB, free: 10.21 MB / 0.5573 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002046/0.4731, allocations: 37.98 kB / 0.7395 GB, free: 10.18 MB / 0.5573 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002459/0.4756, allocations: 1.903 MB / 0.7414 GB, free: 8.246 MB / 0.5573 GB
Notification: Performance of pre-optimization done (n=253): time 5.35e-06/0.4756, allocations: 8 kB / 0.7414 GB, free: 8.238 MB / 0.5573 GB
Notification: Performance of matching and sorting (n=253): time 0.008201/0.4838, allocations: 4.905 MB / 0.7462 GB, free: 3.328 MB / 0.5573 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001791/0.484, allocations: 487 kB / 0.7467 GB, free: 2.75 MB / 0.5573 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004685/0.4886, allocations: 3.743 MB / 0.7503 GB, free: 14.98 MB / 0.573 GB
Notification: Performance of collectPreVariables (initialization): time 0.0005165/0.4892, allocations: 89.7 kB / 0.7504 GB, free: 14.89 MB / 0.573 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001235/0.4904, allocations: 1.74 MB / 0.7521 GB, free: 13.14 MB / 0.573 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001004/0.4914, allocations: 0.9066 MB / 0.753 GB, free: 12.22 MB / 0.573 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009156/0.4923, allocations: 0.7637 MB / 0.7537 GB, free: 11.45 MB / 0.573 GB
Notification: Performance of setup shared object (initialization): time 8.566e-05/0.4924, allocations: 301.1 kB / 0.754 GB, free: 11.15 MB / 0.573 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002365/0.4948, allocations: 1.741 MB / 0.7557 GB, free: 9.395 MB / 0.573 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002901/0.4977, allocations: 2.722 MB / 0.7584 GB, free: 6.383 MB / 0.573 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.01692/0.5146, allocations: 8.793 MB / 0.767 GB, free: 13.41 MB / 0.5886 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.453e-05/0.5146, allocations: 4 kB / 0.767 GB, free: 13.4 MB / 0.5886 GB
Notification: Performance of matching and sorting (n=537) (initialization): time 0.00808/0.5227, allocations: 5.121 MB / 0.772 GB, free: 8.254 MB / 0.5886 GB
Notification: Performance of prepare postOptimizeDAE: time 8.155e-05/0.5228, allocations: 73.84 kB / 0.772 GB, free: 8.176 MB / 0.5886 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.129e-05/0.5228, allocations: 12 kB / 0.772 GB, free: 8.164 MB / 0.5886 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005771/0.5234, allocations: 388.5 kB / 0.7724 GB, free: 7.785 MB / 0.5886 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002208/0.5256, allocations: 0.8425 MB / 0.7732 GB, free: 6.941 MB / 0.5886 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00343/0.529, allocations: 2.999 MB / 0.7762 GB, free: 3.824 MB / 0.5886 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00311/0.5321, allocations: 0.4934 MB / 0.7767 GB, free: 3.328 MB / 0.5886 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002512/0.5324, allocations: 140 kB / 0.7768 GB, free: 3.191 MB / 0.5886 GB
Warning: Assuming fixed start value for the following 32 variables:
         WTG4A.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG4A.HvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         WTG4A.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG4A.LvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         WTG4A.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG4A.injector.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = line.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         line.running:DISCRETE(flow=false start = line.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.wecc_reec.firstOrder.y:VARIABLE(flow=false start = WTG4A.wecc_reec.UConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = WTG4A.wecc_reec.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG4A.wecc_reec.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG4A.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = WTG4A.wecc_reec.PConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = WTG4A.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.iqInjectionLogic.vDip:DISCRETE(flow=false start = false fixed = true )  \"Ongoing voltage dip\" type: Boolean
         WTG4A.wecc_reec.iqInjectionLogic.vDipInjEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  \"ending time of the voltage dip start (in seconds)\" type: Real
         WTG4A.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu:DISCRETE(start = 0.0 unit = \"1\" fixed = true )  type: Real
         WTG4A.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  type: Real
         WTG4A.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = WTG4A.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG4A.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.firstOrder.y:VARIABLE(flow=false start = WTG4A.wecc_regc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true )  type: Boolean
         WTG4A.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true )  type: Real
         WTG4A.injector.running:DISCRETE(flow=false start = WTG4A.injector.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.LvTfo.running:DISCRETE(flow=false start = WTG4A.LvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.HvTfo.running:DISCRETE(flow=false start = WTG4A.HvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.wecc_repc.firstOrder.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG4A.wecc_repc.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = WTG4A.wecc_repc.leadLag.X0[1] * WTG4A.wecc_repc.leadLag.a_end fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         WTG4A.wecc_wtgt.Pmech.y:VARIABLE(flow=false start = WTG4A.wecc_wtgt.Pmech.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true )  \"Boolean input signal\" type: Boolean
         WTG4A.wecc_reec.currentLimitsCalculation1.vDip:DISCRETE(flow=false fixed = true )  \"Ongoing voltage dip\" type: Boolean
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003979/0.5363, allocations: 2.461 MB / 0.7792 GB, free: 0.7344 MB / 0.5886 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002749/0.5391, allocations: 2.667 MB / 0.7818 GB, free: 13.77 MB / 0.6042 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.01556/0.5546, allocations: 8.641 MB / 0.7902 GB, free: 4.949 MB / 0.6042 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.26e-05/0.5547, allocations: 4 kB / 0.7902 GB, free: 4.945 MB / 0.6042 GB
Notification: Performance of matching and sorting (n=537) (initialization_lambda0): time 0.007724/0.5624, allocations: 5.027 MB / 0.7951 GB, free: 15.89 MB / 0.6198 GB
Notification: Performance of prepare postOptimizeDAE: time 6.949e-05/0.5625, allocations: 65.66 kB / 0.7952 GB, free: 15.82 MB / 0.6198 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.498e-05/0.5625, allocations: 12.06 kB / 0.7952 GB, free: 15.81 MB / 0.6198 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000504/0.563, allocations: 392.6 kB / 0.7956 GB, free: 15.42 MB / 0.6198 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002082/0.5651, allocations: 0.8269 MB / 0.7964 GB, free: 14.59 MB / 0.6198 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.003189/0.5682, allocations: 2.999 MB / 0.7993 GB, free: 11.49 MB / 0.6198 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002777/0.571, allocations: 414.1 kB / 0.7997 GB, free: 11.08 MB / 0.6198 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002989/0.5713, allocations: 144 kB / 0.7999 GB, free: 10.94 MB / 0.6198 GB
Warning: Assuming fixed start value for the following 32 variables:
         WTG4A.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG4A.HvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         WTG4A.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG4A.LvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         WTG4A.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG4A.injector.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         line.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = line.State0 fixed = true )  \"Line connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         line.running:DISCRETE(flow=false start = line.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.wecc_reec.firstOrder.y:VARIABLE(flow=false start = WTG4A.wecc_reec.UConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = WTG4A.wecc_reec.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG4A.wecc_reec.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG4A.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = WTG4A.wecc_reec.PConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = WTG4A.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_reec.iqInjectionLogic.vDip:DISCRETE(flow=false start = false fixed = true )  \"Ongoing voltage dip\" type: Boolean
         WTG4A.wecc_reec.iqInjectionLogic.vDipInjEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  \"ending time of the voltage dip start (in seconds)\" type: Real
         WTG4A.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu:DISCRETE(start = 0.0 unit = \"1\" fixed = true )  type: Real
         WTG4A.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  type: Real
         WTG4A.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = WTG4A.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG4A.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.firstOrder.y:VARIABLE(flow=false start = WTG4A.wecc_regc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true )  type: Boolean
         WTG4A.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true )  type: Real
         WTG4A.injector.running:DISCRETE(flow=false start = WTG4A.injector.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.LvTfo.running:DISCRETE(flow=false start = WTG4A.LvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.HvTfo.running:DISCRETE(flow=false start = WTG4A.HvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         WTG4A.wecc_repc.firstOrder.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = WTG4A.wecc_repc.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG4A.wecc_repc.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = WTG4A.wecc_repc.leadLag.X0[1] * WTG4A.wecc_repc.leadLag.a_end fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         WTG4A.wecc_wtgt.Pmech.y:VARIABLE(flow=false start = WTG4A.wecc_wtgt.Pmech.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         WTG4A.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true )  \"Boolean input signal\" type: Boolean
         WTG4A.wecc_reec.currentLimitsCalculation1.vDip:DISCRETE(flow=false fixed = true )  \"Ongoing voltage dip\" type: Boolean
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 47
 * Number of states: 0 ()
 * Number of discrete variables: 78 ($PRE.WTG4A.HvTfo.state,WTG4A.HvTfo.state,$PRE.WTG4A.LvTfo.state,WTG4A.LvTfo.state,$PRE.WTG4A.injector.state,WTG4A.injector.state,$PRE.line.state,line.state,WTG4A.injector.switchOffSignal3,WTG4A.injector.switchOffSignal2,WTG4A.injector.switchOffSignal1,line.switchOffSignal2,line.switchOffSignal1,WTG4A.wecc_regc.rateLimFirstOrderFreeze1.local_freeze,WTG4A.wecc_regc.rateLimFirstOrderFreeze2.local_freeze,$PRE.line.running,$PRE.WTG4A.wecc_reec.iqInjectionLogic.vDip,$PRE.WTG4A.wecc_reec.iqInjectionLogic.vDipInjEndTime,$PRE.WTG4A.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,$PRE.WTG4A.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,$PRE.WTG4A.wecc_regc.offDelay.delaySignal,$PRE.WTG4A.wecc_regc.offDelay.tNext,$PRE.WTG4A.injector.running,$PRE.WTG4A.LvTfo.running,$PRE.WTG4A.HvTfo.running,WTG4A.wecc_regc.QConv0PuPos.k,WTG4A.wecc_regc.and1.u[1],WTG4A.wecc_regc.QConv0PuPos.y,WTG4A.wecc_regc.QConv0PuNeg.k,WTG4A.wecc_regc.and2.u[2],WTG4A.wecc_regc.QConv0PuNeg.y,$PRE.WTG4A.wecc_regc.offDelay.u,$PRE.WTG4A.wecc_reec.currentLimitsCalculation1.vDip,WTG4A.wecc_repc.freeze,WTG4A.HvTfo.running,WTG4A.LvTfo.running,WTG4A.injector.running,WTG4A.wecc_regc.and2.y,WTG4A.wecc_regc.and1.y,WTG4A.wecc_regc.and1.u[2],WTG4A.wecc_regc.offDelay.tNext,WTG4A.wecc_regc.offDelay.delaySignal,WTG4A.wecc_regc.offDelay.u,WTG4A.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,WTG4A.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,WTG4A.wecc_reec.currentLimitsCalculation1.vDip,WTG4A.wecc_reec.iqInjectionLogic.vDipInjEndTime,WTG4A.wecc_reec.iqInjectionLogic.vDip,line.running,$whenCondition2,$whenCondition1,$whenCondition3,$whenCondition5,$whenCondition4,$whenCondition7,$whenCondition6,$whenCondition9,$whenCondition8,$whenCondition11,$whenCondition10,$whenCondition13,$whenCondition12,$whenCondition14,$whenCondition16,$whenCondition15,$whenCondition17,$whenCondition19,$whenCondition18,$whenCondition20,WTG4A.wecc_wtgt.dOmegaGenerator.local_reset,WTG4A.wecc_wtgt.dOmegaTurbine.local_reset,WTG4A.wecc_wtgt.dPhi.local_reset,WTG4A.wecc_repc.limPIDFreeze.I.resetLocal,WTG4A.wecc_repc.limPID.I.local_reset,WTG4A.pll.limIntegrator.local_reset,WTG4A.pll.integrator.local_reset,WTG4A.wecc_reec.varLimPIDFreeze.I.resetLocal,WTG4A.wecc_reec.limPIDFreeze.I.resetLocal)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (500):
 * Single equations (assignments): 498
 * Array equations: 0
 * Algorithm blocks: 1
 * 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
   {(10,4,46.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.001077/0.5724, allocations: 0.5569 MB / 0.8004 GB, free: 10.39 MB / 0.6198 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008909/0.5733, allocations: 421.3 kB / 0.8008 GB, free: 9.973 MB / 0.6198 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005595/0.5789, allocations: 3.658 MB / 0.8044 GB, free: 6.293 MB / 0.6198 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.543e-05/0.5789, allocations: 24 kB / 0.8044 GB, free: 6.27 MB / 0.6198 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 9.438e-06/0.5789, allocations: 4 kB / 0.8044 GB, free: 6.266 MB / 0.6198 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.636e-05/0.5789, allocations: 11.95 kB / 0.8044 GB, free: 6.254 MB / 0.6198 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01073/0.5897, allocations: 7.447 MB / 0.8117 GB, free: 14.67 MB / 0.6355 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.174e-05/0.5897, allocations: 11.92 kB / 0.8117 GB, free: 14.66 MB / 0.6355 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001592/0.5913, allocations: 0.5076 MB / 0.8122 GB, free: 14.15 MB / 0.6355 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0005133/0.5918, allocations: 368.7 kB / 0.8125 GB, free: 13.79 MB / 0.6355 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001675/0.5919, allocations: 71.97 kB / 0.8126 GB, free: 13.72 MB / 0.6355 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.003122/0.5951, allocations: 2.983 MB / 0.8155 GB, free: 10.62 MB / 0.6355 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.119e-06/0.5951, allocations: 11.94 kB / 0.8155 GB, free: 10.61 MB / 0.6355 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.008243/0.6033, allocations: 5.76 MB / 0.8212 GB, free: 4.691 MB / 0.6355 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002098/0.6054, allocations: 0.8207 MB / 0.822 GB, free: 3.867 MB / 0.6355 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004863/0.6059, allocations: 75.98 kB / 0.822 GB, free: 3.793 MB / 0.6355 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001289/0.6072, allocations: 223.8 kB / 0.8223 GB, free: 3.574 MB / 0.6355 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003951/0.6076, allocations: 261.4 kB / 0.8225 GB, free: 3.32 MB / 0.6355 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001334/0.6077, allocations: 91.95 kB / 0.8226 GB, free: 3.23 MB / 0.6355 GB
Notification: Performance of sorting global known variables: time 0.003064/0.6108, allocations: 2.447 MB / 0.825 GB, free: 0.7734 MB / 0.6355 GB
Notification: Performance of sort global known variables: time 1.91e-07/0.6108, allocations: 0 / 0.825 GB, free: 0.7734 MB / 0.6355 GB
Notification: Performance of remove unused functions: time 0.003712/0.6145, allocations: 1.696 MB / 0.8266 GB, free: 15.07 MB / 0.6511 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 23
 * Number of states: 23 (WTG4A.wecc_reec.firstOrder.y,WTG4A.wecc_reec.firstOrder1.y,WTG4A.wecc_reec.limPIDFreeze.I.y,WTG4A.wecc_reec.rateLimFirstOrderFreeze1.y,WTG4A.wecc_reec.slewRateLimiter.y,WTG4A.wecc_reec.rateLimFirstOrderFreeze.y,WTG4A.wecc_reec.varLimPIDFreeze.I.y,WTG4A.wecc_regc.rateLimFirstOrderFreeze2.y,WTG4A.wecc_regc.rateLimFirstOrderFreeze1.y,WTG4A.wecc_regc.firstOrder.y,WTG4A.pll.integrator.y,WTG4A.pll.limIntegrator.y,WTG4A.wecc_repc.firstOrder.y,WTG4A.wecc_repc.limPID.I.y,WTG4A.wecc_repc.firstOrder1.y,WTG4A.wecc_repc.firstOrder2.y,WTG4A.wecc_repc.firstOrder3.y,WTG4A.wecc_repc.limPIDFreeze.I.y,WTG4A.wecc_repc.leadLag.x_scaled[1],WTG4A.wecc_wtgt.dPhi.y,WTG4A.wecc_wtgt.dOmegaTurbine.y,WTG4A.wecc_wtgt.dOmegaGenerator.y,WTG4A.wecc_wtgt.Pmech.y)
 * Number of discrete variables: 40 ($whenCondition20,$whenCondition18,$whenCondition19,$whenCondition17,$whenCondition15,$whenCondition16,$whenCondition14,$whenCondition12,$whenCondition13,$whenCondition10,$whenCondition11,$whenCondition8,$whenCondition9,$whenCondition6,$whenCondition7,$whenCondition4,$whenCondition5,$whenCondition3,$whenCondition1,$whenCondition2,line.running,line.state,WTG4A.wecc_reec.iqInjectionLogic.vDip,WTG4A.wecc_reec.iqInjectionLogic.vDipInjEndTime,WTG4A.wecc_reec.currentLimitsCalculation1.vDip,WTG4A.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,WTG4A.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,WTG4A.wecc_regc.offDelay.u,WTG4A.wecc_regc.offDelay.delaySignal,WTG4A.wecc_regc.offDelay.tNext,WTG4A.wecc_regc.and1.u[2],WTG4A.wecc_regc.and1.y,WTG4A.wecc_regc.and2.y,WTG4A.injector.running,WTG4A.injector.state,WTG4A.LvTfo.running,WTG4A.LvTfo.state,WTG4A.HvTfo.running,WTG4A.HvTfo.state,WTG4A.wecc_repc.freeze)
 * Number of discrete states: 10 (WTG4A.HvTfo.running,WTG4A.LvTfo.running,WTG4A.injector.running,line.running,WTG4A.wecc_regc.offDelay.u,WTG4A.wecc_reec.currentLimitsCalculation1.vDip,WTG4A.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,WTG4A.wecc_reec.currentLimitsCalculation1.ipMaxPu,WTG4A.wecc_reec.iqInjectionLogic.vDip,WTG4A.wecc_reec.iqInjectionLogic.vDipInjEndTime)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (250):
 * Single equations (assignments): 236
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 13
 * 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
   {(10,4,46.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.008541/0.623, allocations: 6.752 MB / 0.8332 GB, free: 8.301 MB / 0.6511 GB
Notification: Performance of simCode: created initialization part: time 0.01016/0.6332, allocations: 6.62 MB / 0.8397 GB, free: 1.617 MB / 0.6511 GB
Notification: Performance of simCode: created event and clocks part: time 1.014e-05/0.6332, allocations: 0 / 0.8397 GB, free: 1.617 MB / 0.6511 GB
Notification: Performance of simCode: created simulation system equations: time 0.003321/0.6365, allocations: 2.119 MB / 0.8418 GB, free: 15.48 MB / 0.6667 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002556/0.6391, allocations: 0.5682 MB / 0.8423 GB, free: 14.93 MB / 0.6667 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.3279/0.967, allocations: 34.77 MB / 0.8763 GB, free: 169.6 MB / 0.6667 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001317/0.9683, allocations: 1.61 MB / 0.8778 GB, free: 169.4 MB / 0.6667 GB
Notification: Performance of simCode: alias equations: time 0.001743/0.9701, allocations: 0.5222 MB / 0.8784 GB, free: 169.3 MB / 0.6667 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001419/0.9715, allocations: 1.724 MB / 0.88 GB, free: 167.9 MB / 0.6667 GB
Notification: Performance of SimCode: time 1.482e-06/0.9715, allocations: 0 / 0.88 GB, free: 167.9 MB / 0.6667 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.0842/1.056, allocations: 87.76 MB / 0.9657 GB, free: 90.23 MB / 0.6667 GB
Notification: Performance of buildModelFMU: Generate platform static: time 9.133/10.19, allocations: 11.23 kB / 0.9658 GB, free: 90.22 MB / 0.6667 GB
"
[Timeout remaining time 650]
(rm -f Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.pipe ; mkfifo Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.pipe ; head -c 1048576 < Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.pipe >> ../files/Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource_res.mat --tempDir=temp_Dynawo_dev_Dynawo_Examples_Wind_WECC_WTG4ACurrentSource_fmu --startTime=0 --stopTime=20 --stepSize=0.0001 --timeout=50 --tolerance=0.0001 Dynawo_dev_Dynawo_Examples_Wind_WECC_WTG4ACurrentSource.fmu > Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.pipe 2>&1) [Timeout 52.5]
diffSimulationResults("Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/Dynawo/dynawo/sources/Models/Modelica/Dynawo/Examples/references/Dynawo.Examples.Wind.WECC.WTG4ACurrentSource_res.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG4ACurrentSource.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 643]
Reference file matches
[Calling sys.exit(0), Time elapsed: 40.263693265616894]