Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.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.0009873/0.0009873, allocations: 77.97 kB / 21.46 MB, free: 3.043 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.001021/0.001021, allocations: 164 kB / 24.85 MB, free: 8.641 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 0.9171/0.9171, allocations: 158.6 MB / 186.8 MB, free: 2.801 MB / 170.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.4445/0.4445, allocations: 90.57 MB / 329.7 MB, free: 14.58 MB / 314.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)
Running command: translateModel(Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6,tolerance=1e-06,outputFormat="mat",numberOfIntervals=20000,variableFilter="time|Unit.HvMeasurements.PPu|Unit.HvMeasurements.QPu|Unit.HvMeasurements.UPu|Unit.LvMeasurements.PPu|Unit.LvMeasurements.QPu|Unit.LvMeasurements.UPu",fileNamePrefix="Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6")
translateModel(Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6,tolerance=1e-06,outputFormat="mat",numberOfIntervals=20000,variableFilter="time|Unit.HvMeasurements.PPu|Unit.HvMeasurements.QPu|Unit.HvMeasurements.UPu|Unit.LvMeasurements.PPu|Unit.LvMeasurements.QPu|Unit.LvMeasurements.UPu",fileNamePrefix="Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.242e-06/1.242e-06, allocations: 0 / 430.3 MB, free: 10.43 MB / 410.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.268e-05/2.393e-05, allocations: 5.828 kB / 430.3 MB, free: 10.43 MB / 410.7 MB
Notification: Performance of NFInst.instantiate(Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6): time 0.01477/0.01479, allocations: 16.99 MB / 447.3 MB, free: 9.352 MB / 426.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01316/0.02796, allocations: 8.228 MB / 455.5 MB, free: 1.098 MB / 426.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.003556/0.03151, allocations: 119.2 kB / 455.6 MB, free: 0.9805 MB / 426.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.002598/0.03411, allocations: 1.249 MB / 456.9 MB, free: 15.72 MB / 442.7 MB
[/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.005004/0.03911, allocations: 1.844 MB / 458.7 MB, free: 13.87 MB / 442.7 MB
[/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 operands is deprecated in non-function contexts.
Notification: Performance of NFTyping.typeClassSections: time 0.006436/0.04555, allocations: 3.841 MB / 462.5 MB, free: 10.02 MB / 442.7 MB
Notification: Performance of NFFlatten.flatten: time 0.007923/0.05347, allocations: 6.958 MB / 469.5 MB, free: 3.043 MB / 442.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.002729/0.0562, allocations: 1.5 MB / 471 MB, free: 1.473 MB / 442.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.1694/0.2256, allocations: 2.365 MB / 473.4 MB, free: 51.64 MB / 442.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002746/0.2283, allocations: 2.105 MB / 475.5 MB, free: 51.64 MB / 442.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.001812/0.2301, allocations: 0.7041 MB / 476.2 MB, free: 51.64 MB / 442.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001744/0.2319, allocations: 1.073 MB / 477.2 MB, free: 51.64 MB / 442.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0009873/0.2329, allocations: 1.562 MB / 478.8 MB, free: 51.64 MB / 442.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001584/0.2344, allocations: 1.83 MB / 480.6 MB, free: 51.54 MB / 442.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.005407/0.2399, allocations: 4.785 MB / 485.4 MB, free: 50.87 MB / 442.7 MB
Notification: Performance of FrontEnd - DAE generated: time 6.091e-06/0.2399, allocations: 0 / 485.4 MB, free: 50.87 MB / 442.7 MB
Notification: Performance of FrontEnd: time 9.52e-07/0.2399, allocations: 0.9375 kB / 485.4 MB, free: 50.87 MB / 442.7 MB
Notification: Performance of Transformations before backend: time 0.0001027/0.24, allocations: 0 / 485.4 MB, free: 50.87 MB / 442.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 891
 * Number of variables: 891
Notification: Performance of Generate backend data structure: time 0.01008/0.25, allocations: 7.093 MB / 492.5 MB, free: 47.97 MB / 442.7 MB
Notification: Performance of prepare preOptimizeDAE: time 5.169e-05/0.2501, allocations: 10.59 kB / 492.5 MB, free: 47.97 MB / 442.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003091/0.2532, allocations: 1.451 MB / 494 MB, free: 47.62 MB / 442.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004141/0.2573, allocations: 3.334 MB / 497.3 MB, free: 46.7 MB / 442.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004213/0.2577, allocations: 0.9915 MB / 498.3 MB, free: 45.88 MB / 442.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0005104/0.2583, allocations: 373.2 kB / 498.7 MB, free: 45.87 MB / 442.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003848/0.2621, allocations: 3.488 MB / 0.4904 GB, free: 44.42 MB / 442.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.821e-05/0.2622, allocations: 6.156 kB / 0.4904 GB, free: 44.42 MB / 442.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002347/0.2624, allocations: 150.2 kB / 0.4905 GB, free: 44.42 MB / 442.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.557e-05/0.2624, allocations: 84.38 kB / 0.4906 GB, free: 44.42 MB / 442.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001962/0.2644, allocations: 1.19 MB / 0.4918 GB, free: 44.33 MB / 442.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.01405/0.2785, allocations: 13.47 MB / 0.5049 GB, free: 35.76 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.003223/0.2817, allocations: 1.753 MB / 0.5066 GB, free: 35.4 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.000842/0.2825, allocations: 0.5128 MB / 0.5071 GB, free: 35.31 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001794/0.2827, allocations: 69.73 kB / 0.5072 GB, free: 35.3 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001696/0.2844, allocations: 1.162 MB / 0.5083 GB, free: 35.03 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=290): time 5.611e-06/0.2844, allocations: 0 / 0.5083 GB, free: 35.03 MB / 442.7 MB
Notification: Performance of matching and sorting (n=290): time 0.00532/0.2897, allocations: 2.482 MB / 0.5108 GB, free: 34.39 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001249/0.2898, allocations: 0.5993 MB / 0.5114 GB, free: 33.69 MB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004003/0.2939, allocations: 3.433 MB / 0.5147 GB, free: 33.02 MB / 442.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0003173/0.2942, allocations: 100.3 kB / 0.5148 GB, free: 32.95 MB / 442.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001392/0.2956, allocations: 2.329 MB / 0.5171 GB, free: 31.32 MB / 442.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.000762/0.2963, allocations: 1.038 MB / 0.5181 GB, free: 30.58 MB / 442.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0009734/0.2973, allocations: 0.881 MB / 0.519 GB, free: 30.27 MB / 442.7 MB
Notification: Performance of setup shared object (initialization): time 3.951e-05/0.2973, allocations: 480.7 kB / 0.5194 GB, free: 29.8 MB / 442.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001197/0.2985, allocations: 0.7752 MB / 0.5202 GB, free: 29.61 MB / 442.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001588/0.3001, allocations: 2.127 MB / 0.5222 GB, free: 27.98 MB / 442.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003627/0.3037, allocations: 3.315 MB / 0.5255 GB, free: 26.41 MB / 442.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.319e-05/0.3038, allocations: 3.188 kB / 0.5255 GB, free: 26.41 MB / 442.7 MB
Notification: Performance of matching and sorting (n=581) (initialization): time 0.005617/0.3094, allocations: 3.468 MB / 0.5289 GB, free: 25.29 MB / 442.7 MB
Notification: Performance of prepare postOptimizeDAE: time 6.396e-05/0.3094, allocations: 68.08 kB / 0.5289 GB, free: 25.25 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.391e-05/0.3095, allocations: 13.84 kB / 0.529 GB, free: 25.25 MB / 442.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006402/0.3101, allocations: 231.3 kB / 0.5292 GB, free: 25.2 MB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003991/0.3141, allocations: 1.272 MB / 0.5304 GB, free: 25.15 MB / 442.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001886/0.316, allocations: 2.229 MB / 0.5326 GB, free: 23.16 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002574/0.3186, allocations: 450.5 kB / 0.533 GB, free: 22.77 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002616/0.3188, allocations: 121.8 kB / 0.5331 GB, free: 22.65 MB / 442.7 MB
Warning: Assuming fixed start value for the following 35 variables:
         Unit.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Unit.LvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Unit.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Unit.injector.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Unit.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Unit.HvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Unit.HvTfo.running:DISCRETE(flow=false start = Unit.HvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Unit.wecc_wtgp.limitedIntegrator.y:VARIABLE(flow=false start = Unit.wecc_wtgp.limitedIntegrator.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgp.limitedIntegrator1.y:VARIABLE(flow=false start = Unit.wecc_wtgp.limitedIntegrator1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgq.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_wtgq.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgq.firstOrder1.y:VARIABLE(flow=false start = Unit.wecc_wtgp.omegaRefWTGQPu0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgq.limitedIntegratorFreeze.y:VARIABLE(flow=false start = Unit.wecc_wtgq.limitedIntegratorFreeze.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = Unit.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = Unit.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_regc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true )  type: Boolean
         Unit.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true )  type: Real
         Unit.wecc_repc.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = Unit.wecc_repc.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = Unit.wecc_repc.leadLag.X0[1] * Unit.wecc_repc.leadLag.a_end fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         Unit.injector.running:DISCRETE(flow=false start = Unit.injector.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Unit.wecc_reec.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_reec.UConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = Unit.wecc_reec.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = Unit.wecc_reec.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = Unit.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = Unit.wecc_wtgp.PConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = Unit.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.iqInjectionLogic.vDip:DISCRETE(flow=false start = false fixed = true )  \"Ongoing voltage dip\" type: Boolean
         Unit.wecc_reec.iqInjectionLogic.vDipInjEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  \"ending time of the voltage dip start (in seconds)\" type: Real
         Unit.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu:DISCRETE(start = 0.0 unit = \"1\" fixed = true )  type: Real
         Unit.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  type: Real
         Unit.LvTfo.running:DISCRETE(flow=false start = Unit.LvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Unit.Pm0Pu:VARIABLE(unit = \"1\" fixed = true )  \"Initial mechanical power in pu (base SNom)\" type: Real
         Unit.wecc_reec.currentLimitsCalculation1.vDip:DISCRETE(flow=false fixed = true )  \"Ongoing voltage dip\" type: Boolean
         Unit.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true )  \"Boolean input signal\" type: Boolean
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.002766/0.3216, allocations: 1.107 MB / 0.5342 GB, free: 21.72 MB / 442.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001918/0.3235, allocations: 2.125 MB / 0.5363 GB, free: 19.44 MB / 442.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.004428/0.3279, allocations: 3.289 MB / 0.5395 GB, free: 16.23 MB / 442.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.346e-05/0.3279, allocations: 4 kB / 0.5395 GB, free: 16.22 MB / 442.7 MB
Notification: Performance of matching and sorting (n=581) (initialization_lambda0): time 0.005574/0.3335, allocations: 3.467 MB / 0.5429 GB, free: 12.82 MB / 442.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.558e-05/0.3336, allocations: 65.67 kB / 0.543 GB, free: 12.75 MB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.074e-05/0.3336, allocations: 12 kB / 0.543 GB, free: 12.74 MB / 442.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005791/0.3342, allocations: 227.7 kB / 0.5432 GB, free: 12.52 MB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.004021/0.3382, allocations: 1.263 MB / 0.5444 GB, free: 11.31 MB / 442.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001836/0.34, allocations: 2.224 MB / 0.5466 GB, free: 9.02 MB / 442.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002544/0.3426, allocations: 433.9 kB / 0.547 GB, free: 8.594 MB / 442.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002482/0.3428, allocations: 120 kB / 0.5471 GB, free: 8.477 MB / 442.7 MB
Warning: Assuming fixed start value for the following 35 variables:
         Unit.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Unit.LvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Unit.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Unit.injector.State0 fixed = true )  \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Unit.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = Unit.HvTfo.State0 fixed = true )  \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined)
         Unit.HvTfo.running:DISCRETE(flow=false start = Unit.HvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Unit.wecc_wtgp.limitedIntegrator.y:VARIABLE(flow=false start = Unit.wecc_wtgp.limitedIntegrator.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgp.limitedIntegrator1.y:VARIABLE(flow=false start = Unit.wecc_wtgp.limitedIntegrator1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgq.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_wtgq.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgq.firstOrder1.y:VARIABLE(flow=false start = Unit.wecc_wtgp.omegaRefWTGQPu0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_wtgq.limitedIntegratorFreeze.y:VARIABLE(flow=false start = Unit.wecc_wtgq.limitedIntegratorFreeze.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = Unit.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = Unit.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_regc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true )  type: Boolean
         Unit.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true )  type: Real
         Unit.wecc_repc.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder2.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = Unit.wecc_repc.firstOrder3.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = Unit.wecc_repc.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = Unit.wecc_repc.leadLag.X0[1] * Unit.wecc_repc.leadLag.a_end fixed = true protected = true )  \"Scaled vector x\" type: Real [1]
         Unit.injector.running:DISCRETE(flow=false start = Unit.injector.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Unit.wecc_reec.firstOrder.y:VARIABLE(flow=false start = Unit.wecc_reec.UConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = Unit.wecc_reec.firstOrder1.y_start fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = Unit.wecc_reec.limPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = Unit.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = Unit.wecc_wtgp.PConv0Pu fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = Unit.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true )  \"Connector of Real output signal\" type: Real
         Unit.wecc_reec.iqInjectionLogic.vDip:DISCRETE(flow=false start = false fixed = true )  \"Ongoing voltage dip\" type: Boolean
         Unit.wecc_reec.iqInjectionLogic.vDipInjEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  \"ending time of the voltage dip start (in seconds)\" type: Real
         Unit.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu:DISCRETE(start = 0.0 unit = \"1\" fixed = true )  type: Real
         Unit.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true )  type: Real
         Unit.LvTfo.running:DISCRETE(flow=false start = Unit.LvTfo.Running0 fixed = true )  \"Indicates if the component is running or not\" type: Boolean
         Unit.Pm0Pu:VARIABLE(unit = \"1\" fixed = true )  \"Initial mechanical power in pu (base SNom)\" type: Real
         Unit.wecc_reec.currentLimitsCalculation1.vDip:DISCRETE(flow=false fixed = true )  \"Ongoing voltage dip\" type: Boolean
         Unit.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true )  \"Boolean input signal\" type: Boolean
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 54
 * Number of states: 0 ()
 * Number of discrete variables: 81 ($PRE.infiniteBusFromTable.tableOmegaRefPu.nextTimeEvent,$whenCondition20,infiniteBusFromTable.tableOmegaRefPu.nextTimeEventScaled,$PRE.infiniteBusFromTable.tableOmegaRefPu.nextTimeEventScaled,infiniteBusFromTable.tableOmegaRefPu.nextTimeEvent,$PRE.Unit.LvTfo.state,Unit.LvTfo.state,$PRE.Unit.injector.state,Unit.injector.state,$PRE.Unit.HvTfo.state,Unit.HvTfo.state,$PRE.infiniteBusFromTable.tableUPhase.nextTimeEvent,$whenCondition2,$PRE.infiniteBusFromTable.tableUPu.nextTimeEvent,$whenCondition1,Unit.wecc_wtgp.absLimRateLimFirstOrderFreeze.integrator.local_reset,Unit.wecc_wtgt.dPhi.local_reset,Unit.wecc_wtgt.dOmegaTurbine.local_reset,Unit.wecc_wtgt.dOmegaGenerator.local_reset,Unit.wecc_repc.limPID.I.local_reset,Unit.wecc_repc.limPIDFreeze.I.resetLocal,Unit.pll.integrator.local_reset,Unit.pll.limIntegrator.local_reset,Unit.wecc_reec.limPIDFreeze.I.resetLocal,Unit.wecc_reec.varLimPIDFreeze.I.resetLocal,Unit.wecc_regc.rateLimFirstOrderFreeze2.local_freeze,Unit.wecc_regc.rateLimFirstOrderFreeze1.local_freeze,Unit.injector.switchOffSignal1,Unit.injector.switchOffSignal2,Unit.injector.switchOffSignal3,$PRE.Unit.HvTfo.running,$PRE.Unit.wecc_regc.offDelay.delaySignal,$PRE.Unit.wecc_regc.offDelay.tNext,$PRE.Unit.injector.running,$PRE.Unit.wecc_reec.iqInjectionLogic.vDip,$PRE.Unit.wecc_reec.iqInjectionLogic.vDipInjEndTime,$PRE.Unit.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,$PRE.Unit.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,$PRE.Unit.LvTfo.running,Unit.wecc_regc.QConv0PuPos.k,Unit.wecc_regc.QConv0PuNeg.k,$PRE.Unit.wecc_reec.currentLimitsCalculation1.vDip,$PRE.Unit.wecc_regc.offDelay.u,Unit.LvTfo.running,Unit.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,Unit.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,Unit.wecc_reec.currentLimitsCalculation1.vDip,Unit.wecc_reec.iqInjectionLogic.vDipInjEndTime,Unit.wecc_reec.iqInjectionLogic.vDip,Unit.injector.running,Unit.wecc_repc.freeze,Unit.wecc_regc.and2.y,Unit.wecc_regc.and1.y,Unit.wecc_regc.and1.u[2],Unit.wecc_regc.offDelay.tNext,Unit.wecc_regc.offDelay.delaySignal,Unit.wecc_regc.offDelay.u,Unit.HvTfo.running,infiniteBusFromTable.tableUPhase.nextTimeEventScaled,$PRE.infiniteBusFromTable.tableUPhase.nextTimeEventScaled,infiniteBusFromTable.tableUPhase.nextTimeEvent,infiniteBusFromTable.tableUPu.nextTimeEventScaled,$PRE.infiniteBusFromTable.tableUPu.nextTimeEventScaled,infiniteBusFromTable.tableUPu.nextTimeEvent,$whenCondition4,$whenCondition3,$whenCondition5,$whenCondition7,$whenCondition6,$whenCondition9,$whenCondition8,$whenCondition10,$whenCondition12,$whenCondition11,$whenCondition14,$whenCondition13,$whenCondition16,$whenCondition15,$whenCondition18,$whenCondition17,$whenCondition19)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (548):
 * Single equations (assignments): 546
 * 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
   {(6,4,72.2%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0009554/0.3438, allocations: 0.6778 MB / 0.5478 GB, free: 7.863 MB / 442.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008283/0.3446, allocations: 0.5634 MB / 0.5483 GB, free: 7.297 MB / 442.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003573/0.3482, allocations: 2.046 MB / 0.5503 GB, free: 5.211 MB / 442.7 MB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.767e-05/0.3482, allocations: 28 kB / 0.5504 GB, free: 5.184 MB / 442.7 MB
Notification: Performance of postOpt constantLinearSystem (simulation): time 7.654e-06/0.3482, allocations: 4 kB / 0.5504 GB, free: 5.18 MB / 442.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.286e-05/0.3482, allocations: 15.94 kB / 0.5504 GB, free: 5.164 MB / 442.7 MB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.007395/0.3556, allocations: 5.123 MB / 0.5554 GB, free: 308 kB / 442.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.153e-06/0.3556, allocations: 8 kB / 0.5554 GB, free: 300 kB / 442.7 MB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00277/0.3584, allocations: 0.9037 MB / 0.5563 GB, free: 15.42 MB / 458.7 MB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0004824/0.3589, allocations: 219.1 kB / 0.5565 GB, free: 15.2 MB / 458.7 MB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001698/0.3591, allocations: 75.97 kB / 0.5566 GB, free: 15.13 MB / 458.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001695/0.3607, allocations: 2.216 MB / 0.5587 GB, free: 12.83 MB / 458.7 MB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.09e-06/0.3608, allocations: 15.75 kB / 0.5587 GB, free: 12.82 MB / 458.7 MB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.004644/0.3654, allocations: 3.172 MB / 0.5618 GB, free: 9.496 MB / 458.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.00135/0.3667, allocations: 0.908 MB / 0.5627 GB, free: 8.586 MB / 458.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004021/0.3672, allocations: 83.98 kB / 0.5628 GB, free: 8.504 MB / 458.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001153/0.3683, allocations: 195.8 kB / 0.563 GB, free: 8.312 MB / 458.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003785/0.3687, allocations: 292.6 kB / 0.5633 GB, free: 8.031 MB / 458.7 MB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001499/0.3688, allocations: 83.91 kB / 0.5633 GB, free: 7.949 MB / 458.7 MB
Notification: Performance of sorting global known variables: time 0.00184/0.3707, allocations: 1.861 MB / 0.5652 GB, free: 6.277 MB / 458.7 MB
Notification: Performance of sort global known variables: time 2.11e-07/0.3707, allocations: 0 / 0.5652 GB, free: 6.277 MB / 458.7 MB
Notification: Performance of remove unused functions: time 0.003521/0.3742, allocations: 1.995 MB / 0.5671 GB, free: 4.277 MB / 458.7 MB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 24
 * Number of states: 28 (Unit.wecc_wtgp.limitedIntegrator.y,Unit.wecc_wtgp.limitedIntegrator1.y,Unit.wecc_wtgp.absLimRateLimFirstOrderFreeze.integrator.y,Unit.wecc_wtgq.firstOrder.y,Unit.wecc_wtgq.firstOrder1.y,Unit.wecc_wtgq.limitedIntegratorFreeze.y,Unit.wecc_wtgt.dPhi.y,Unit.wecc_wtgt.dOmegaTurbine.y,Unit.wecc_wtgt.dOmegaGenerator.y,Unit.wecc_regc.rateLimFirstOrderFreeze2.y,Unit.wecc_regc.rateLimFirstOrderFreeze1.y,Unit.wecc_regc.firstOrder.y,Unit.wecc_repc.firstOrder.y,Unit.wecc_repc.limPID.I.y,Unit.wecc_repc.firstOrder1.y,Unit.wecc_repc.firstOrder2.y,Unit.wecc_repc.firstOrder3.y,Unit.wecc_repc.limPIDFreeze.I.y,Unit.wecc_repc.leadLag.x_scaled[1],Unit.pll.integrator.y,Unit.pll.limIntegrator.y,Unit.wecc_reec.firstOrder.y,Unit.wecc_reec.firstOrder1.y,Unit.wecc_reec.limPIDFreeze.I.y,Unit.wecc_reec.rateLimFirstOrderFreeze1.y,Unit.wecc_reec.slewRateLimiter.y,Unit.wecc_reec.rateLimFirstOrderFreeze.y,Unit.wecc_reec.varLimPIDFreeze.I.y)
 * Number of discrete variables: 44 ($whenCondition19,$whenCondition17,$whenCondition18,$whenCondition15,$whenCondition16,$whenCondition13,$whenCondition14,$whenCondition11,$whenCondition12,$whenCondition10,$whenCondition8,$whenCondition9,$whenCondition6,$whenCondition7,$whenCondition5,$whenCondition3,$whenCondition4,$whenCondition2,$whenCondition1,infiniteBusFromTable.tableUPu.nextTimeEvent,infiniteBusFromTable.tableUPu.nextTimeEventScaled,infiniteBusFromTable.tableUPhase.nextTimeEvent,infiniteBusFromTable.tableUPhase.nextTimeEventScaled,Unit.HvTfo.running,Unit.HvTfo.state,Unit.wecc_regc.offDelay.u,Unit.wecc_regc.offDelay.delaySignal,Unit.wecc_regc.offDelay.tNext,Unit.wecc_regc.and1.u[2],Unit.wecc_regc.and1.y,Unit.wecc_regc.and2.y,Unit.wecc_repc.freeze,Unit.injector.running,Unit.injector.state,Unit.wecc_reec.iqInjectionLogic.vDip,Unit.wecc_reec.iqInjectionLogic.vDipInjEndTime,Unit.wecc_reec.currentLimitsCalculation1.vDip,Unit.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,Unit.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,Unit.LvTfo.running,Unit.LvTfo.state,$whenCondition20,infiniteBusFromTable.tableOmegaRefPu.nextTimeEvent,infiniteBusFromTable.tableOmegaRefPu.nextTimeEventScaled)
 * Number of discrete states: 15 (infiniteBusFromTable.tableOmegaRefPu.nextTimeEventScaled,infiniteBusFromTable.tableOmegaRefPu.nextTimeEvent,Unit.LvTfo.running,Unit.injector.running,Unit.HvTfo.running,infiniteBusFromTable.tableUPhase.nextTimeEvent,infiniteBusFromTable.tableUPu.nextTimeEvent,infiniteBusFromTable.tableUPhase.nextTimeEventScaled,infiniteBusFromTable.tableUPu.nextTimeEventScaled,Unit.wecc_regc.offDelay.u,Unit.wecc_reec.currentLimitsCalculation1.vDip,Unit.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,Unit.wecc_reec.currentLimitsCalculation1.ipMaxPu,Unit.wecc_reec.iqInjectionLogic.vDip,Unit.wecc_reec.iqInjectionLogic.vDipInjEndTime)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (291):
 * Single equations (assignments): 273
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 17
 * 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
   {(6,4,72.2%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.001974/0.3762, allocations: 0.8056 MB / 0.5679 GB, free: 3.84 MB / 458.7 MB
Notification: Performance of simCode: created initialization part: time 0.006656/0.3828, allocations: 4.437 MB / 0.5722 GB, free: 15.55 MB / 474.7 MB
Notification: Performance of simCode: created event and clocks part: time 1.282e-05/0.3828, allocations: 4 kB / 0.5722 GB, free: 15.54 MB / 474.7 MB
Notification: Performance of simCode: created simulation system equations: time 0.001669/0.3845, allocations: 1.106 MB / 0.5733 GB, free: 14.5 MB / 474.7 MB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.00277/0.3873, allocations: 0.8 MB / 0.5741 GB, free: 13.74 MB / 474.7 MB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01128/0.3986, allocations: 7.902 MB / 0.5818 GB, free: 5.723 MB / 474.7 MB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0008941/0.3994, allocations: 0.757 MB / 0.5825 GB, free: 4.945 MB / 474.7 MB
Notification: Performance of simCode: alias equations: time 0.002287/0.4017, allocations: 0.5666 MB / 0.5831 GB, free: 4.375 MB / 474.7 MB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001712/0.4034, allocations: 0.8337 MB / 0.5839 GB, free: 3.539 MB / 474.7 MB
Notification: Performance of SimCode: time 1.823e-06/0.4034, allocations: 0 / 0.5839 GB, free: 3.539 MB / 474.7 MB
Notification: Performance of Templates: time 0.07538/0.4788, allocations: 59.67 MB / 0.6422 GB, free: 8.34 MB / 0.5261 GB
"
[Timeout remaining time 660]
make -j1 -f Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.makefile [Timeout 660]
(rm -f Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.pipe ; mkfifo Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.pipe ; head -c 1048576 < Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.pipe >> ../files/Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.sim & ./Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6  -lv=LOG_STDOUT,LOG_ASSERT,LOG_STATS -s=ida -variableFilter=.* -abortSlowSimulation -alarm=240  -emit_protected -lv LOG_STATS > Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6.pipe 2>&1) [Timeout 240]
diffSimulationResults("Dynawo_dev_Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/Dynawo/dynawo/sources/Models/Modelica/Dynawo/Examples/references/Dynawo.Examples.GridCodeSimulations.SheetSimulations.SheetI6_res.csv","",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660]
""
[Timeout remaining time 659]
Reference file matches
[Calling sys.exit(0), Time elapsed: 6.092142425943166]