Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.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.001904/0.001904, allocations: 88.22 kB / 20.23 MB, free: 4.207 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.002332/0.002332, allocations: 157.7 kB / 23.53 MB, free: 0.9023 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.336/1.336, allocations: 158.6 MB / 185.3 MB, free: 2.895 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.6492/0.6492, allocations: 90.63 MB / 328.3 MB, free: 14.59 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) Ignoring simflag -noemit -noHomotopyOnFirstTry=() since the simulation runtime does not accept it Ignoring simflag -noemit -noRestart=() since the simulation runtime does not accept it Ignoring simflag -noemit -noRootFinding=() since the simulation runtime does not accept it Running command: translateModel(Dynawo.Examples.Wind.WECC.WTG3CurrentSource1,tolerance=1e-05,outputFormat="mat",numberOfIntervals=20000,variableFilter="time|WTG3.injector.PInjPu|WTG3.injector.QInjPu|WTG3.injector.UPu",fileNamePrefix="Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1") translateModel(Dynawo.Examples.Wind.WECC.WTG3CurrentSource1,tolerance=1e-05,outputFormat="mat",numberOfIntervals=20000,variableFilter="time|WTG3.injector.PInjPu|WTG3.injector.QInjPu|WTG3.injector.UPu",fileNamePrefix="Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1") [Timeout 660] "Notification: Performance of FrontEnd - loaded program: time 1.513e-06/1.513e-06, allocations: 0 / 433.8 MB, free: 5.445 MB / 410.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 2.364e-05/2.516e-05, allocations: 2.312 kB / 433.8 MB, free: 5.441 MB / 410.7 MB Notification: Performance of NFInst.instantiate(Dynawo.Examples.Wind.WECC.WTG3CurrentSource1): time 0.02265/0.02267, allocations: 16.07 MB / 449.9 MB, free: 5.285 MB / 426.7 MB Notification: Performance of NFInst.instExpressions: time 0.01595/0.03862, allocations: 7.804 MB / 457.7 MB, free: 13.46 MB / 442.7 MB Notification: Performance of NFInst.updateImplicitVariability: time 0.003041/0.04166, allocations: 115.2 kB / 457.8 MB, free: 13.34 MB / 442.7 MB Notification: Performance of NFTyping.typeComponents: time 0.002816/0.04447, allocations: 1.089 MB / 458.9 MB, free: 12.25 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.005218/0.04969, allocations: 1.68 MB / 460.6 MB, free: 10.55 MB / 442.7 MB [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:66:5-70:11:writable] Warning: In relation WTG3.HvTfo.terminal1.V.re == 0.0, == on Real operands is deprecated in non-function contexts. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:66:5-70:11:writable] Warning: In relation WTG3.HvTfo.terminal1.V.im == 0.0, == on Real operands is deprecated in non-function contexts. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:71:5-75:11:writable] Warning: In relation WTG3.HvTfo.terminal2.V.re == 0.0, == on Real operands is deprecated in non-function contexts. [/home/hudson/saved_omc/libraries/.openmodelica/libraries/Dynawo master/Electrical/Transformers/TransformersFixedTap/TransformerFixedRatio.mo:71:5-75:11:writable] Warning: In relation WTG3.HvTfo.terminal2.V.im == 0.0, == on Real operands is deprecated in non-function contexts. [/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.008068/0.05776, allocations: 3.724 MB / 464.3 MB, free: 6.824 MB / 442.7 MB Notification: Performance of NFFlatten.flatten: time 0.009865/0.06762, allocations: 6.686 MB / 471 MB, free: 124 kB / 442.7 MB Notification: Performance of NFFlatten.resolveConnections: time 0.1613/0.2289, allocations: 1.497 MB / 472.5 MB, free: 55.42 MB / 442.7 MB Notification: Performance of NFEvalConstants.evaluate: time 0.002787/0.2317, allocations: 2.261 MB / 474.8 MB, free: 55.42 MB / 442.7 MB Notification: Performance of NFSimplifyModel.simplify: time 0.002271/0.234, allocations: 1.98 MB / 476.7 MB, free: 55.42 MB / 442.7 MB Notification: Performance of NFPackage.collectConstants: time 0.001217/0.2352, allocations: 0.6995 MB / 477.4 MB, free: 55.42 MB / 442.7 MB Notification: Performance of NFFlatten.collectFunctions: time 0.001621/0.2368, allocations: 1.028 MB / 478.5 MB, free: 55.42 MB / 442.7 MB Notification: Performance of NFScalarize.scalarize: time 0.001003/0.2378, allocations: 1.488 MB / 480 MB, free: 55.21 MB / 442.7 MB Notification: Performance of NFVerifyModel.verify: time 0.001774/0.2396, allocations: 1.784 MB / 481.7 MB, free: 54.94 MB / 442.7 MB Notification: Performance of NFConvertDAE.convert: time 0.008047/0.2477, allocations: 8.808 MB / 490.5 MB, free: 54.31 MB / 442.7 MB Notification: Performance of FrontEnd - DAE generated: time 5.551e-06/0.2477, allocations: 0 / 490.5 MB, free: 54.31 MB / 442.7 MB Notification: Performance of FrontEnd: time 1.383e-06/0.2477, allocations: 0.5625 kB / 490.5 MB, free: 54.31 MB / 442.7 MB Notification: Performance of Transformations before backend: time 0.0001955/0.2479, allocations: 0.9688 kB / 490.5 MB, free: 54.31 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: 889 * Number of variables: 889 Notification: Performance of Generate backend data structure: time 0.01918/0.267, allocations: 6.866 MB / 497.4 MB, free: 50.87 MB / 442.7 MB Notification: Performance of prepare preOptimizeDAE: time 3.639e-05/0.2671, allocations: 8.656 kB / 497.4 MB, free: 50.87 MB / 442.7 MB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006094/0.2732, allocations: 1.615 MB / 499 MB, free: 50.31 MB / 442.7 MB Notification: Performance of preOpt evaluateParameters (simulation): time 0.006202/0.2794, allocations: 3.484 MB / 0.4907 GB, free: 48.42 MB / 442.7 MB Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0007645/0.2801, allocations: 1.286 MB / 0.492 GB, free: 47.12 MB / 442.7 MB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0009902/0.2811, allocations: 379.8 kB / 0.4924 GB, free: 47 MB / 442.7 MB Notification: Performance of preOpt clockPartitioning (simulation): time 0.0108/0.2919, allocations: 4.902 MB / 0.4971 GB, free: 44.34 MB / 442.7 MB Notification: Performance of preOpt findStateOrder (simulation): time 7.856e-05/0.292, allocations: 8.531 kB / 0.4972 GB, free: 44.34 MB / 442.7 MB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.000454/0.2925, allocations: 151.7 kB / 0.4973 GB, free: 44.23 MB / 442.7 MB Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.571e-05/0.2925, allocations: 87.25 kB / 0.4974 GB, free: 44.23 MB / 442.7 MB Notification: Performance of preOpt removeEqualRHS (simulation): time 0.009327/0.3018, allocations: 3.703 MB / 0.501 GB, free: 42.89 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.02373/0.3256, allocations: 13.16 MB / 0.5138 GB, free: 34.93 MB / 442.7 MB Notification: Performance of preOpt comSubExp (simulation): time 0.00784/0.3334, allocations: 3.405 MB / 0.5172 GB, free: 33.63 MB / 442.7 MB Notification: Performance of preOpt resolveLoops (simulation): time 0.003323/0.3367, allocations: 1.555 MB / 0.5187 GB, free: 32.83 MB / 442.7 MB Notification: Performance of preOpt evalFunc (simulation): time 0.0002605/0.337, allocations: 60.55 kB / 0.5187 GB, free: 32.82 MB / 442.7 MB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004269/0.3413, allocations: 2.209 MB / 0.5209 GB, free: 31.51 MB / 442.7 MB Notification: Performance of pre-optimization done (n=291): time 6.372e-06/0.3413, allocations: 0 / 0.5209 GB, free: 31.51 MB / 442.7 MB Notification: Performance of matching and sorting (n=291): time 0.01448/0.3557, allocations: 5.626 MB / 0.5264 GB, free: 28.67 MB / 442.7 MB Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001234/0.3559, allocations: 0.5736 MB / 0.527 GB, free: 28 MB / 442.7 MB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006664/0.3625, allocations: 3.92 MB / 0.5308 GB, free: 24.98 MB / 442.7 MB Notification: Performance of collectPreVariables (initialization): time 0.0005714/0.3631, allocations: 89.7 kB / 0.5309 GB, free: 24.89 MB / 442.7 MB Notification: Performance of collectInitialEqns (initialization): time 0.001656/0.3648, allocations: 2.048 MB / 0.5329 GB, free: 22.86 MB / 442.7 MB Notification: Performance of collectInitialBindings (initialization): time 0.001101/0.3659, allocations: 1.036 MB / 0.5339 GB, free: 21.84 MB / 442.7 MB Notification: Performance of simplifyInitialFunctions (initialization): time 0.001768/0.3676, allocations: 0.8155 MB / 0.5347 GB, free: 21.01 MB / 442.7 MB Notification: Performance of setup shared object (initialization): time 3.391e-05/0.3677, allocations: 305.1 kB / 0.535 GB, free: 20.71 MB / 442.7 MB Notification: Performance of preBalanceInitialSystem (initialization): time 0.00384/0.3715, allocations: 1.957 MB / 0.5369 GB, free: 18.73 MB / 442.7 MB Notification: Performance of partitionIndependentBlocks (initialization): time 0.004329/0.3758, allocations: 3.127 MB / 0.5399 GB, free: 15.25 MB / 442.7 MB Notification: Performance of analyzeInitialSystem (initialization): time 0.02895/0.4048, allocations: 9.851 MB / 0.5496 GB, free: 5.219 MB / 442.7 MB Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.472e-05/0.4048, allocations: 0 / 0.5496 GB, free: 5.219 MB / 442.7 MB Notification: Performance of matching and sorting (n=579) (initialization): time 0.01265/0.4174, allocations: 5.842 MB / 0.5553 GB, free: 15.37 MB / 458.7 MB Notification: Performance of prepare postOptimizeDAE: time 0.000119/0.4176, allocations: 69.66 kB / 0.5553 GB, free: 15.3 MB / 458.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.993e-05/0.4176, allocations: 12 kB / 0.5553 GB, free: 15.29 MB / 458.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.001416/0.419, allocations: 447.6 kB / 0.5558 GB, free: 14.85 MB / 458.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007419/0.4264, allocations: 1.416 MB / 0.5572 GB, free: 13.48 MB / 458.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006025/0.4325, allocations: 2.993 MB / 0.5601 GB, free: 10.41 MB / 458.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004656/0.4371, allocations: 0.5014 MB / 0.5606 GB, free: 9.91 MB / 458.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0007745/0.4379, allocations: 160 kB / 0.5607 GB, free: 9.754 MB / 458.7 MB Warning: Assuming fixed start value for the following 36 variables: WTG3.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG3.LvTfo.State0 fixed = true ) \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined) WTG3.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG3.injector.State0 fixed = true ) \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined) WTG3.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG3.HvTfo.State0 fixed = true ) \"Transformer 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 WTG3.HvTfo.running:DISCRETE(flow=false start = WTG3.HvTfo.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean WTG3.wecc_wtgp.limitedIntegrator.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.limitedIntegrator.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgp.limitedIntegrator1.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.limitedIntegrator1.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgq.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_wtgq.firstOrder.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgq.firstOrder1.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.omegaRefWTGQPu0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgq.limitedIntegratorFreeze.y:VARIABLE(flow=false start = WTG3.wecc_wtgq.limitedIntegratorFreeze.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = WTG3.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG3.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_regc.firstOrder.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true ) type: Boolean WTG3.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true ) type: Real WTG3.wecc_repc.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder1.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder2.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder3.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG3.wecc_repc.limPIDFreeze.I.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = WTG3.wecc_repc.leadLag.X0[1] * WTG3.wecc_repc.leadLag.a_end fixed = true protected = true ) \"Scaled vector x\" type: Real [1] WTG3.injector.running:DISCRETE(flow=false start = WTG3.injector.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean WTG3.wecc_reec.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_reec.UConv0Pu fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = WTG3.wecc_reec.firstOrder1.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG3.wecc_reec.limPIDFreeze.I.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG3.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.PConv0Pu fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = WTG3.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.iqInjectionLogic.vDip:DISCRETE(flow=false start = false fixed = true ) \"Ongoing voltage dip\" type: Boolean WTG3.wecc_reec.iqInjectionLogic.vDipInjEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true ) \"ending time of the voltage dip start (in seconds)\" type: Real WTG3.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu:DISCRETE(start = 0.0 unit = \"1\" fixed = true ) type: Real WTG3.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true ) type: Real WTG3.LvTfo.running:DISCRETE(flow=false start = WTG3.LvTfo.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean WTG3.wecc_reec.currentLimitsCalculation1.vDip:DISCRETE(flow=false fixed = true ) \"Ongoing voltage dip\" type: Boolean WTG3.wecc_regc.offDelay.u:DISCRETE(flow=false fixed = true ) \"Boolean input signal\" type: Boolean Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007875/0.4458, allocations: 2.79 MB / 0.5634 GB, free: 6.977 MB / 458.7 MB Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.006756/0.4525, allocations: 3.071 MB / 0.5664 GB, free: 3.551 MB / 458.7 MB Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.03613/0.4886, allocations: 9.709 MB / 0.5759 GB, free: 9.629 MB / 474.7 MB Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.93e-05/0.4887, allocations: 4 kB / 0.5759 GB, free: 9.625 MB / 474.7 MB Notification: Performance of matching and sorting (n=579) (initialization_lambda0): time 0.01737/0.506, allocations: 5.733 MB / 0.5815 GB, free: 3.883 MB / 474.7 MB Notification: Performance of prepare postOptimizeDAE: time 0.0001374/0.5062, allocations: 73.59 kB / 0.5816 GB, free: 3.805 MB / 474.7 MB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.579e-05/0.5062, allocations: 12 kB / 0.5816 GB, free: 3.793 MB / 474.7 MB Notification: Performance of postOpt tearingSystem (initialization): time 0.001465/0.5077, allocations: 444.2 kB / 0.582 GB, free: 3.359 MB / 474.7 MB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.007595/0.5153, allocations: 1.392 MB / 0.5834 GB, free: 2.02 MB / 474.7 MB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006069/0.5213, allocations: 2.991 MB / 0.5863 GB, free: 14.94 MB / 490.7 MB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004695/0.526, allocations: 492.2 kB / 0.5868 GB, free: 14.46 MB / 490.7 MB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.000787/0.5268, allocations: 152 kB / 0.5869 GB, free: 14.31 MB / 490.7 MB Warning: Assuming fixed start value for the following 36 variables: WTG3.LvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG3.LvTfo.State0 fixed = true ) \"Transformer connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined) WTG3.injector.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG3.injector.State0 fixed = true ) \"Injector connection state\" type: enumeration(Open, Closed, Closed1, Closed2, Closed3, Undefined) WTG3.HvTfo.state:DISCRETE(min = Dynawo.Electrical.Constants.state.Open max = Dynawo.Electrical.Constants.state.Undefined start = WTG3.HvTfo.State0 fixed = true ) \"Transformer 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 WTG3.HvTfo.running:DISCRETE(flow=false start = WTG3.HvTfo.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean WTG3.wecc_wtgp.limitedIntegrator.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.limitedIntegrator.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgp.limitedIntegrator1.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.limitedIntegrator1.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgq.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_wtgq.firstOrder.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgq.firstOrder1.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.omegaRefWTGQPu0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_wtgq.limitedIntegratorFreeze.y:VARIABLE(flow=false start = WTG3.wecc_wtgq.limitedIntegratorFreeze.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.rateLimFirstOrderFreeze2.y:VARIABLE(flow=false start = WTG3.wecc_regc.rateLimFirstOrderFreeze2.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG3.wecc_regc.rateLimFirstOrderFreeze1.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_regc.firstOrder.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_regc.offDelay.delaySignal:DISCRETE(start = false fixed = true protected = true ) type: Boolean WTG3.wecc_regc.offDelay.tNext:DISCRETE(start = -1.0 unit = \"s\" fixed = true protected = true ) type: Real WTG3.wecc_repc.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.firstOrder1.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder1.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.firstOrder2.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder2.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.firstOrder3.y:VARIABLE(flow=false start = WTG3.wecc_repc.firstOrder3.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG3.wecc_repc.limPIDFreeze.I.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_repc.leadLag.x_scaled[1]:VARIABLE(start = WTG3.wecc_repc.leadLag.X0[1] * WTG3.wecc_repc.leadLag.a_end fixed = true protected = true ) \"Scaled vector x\" type: Real [1] WTG3.injector.running:DISCRETE(flow=false start = WTG3.injector.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean WTG3.wecc_reec.firstOrder.y:VARIABLE(flow=false start = WTG3.wecc_reec.UConv0Pu fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.firstOrder1.y:VARIABLE(flow=false start = WTG3.wecc_reec.firstOrder1.y_start fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.limPIDFreeze.I.y:VARIABLE(flow=false start = WTG3.wecc_reec.limPIDFreeze.I.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.rateLimFirstOrderFreeze1.y:VARIABLE(flow=false start = WTG3.wecc_reec.rateLimFirstOrderFreeze1.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.rateLimFirstOrderFreeze.y:VARIABLE(flow=false start = WTG3.wecc_wtgp.PConv0Pu fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.varLimPIDFreeze.I.y:VARIABLE(flow=false start = WTG3.wecc_reec.varLimPIDFreeze.I.Y0 fixed = true ) \"Connector of Real output signal\" type: Real WTG3.wecc_reec.iqInjectionLogic.vDip:DISCRETE(flow=false start = false fixed = true ) \"Ongoing voltage dip\" type: Boolean WTG3.wecc_reec.iqInjectionLogic.vDipInjEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true ) \"ending time of the voltage dip start (in seconds)\" type: Real WTG3.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu:DISCRETE(start = 0.0 unit = \"1\" fixed = true ) type: Real WTG3.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime:DISCRETE(start = -1.0 unit = \"s\" fixed = true ) type: Real WTG3.LvTfo.running:DISCRETE(flow=false start = WTG3.LvTfo.Running0 fixed = true ) \"Indicates if the component is running or not\" type: Boolean WTG3.wecc_reec.currentLimitsCalculation1.vDip:DISCRETE(flow=false fixed = true ) \"Ongoing voltage dip\" type: Boolean WTG3.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: 53 * Number of states: 0 () * Number of discrete variables: 75 ($PRE.WTG3.LvTfo.state,WTG3.LvTfo.state,$PRE.WTG3.injector.state,WTG3.injector.state,$PRE.WTG3.HvTfo.state,WTG3.HvTfo.state,$PRE.line.state,line.state,WTG3.injector.switchOffSignal3,WTG3.injector.switchOffSignal2,WTG3.injector.switchOffSignal1,line.switchOffSignal2,line.switchOffSignal1,WTG3.wecc_regc.rateLimFirstOrderFreeze1.local_freeze,WTG3.wecc_regc.rateLimFirstOrderFreeze2.local_freeze,$PRE.line.running,$PRE.WTG3.HvTfo.running,$PRE.WTG3.wecc_regc.offDelay.delaySignal,$PRE.WTG3.wecc_regc.offDelay.tNext,$PRE.WTG3.injector.running,$PRE.WTG3.wecc_reec.iqInjectionLogic.vDip,$PRE.WTG3.wecc_reec.iqInjectionLogic.vDipInjEndTime,$PRE.WTG3.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,$PRE.WTG3.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,$PRE.WTG3.LvTfo.running,WTG3.wecc_regc.QConv0PuNeg.k,WTG3.wecc_regc.QConv0PuPos.k,$PRE.WTG3.wecc_reec.currentLimitsCalculation1.vDip,$PRE.WTG3.wecc_regc.offDelay.u,WTG3.LvTfo.running,WTG3.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,WTG3.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,WTG3.wecc_reec.currentLimitsCalculation1.vDip,WTG3.wecc_reec.iqInjectionLogic.vDipInjEndTime,WTG3.wecc_reec.iqInjectionLogic.vDip,WTG3.injector.running,WTG3.wecc_repc.freeze,WTG3.wecc_regc.and2.y,WTG3.wecc_regc.and1.y,WTG3.wecc_regc.and1.u[2],WTG3.wecc_regc.offDelay.tNext,WTG3.wecc_regc.offDelay.delaySignal,WTG3.wecc_regc.offDelay.u,WTG3.HvTfo.running,line.running,$whenCondition2,$whenCondition1,$whenCondition3,$whenCondition5,$whenCondition4,$whenCondition6,$whenCondition8,$whenCondition7,$whenCondition10,$whenCondition9,$whenCondition11,$whenCondition13,$whenCondition12,$whenCondition15,$whenCondition14,$whenCondition17,$whenCondition16,$whenCondition19,$whenCondition18,$whenCondition20,WTG3.wecc_reec.varLimPIDFreeze.I.resetLocal,WTG3.wecc_reec.limPIDFreeze.I.resetLocal,WTG3.pll.limIntegrator.local_reset,WTG3.pll.integrator.local_reset,WTG3.wecc_repc.limPIDFreeze.I.resetLocal,WTG3.wecc_repc.limPID.I.local_reset,WTG3.wecc_wtgt.dOmegaGenerator.local_reset,WTG3.wecc_wtgt.dOmegaTurbine.local_reset,WTG3.wecc_wtgt.dPhi.local_reset,WTG3.wecc_wtgp.absLimRateLimFirstOrderFreeze.integrator.local_reset) * Number of discrete states: 0 () * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (541): * Single equations (assignments): 539 * 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.001713/0.5285, allocations: 0.5877 MB / 0.5875 GB, free: 13.79 MB / 490.7 MB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001717/0.5303, allocations: 0.5284 MB / 0.588 GB, free: 13.26 MB / 490.7 MB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0125/0.5428, allocations: 4.182 MB / 0.5921 GB, free: 9.027 MB / 490.7 MB Notification: Performance of postOpt inlineArrayEqn (simulation): time 3.337e-05/0.5428, allocations: 28 kB / 0.5921 GB, free: 9 MB / 490.7 MB Notification: Performance of postOpt constantLinearSystem (simulation): time 1.388e-05/0.5428, allocations: 4 kB / 0.5921 GB, free: 8.996 MB / 490.7 MB Notification: Performance of postOpt simplifysemiLinear (simulation): time 4.424e-05/0.5428, allocations: 15.94 kB / 0.5921 GB, free: 8.98 MB / 490.7 MB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.02161/0.5645, allocations: 8.208 MB / 0.6002 GB, free: 0.7227 MB / 490.7 MB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.026e-05/0.5645, allocations: 4 kB / 0.6002 GB, free: 0.7188 MB / 490.7 MB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.006718/0.5712, allocations: 1.069 MB / 0.6012 GB, free: 15.68 MB / 0.4948 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.001415/0.5726, allocations: 436.5 kB / 0.6016 GB, free: 15.25 MB / 0.4948 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.000597/0.5732, allocations: 75.97 kB / 0.6017 GB, free: 15.17 MB / 0.4948 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.00573/0.5789, allocations: 2.977 MB / 0.6046 GB, free: 12.11 MB / 0.4948 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 6.603e-06/0.5789, allocations: 4 kB / 0.6046 GB, free: 12.11 MB / 0.4948 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0187/0.5976, allocations: 6.687 MB / 0.6111 GB, free: 5.25 MB / 0.4948 GB Notification: Performance of postOpt removeConstants (simulation): time 0.002957/0.6006, allocations: 0.8906 MB / 0.612 GB, free: 4.355 MB / 0.4948 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001108/0.6017, allocations: 87.98 kB / 0.6121 GB, free: 4.27 MB / 0.4948 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003256/0.605, allocations: 263.7 kB / 0.6123 GB, free: 4.012 MB / 0.4948 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001073/0.606, allocations: 312.2 kB / 0.6126 GB, free: 3.711 MB / 0.4948 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0003386/0.6064, allocations: 99.95 kB / 0.6127 GB, free: 3.613 MB / 0.4948 GB Notification: Performance of sorting global known variables: time 0.005617/0.612, allocations: 2.541 MB / 0.6152 GB, free: 1.066 MB / 0.4948 GB Notification: Performance of sort global known variables: time 1.1e-07/0.612, allocations: 0 / 0.6152 GB, free: 1.066 MB / 0.4948 GB Notification: Performance of remove unused functions: time 0.006495/0.6185, allocations: 1.972 MB / 0.6171 GB, free: 15.09 MB / 0.5105 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 24 * Number of states: 28 (WTG3.wecc_wtgp.limitedIntegrator.y,WTG3.wecc_wtgp.limitedIntegrator1.y,WTG3.wecc_wtgp.absLimRateLimFirstOrderFreeze.integrator.y,WTG3.wecc_wtgq.firstOrder.y,WTG3.wecc_wtgq.firstOrder1.y,WTG3.wecc_wtgq.limitedIntegratorFreeze.y,WTG3.wecc_wtgt.dPhi.y,WTG3.wecc_wtgt.dOmegaTurbine.y,WTG3.wecc_wtgt.dOmegaGenerator.y,WTG3.wecc_regc.rateLimFirstOrderFreeze2.y,WTG3.wecc_regc.rateLimFirstOrderFreeze1.y,WTG3.wecc_regc.firstOrder.y,WTG3.wecc_repc.firstOrder.y,WTG3.wecc_repc.limPID.I.y,WTG3.wecc_repc.firstOrder1.y,WTG3.wecc_repc.firstOrder2.y,WTG3.wecc_repc.firstOrder3.y,WTG3.wecc_repc.limPIDFreeze.I.y,WTG3.wecc_repc.leadLag.x_scaled[1],WTG3.pll.integrator.y,WTG3.pll.limIntegrator.y,WTG3.wecc_reec.firstOrder.y,WTG3.wecc_reec.firstOrder1.y,WTG3.wecc_reec.limPIDFreeze.I.y,WTG3.wecc_reec.rateLimFirstOrderFreeze1.y,WTG3.wecc_reec.slewRateLimiter.y,WTG3.wecc_reec.rateLimFirstOrderFreeze.y,WTG3.wecc_reec.varLimPIDFreeze.I.y) * Number of discrete variables: 40 ($whenCondition20,$whenCondition18,$whenCondition19,$whenCondition16,$whenCondition17,$whenCondition14,$whenCondition15,$whenCondition12,$whenCondition13,$whenCondition11,$whenCondition9,$whenCondition10,$whenCondition7,$whenCondition8,$whenCondition6,$whenCondition4,$whenCondition5,$whenCondition3,$whenCondition1,$whenCondition2,line.running,line.state,WTG3.HvTfo.running,WTG3.HvTfo.state,WTG3.wecc_regc.offDelay.u,WTG3.wecc_regc.offDelay.delaySignal,WTG3.wecc_regc.offDelay.tNext,WTG3.wecc_regc.and1.u[2],WTG3.wecc_regc.and1.y,WTG3.wecc_regc.and2.y,WTG3.wecc_repc.freeze,WTG3.injector.running,WTG3.injector.state,WTG3.wecc_reec.iqInjectionLogic.vDip,WTG3.wecc_reec.iqInjectionLogic.vDipInjEndTime,WTG3.wecc_reec.currentLimitsCalculation1.vDip,WTG3.wecc_reec.currentLimitsCalculation1.ipMaxFrzPu,WTG3.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,WTG3.LvTfo.running,WTG3.LvTfo.state) * Number of discrete states: 10 (WTG3.LvTfo.running,WTG3.injector.running,WTG3.HvTfo.running,line.running,WTG3.wecc_regc.offDelay.u,WTG3.wecc_reec.currentLimitsCalculation1.vDip,WTG3.wecc_reec.currentLimitsCalculation1.vDipFrzEndTime,WTG3.wecc_reec.currentLimitsCalculation1.ipMaxPu,WTG3.wecc_reec.iqInjectionLogic.vDip,WTG3.wecc_reec.iqInjectionLogic.vDipInjEndTime) * Number of clocked states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (288): * Single equations (assignments): 274 * 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.002292/0.6208, allocations: 0.555 MB / 0.6177 GB, free: 14.75 MB / 0.5105 GB Notification: Performance of simCode: created initialization part: time 0.01951/0.6403, allocations: 7.369 MB / 0.6249 GB, free: 7.457 MB / 0.5105 GB Notification: Performance of simCode: created event and clocks part: time 1.102e-05/0.6403, allocations: 7.859 kB / 0.6249 GB, free: 7.449 MB / 0.5105 GB Notification: Performance of simCode: created simulation system equations: time 0.007286/0.6476, allocations: 2.574 MB / 0.6274 GB, free: 4.926 MB / 0.5105 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004946/0.6525, allocations: 0.7309 MB / 0.6281 GB, free: 4.219 MB / 0.5105 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.02284/0.6754, allocations: 8.957 MB / 0.6369 GB, free: 11.13 MB / 0.5261 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.003199/0.6786, allocations: 1.876 MB / 0.6387 GB, free: 9.219 MB / 0.5261 GB Notification: Performance of simCode: alias equations: time 0.003639/0.6822, allocations: 0.5731 MB / 0.6393 GB, free: 8.641 MB / 0.5261 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.00127/0.6835, allocations: 272.4 kB / 0.6395 GB, free: 8.375 MB / 0.5261 GB Notification: Performance of SimCode: time 1.102e-06/0.6835, allocations: 0 / 0.6395 GB, free: 8.375 MB / 0.5261 GB Notification: Performance of Templates: time 0.2429/0.9264, allocations: 52.3 MB / 0.6906 GB, free: 170.2 MB / 0.5417 GB " [Timeout remaining time 659] make -j1 -f Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.makefile [Timeout 660] (rm -f Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.pipe ; mkfifo Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.pipe ; head -c 1048576 < Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.pipe >> ../files/Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.sim & ./Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1 -lv=LOG_STDOUT,LOG_ASSERT,LOG_STATS -s=ida -maxIntegrationOrder=2 -nls=kinsol -initialStepSize=0.00001 -maxStepSize=10 -variableFilter=.* -abortSlowSimulation -alarm=480 -emit_protected -lv LOG_STATS > Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.pipe 2>&1) [Timeout 480] diffSimulationResults("Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1_res.mat","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/ReferenceFiles/Dynawo/dynawo/sources/Models/Modelica/Dynawo/Examples/references/Dynawo.Examples.Wind.WECC.WTG3CurrentSource1_res.csv","/var/lib/jenkins/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Dynawo_dev_Dynawo.Examples.Wind.WECC.WTG3CurrentSource1.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 660] "" [Timeout remaining time 659] Reference file matches [Calling sys.exit(0), Time elapsed: 9.07998109201435]