Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_dev_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS.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.0008019/0.0008019, allocations: 72.5 kB / 19.93 MB, free: 4.633 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.0008457/0.0008457, allocations: 177.9 kB / 23.21 MB, free: 1.348 MB / 18.57 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 0.883/0.883, allocations: 177.1 MB / 203.5 MB, free: 5.746 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo): time 0.04398/0.04398, allocations: 12.51 MB / 272.5 MB, free: 1.133 MB / 250.7 MB
"
[Timeout remaining time 180]
Using package PowerGrids with version 2.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 2.0.0-develop/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+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(PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=500,variableFilter="AVR.Ka|AVR.Ta|AVR.Tb|AVR.Tc|AVR.VcPuStart|AVR.ViMax|AVR.ViMin|AVR.VrMax|AVR.VrMin|AVR.addIng.k1|AVR.addIng.k2|AVR.addIng.k3|AVR.delta|AVR.firstOrderLim.G.k|AVR.firstOrderLim.Gk.k|AVR.firstOrderLim.I.initType|AVR.firstOrderLim.I.k|AVR.firstOrderLim.I.y_start|AVR.firstOrderLim.T|AVR.firstOrderLim.initType|AVR.firstOrderLim.k|AVR.firstOrderLim.lim.homotopyType|AVR.firstOrderLim.lim.limitsAtInit|AVR.firstOrderLim.lim.strict|AVR.firstOrderLim.lim.uMax|AVR.firstOrderLim.lim.uMin|AVR.firstOrderLim.strict|AVR.firstOrderLim.yMax|AVR.firstOrderLim.yMin|AVR.firstOrderLim.yStart|AVR.fixInitialControlledVariable|AVR.fixInitialControlledVariableDefault|AVR.inputLimiter.homotopyType|AVR.inputLimiter.limitsAtInit|AVR.inputLimiter.strict|AVR.inputLimiter.uMax|AVR.inputLimiter.uMin|AVR.largeReal.k|AVR.largeReal.y|AVR.leadLag.T1|AVR.leadLag.T2|AVR.leadLag.firstOrder.T|AVR.leadLag.firstOrder.initType|AVR.leadLag.firstOrder.k|AVR.leadLag.firstOrder.y_start|AVR.leadLag.gain.k|AVR.leadLag.initType|AVR.leadLag.k|AVR.leadLag.noDynamics|AVR.leadLag.yStart|AVR.oversaturationPu|AVR.variableLimiter.homotopyType|AVR.variableLimiter.limit1|AVR.variableLimiter.limitsAtInit|AVR.variableLimiter.strict|AVR.variableLimiter.ySimplified|GEN.DPu|GEN.H|GEN.IStartPu|GEN.LDPu|GEN.LQ1Pu|GEN.LQ2Pu|GEN.LdPu|GEN.LfPu|GEN.LqPu|GEN.MdPu|GEN.MqPu|GEN.PNom|GEN.PStart|GEN.QStart|GEN.SNom|GEN.T1dPu|GEN.T1qPu|GEN.T2dPu|GEN.T2qPu|GEN.T3dPu|GEN.T3qPu|GEN.T4dPu|GEN.T4qPu|GEN.T5dPu|GEN.T5qPu|GEN.T6dPu|GEN.T6qPu|GEN.Tpd|GEN.Tpd0|GEN.Tppd|GEN.Tppd0|GEN.Tppq|GEN.Tppq0|GEN.Tpq|GEN.Tpq0|GEN.UNom|GEN.UPhaseStart|GEN.UStart|GEN.VStartPu|GEN.cosPhiStart|GEN.excitationPuType|GEN.idPuStart|GEN.ifPuStart|GEN.initOpt|GEN.iqPuStart|GEN.kuf|GEN.lambdadPuStart|GEN.lambdaqPuStart|GEN.localInit|GEN.mrcPu|GEN.neglectTransformerTerms|GEN.omega|GEN.omegaBase|GEN.port.IBase|GEN.port.IStart|GEN.port.PStart|GEN.port.QStart|GEN.port.SBase|GEN.port.SNom|GEN.port.UBase|GEN.port.UNom|GEN.port.UPhaseStart|GEN.port.UStart|GEN.port.VBase|GEN.port.VStart|GEN.port.i.im|GEN.port.i.re|GEN.port.iStart.im|GEN.port.iStart.re|GEN.port.portVariablesPhases|GEN.port.v.im|GEN.port.v.re|GEN.port.vStart.im|GEN.port.vStart.re|GEN.portVariablesPhases|GEN.rDPu|GEN.rQ1Pu|GEN.rQ2Pu|GEN.raPu|GEN.referenceGenerator|GEN.rfPu|GEN.sinPhiStart|GEN.thetaStart|GEN.timeConstApprox|GEN.udPuStart|GEN.ufPuInStart|GEN.ufPuStart|GEN.uqPuStart|GEN.xdPu|GEN.xlPu|GEN.xpdPu|GEN.xppdPu|GEN.xppqPu|GEN.xpqPu|GEN.xqPu|GRID.PStart|GRID.QStart|GRID.R_X|GRID.SNom|GRID.SSC|GRID.Ssc.im|GRID.Ssc.re|GRID.UNom|GRID.UPhaseRef|GRID.UPhaseStart|GRID.URef|GRID.UStart|GRID.ZGrid.im|GRID.ZGrid.re|GRID.c|GRID.e.im|GRID.e.re|GRID.eSource.im|GRID.eSource.re|GRID.eStart.im|GRID.eStart.re|GRID.i.im|GRID.i.re|GRID.localInit|GRID.port.IBase|GRID.port.IStart|GRID.port.PStart|GRID.port.QStart|GRID.port.SBase|GRID.port.SNom|GRID.port.UBase|GRID.port.UNom|GRID.port.UPhaseStart|GRID.port.UStart|GRID.port.VBase|GRID.port.VStart|GRID.port.i.im|GRID.port.i.re|GRID.port.iStart.im|GRID.port.iStart.re|GRID.port.portVariablesPhases|GRID.port.v.im|GRID.port.v.re|GRID.port.vStart.im|GRID.port.vStart.re|GRID.portVariablesPhases|GRIDL.PRef|GRIDL.PRefConst|GRIDL.PStart|GRIDL.QRef|GRIDL.QRefConst|GRIDL.QStart|GRIDL.SNom|GRIDL.UNom|GRIDL.UPhaseStart|GRIDL.URef|GRIDL.UStart|GRIDL.i.im|GRIDL.i.re|GRIDL.localInit|GRIDL.port.IBase|GRIDL.port.IStart|GRIDL.port.PStart|GRIDL.port.QStart|GRIDL.port.SBase|GRIDL.port.SNom|GRIDL.port.UBase|GRIDL.port.UNom|GRIDL.port.UPhaseStart|GRIDL.port.UStart|GRIDL.port.VBase|GRIDL.port.VStart|GRIDL.port.i.im|GRIDL.port.i.re|GRIDL.port.iStart.im|GRIDL.port.iStart.re|GRIDL.port.portVariablesPhases|GRIDL.port.v.im|GRIDL.port.v.re|GRIDL.port.vStart.im|GRIDL.port.vStart.re|GRIDL.portVariablesPhases|NTHV.PStart|NTHV.QStart|NTHV.R|NTHV.SNom|NTHV.UNom|NTHV.UPhaseStart|NTHV.UStart|NTHV.X|NTHV.i.im|NTHV.i.re|NTHV.localInit|NTHV.port.IBase|NTHV.port.IStart|NTHV.port.PStart|NTHV.port.QStart|NTHV.port.SBase|NTHV.port.SNom|NTHV.port.UBase|NTHV.port.UNom|NTHV.port.UPhaseStart|NTHV.port.UStart|NTHV.port.VBase|NTHV.port.VStart|NTHV.port.i.im|NTHV.port.i.re|NTHV.port.iStart.im|NTHV.port.iStart.re|NTHV.port.portVariablesPhases|NTHV.port.v.im|NTHV.port.v.re|NTHV.port.vStart.im|NTHV.port.vStart.re|NTHV.portVariablesPhases|NTHV.startTime|NTHV.stopTime|NTHV.v.im|NTHV.v.re|NTLV.PStart|NTLV.QStart|NTLV.SNom|NTLV.UNom|NTLV.UPhaseStart|NTLV.UStart|NTLV.localInit|NTLV.port.IBase|NTLV.port.IStart|NTLV.port.PStart|NTLV.port.QStart|NTLV.port.SBase|NTLV.port.SNom|NTLV.port.UBase|NTLV.port.UNom|NTLV.port.UPhaseStart|NTLV.port.UStart|NTLV.port.VBase|NTLV.port.VStart|NTLV.port.i.im|NTLV.port.i.re|NTLV.port.iStart.im|NTLV.port.iStart.re|NTLV.port.portVariablesPhases|NTLV.port.v.im|NTLV.port.v.re|NTLV.port.vStart.im|NTLV.port.vStart.re|NTLV.portVariablesPhases|PSS.Ks1|PSS.Ks2|PSS.Ks3|PSS.M|PSS.N|PSS.T1|PSS.T2|PSS.T3|PSS.T4|PSS.T6|PSS.T7|PSS.T8|PSS.T9|PSS.Tw1|PSS.Tw2|PSS.Tw3|PSS.Tw4|PSS.VstMax|PSS.VstMin|PSS.add1.k1|PSS.add1.k2|PSS.add2.k1|PSS.add2.k2|PSS.deltaPu.u2|PSS.firstOrderVsi1.T|PSS.firstOrderVsi1.gain.k|PSS.firstOrderVsi1.initType|PSS.firstOrderVsi1.k|PSS.firstOrderVsi1.noDynamics|PSS.firstOrderVsi1.y_start|PSS.firstOrderVsi2.T|PSS.firstOrderVsi2.firstOrder1.T|PSS.firstOrderVsi2.firstOrder1.initType|PSS.firstOrderVsi2.firstOrder1.k|PSS.firstOrderVsi2.firstOrder1.y_start|PSS.firstOrderVsi2.initType|PSS.firstOrderVsi2.k|PSS.firstOrderVsi2.noDynamics|PSS.firstOrderVsi2.y_start|PSS.firstWashoutVsi1.T1|PSS.firstWashoutVsi1.Td|PSS.firstWashoutVsi1.gain.k|PSS.firstWashoutVsi1.initType|PSS.firstWashoutVsi1.integrator.initType|PSS.firstWashoutVsi1.integrator.k|PSS.firstWashoutVsi1.integrator.y_start|PSS.firstWashoutVsi1.noDynamics|PSS.firstWashoutVsi1.yStart|PSS.firstWashoutVsi2.T1|PSS.firstWashoutVsi2.Td|PSS.firstWashoutVsi2.gain.k|PSS.firstWashoutVsi2.initType|PSS.firstWashoutVsi2.integrator.initType|PSS.firstWashoutVsi2.integrator.k|PSS.firstWashoutVsi2.integrator.y_start|PSS.firstWashoutVsi2.noDynamics|PSS.firstWashoutVsi2.yStart|PSS.gainKs1.k|PSS.gainKs3.k|PSS.leadLag1.T1|PSS.leadLag1.T2|PSS.leadLag1.firstOrder.T|PSS.leadLag1.firstOrder.initType|PSS.leadLag1.firstOrder.k|PSS.leadLag1.firstOrder.y_start|PSS.leadLag1.gain.k|PSS.leadLag1.initType|PSS.leadLag1.k|PSS.leadLag1.noDynamics|PSS.leadLag1.yStart|PSS.leadLag2.T1|PSS.leadLag2.T2|PSS.leadLag2.firstOrder.T|PSS.leadLag2.firstOrder.initType|PSS.leadLag2.firstOrder.k|PSS.leadLag2.firstOrder.y_start|PSS.leadLag2.gain.k|PSS.leadLag2.initType|PSS.leadLag2.k|PSS.leadLag2.noDynamics|PSS.leadLag2.yStart|PSS.limiterVst.homotopyType|PSS.limiterVst.limitsAtInit|PSS.limiterVst.strict|PSS.limiterVst.uMax|PSS.limiterVst.uMin|PSS.rampTrackingFilter.M|PSS.rampTrackingFilter.N|PSS.rampTrackingFilter.T1|PSS.rampTrackingFilter.T2|PSS.rampTrackingFilter.gain.k|PSS.rampTrackingFilter.initType|PSS.rampTrackingFilter.k|PSS.rampTrackingFilter.yStart|PSS.refPu.k|PSS.refPu.y|PSS.secondWashoutVsi1.T1|PSS.secondWashoutVsi1.Td|PSS.secondWashoutVsi1.gain.k|PSS.secondWashoutVsi1.initType|PSS.secondWashoutVsi1.integrator.initType|PSS.secondWashoutVsi1.integrator.k|PSS.secondWashoutVsi1.integrator.y_start|PSS.secondWashoutVsi1.noDynamics|PSS.secondWashoutVsi1.yStart|PSS.secondWashoutVsi2.T1|PSS.secondWashoutVsi2.Td|PSS.secondWashoutVsi2.gain.k|PSS.secondWashoutVsi2.initType|PSS.secondWashoutVsi2.noDynamics|PSS.secondWashoutVsi2.yStart|TGEN.B|TGEN.G|TGEN.PStartA|TGEN.PStartB|TGEN.QStartA|TGEN.QStartB|TGEN.R|TGEN.SNom|TGEN.UNomA|TGEN.UNomB|TGEN.UPhaseStartA|TGEN.UPhaseStartB|TGEN.UStartA|TGEN.UStartB|TGEN.X|TGEN.YB.im|TGEN.YB.re|TGEN.computePowerBalance|TGEN.iA.im|TGEN.iA.re|TGEN.portA.IBase|TGEN.portA.IStart|TGEN.portA.PStart|TGEN.portA.QStart|TGEN.portA.SBase|TGEN.portA.SNom|TGEN.portA.UBase|TGEN.portA.UNom|TGEN.portA.UPhaseStart|TGEN.portA.UStart|TGEN.portA.VBase|TGEN.portA.VStart|TGEN.portA.iStart.im|TGEN.portA.iStart.re|TGEN.portA.portVariablesPhases|TGEN.portA.vStart.im|TGEN.portA.vStart.re|TGEN.portB.IBase|TGEN.portB.IStart|TGEN.portB.PStart|TGEN.portB.QStart|TGEN.portB.SBase|TGEN.portB.SNom|TGEN.portB.UBase|TGEN.portB.UNom|TGEN.portB.UPhaseStart|TGEN.portB.UStart|TGEN.portB.VBase|TGEN.portB.VStart|TGEN.portB.iStart.im|TGEN.portB.iStart.re|TGEN.portB.portVariablesPhases|TGEN.portB.vStart.im|TGEN.portB.vStart.re|TGEN.portVariablesPhases|TGEN.rFixed|TGEN.thetaFixed|TGEN.vA.im|TGEN.vA.re|TGOV.Dt|TGOV.PMechPuStart|TGOV.R|TGOV.T1|TGOV.T2|TGOV.T3|TGOV.VMax|TGOV.VMin|TGOV.delta|TGOV.deltaOmegaPu.u2|TGOV.firstOrderLim.G.k|TGOV.firstOrderLim.Gk.k|TGOV.firstOrderLim.I.initType|TGOV.firstOrderLim.I.k|TGOV.firstOrderLim.I.y_start|TGOV.firstOrderLim.T|TGOV.firstOrderLim.initType|TGOV.firstOrderLim.k|TGOV.firstOrderLim.lim.homotopyType|TGOV.firstOrderLim.lim.limitsAtInit|TGOV.firstOrderLim.lim.strict|TGOV.firstOrderLim.lim.uMax|TGOV.firstOrderLim.lim.uMin|TGOV.firstOrderLim.strict|TGOV.firstOrderLim.yMax|TGOV.firstOrderLim.yMin|TGOV.firstOrderLim.yStart|TGOV.fixInitialControlledVariable|TGOV.fixInitialControlledVariableDefault|TGOV.gainDivR.k|TGOV.gainDt.k|TGOV.leadLag.T1|TGOV.leadLag.T2|TGOV.leadLag.firstOrder.T|TGOV.leadLag.firstOrder.initType|TGOV.leadLag.firstOrder.k|TGOV.leadLag.firstOrder.y_start|TGOV.leadLag.gain.k|TGOV.leadLag.initType|TGOV.leadLag.k|TGOV.leadLag.noDynamics|TGOV.leadLag.yStart|TGOV.omegaRefPu.k|TGOV.omegaRefPu.y|TGOV.oversaturationPu|systemPowerGrids.fNom|systemPowerGrids.initOpt|systemPowerGrids.omegaNom|systemPowerGrids.omegaRef|systemPowerGrids.referenceFrequency|time",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS")
translateModel(PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS,tolerance=1e-06,outputFormat="mat",numberOfIntervals=500,variableFilter="AVR.Ka|AVR.Ta|AVR.Tb|AVR.Tc|AVR.VcPuStart|AVR.ViMax|AVR.ViMin|AVR.VrMax|AVR.VrMin|AVR.addIng.k1|AVR.addIng.k2|AVR.addIng.k3|AVR.delta|AVR.firstOrderLim.G.k|AVR.firstOrderLim.Gk.k|AVR.firstOrderLim.I.initType|AVR.firstOrderLim.I.k|AVR.firstOrderLim.I.y_start|AVR.firstOrderLim.T|AVR.firstOrderLim.initType|AVR.firstOrderLim.k|AVR.firstOrderLim.lim.homotopyType|AVR.firstOrderLim.lim.limitsAtInit|AVR.firstOrderLim.lim.strict|AVR.firstOrderLim.lim.uMax|AVR.firstOrderLim.lim.uMin|AVR.firstOrderLim.strict|AVR.firstOrderLim.yMax|AVR.firstOrderLim.yMin|AVR.firstOrderLim.yStart|AVR.fixInitialControlledVariable|AVR.fixInitialControlledVariableDefault|AVR.inputLimiter.homotopyType|AVR.inputLimiter.limitsAtInit|AVR.inputLimiter.strict|AVR.inputLimiter.uMax|AVR.inputLimiter.uMin|AVR.largeReal.k|AVR.largeReal.y|AVR.leadLag.T1|AVR.leadLag.T2|AVR.leadLag.firstOrder.T|AVR.leadLag.firstOrder.initType|AVR.leadLag.firstOrder.k|AVR.leadLag.firstOrder.y_start|AVR.leadLag.gain.k|AVR.leadLag.initType|AVR.leadLag.k|AVR.leadLag.noDynamics|AVR.leadLag.yStart|AVR.oversaturationPu|AVR.variableLimiter.homotopyType|AVR.variableLimiter.limit1|AVR.variableLimiter.limitsAtInit|AVR.variableLimiter.strict|AVR.variableLimiter.ySimplified|GEN.DPu|GEN.H|GEN.IStartPu|GEN.LDPu|GEN.LQ1Pu|GEN.LQ2Pu|GEN.LdPu|GEN.LfPu|GEN.LqPu|GEN.MdPu|GEN.MqPu|GEN.PNom|GEN.PStart|GEN.QStart|GEN.SNom|GEN.T1dPu|GEN.T1qPu|GEN.T2dPu|GEN.T2qPu|GEN.T3dPu|GEN.T3qPu|GEN.T4dPu|GEN.T4qPu|GEN.T5dPu|GEN.T5qPu|GEN.T6dPu|GEN.T6qPu|GEN.Tpd|GEN.Tpd0|GEN.Tppd|GEN.Tppd0|GEN.Tppq|GEN.Tppq0|GEN.Tpq|GEN.Tpq0|GEN.UNom|GEN.UPhaseStart|GEN.UStart|GEN.VStartPu|GEN.cosPhiStart|GEN.excitationPuType|GEN.idPuStart|GEN.ifPuStart|GEN.initOpt|GEN.iqPuStart|GEN.kuf|GEN.lambdadPuStart|GEN.lambdaqPuStart|GEN.localInit|GEN.mrcPu|GEN.neglectTransformerTerms|GEN.omega|GEN.omegaBase|GEN.port.IBase|GEN.port.IStart|GEN.port.PStart|GEN.port.QStart|GEN.port.SBase|GEN.port.SNom|GEN.port.UBase|GEN.port.UNom|GEN.port.UPhaseStart|GEN.port.UStart|GEN.port.VBase|GEN.port.VStart|GEN.port.i.im|GEN.port.i.re|GEN.port.iStart.im|GEN.port.iStart.re|GEN.port.portVariablesPhases|GEN.port.v.im|GEN.port.v.re|GEN.port.vStart.im|GEN.port.vStart.re|GEN.portVariablesPhases|GEN.rDPu|GEN.rQ1Pu|GEN.rQ2Pu|GEN.raPu|GEN.referenceGenerator|GEN.rfPu|GEN.sinPhiStart|GEN.thetaStart|GEN.timeConstApprox|GEN.udPuStart|GEN.ufPuInStart|GEN.ufPuStart|GEN.uqPuStart|GEN.xdPu|GEN.xlPu|GEN.xpdPu|GEN.xppdPu|GEN.xppqPu|GEN.xpqPu|GEN.xqPu|GRID.PStart|GRID.QStart|GRID.R_X|GRID.SNom|GRID.SSC|GRID.Ssc.im|GRID.Ssc.re|GRID.UNom|GRID.UPhaseRef|GRID.UPhaseStart|GRID.URef|GRID.UStart|GRID.ZGrid.im|GRID.ZGrid.re|GRID.c|GRID.e.im|GRID.e.re|GRID.eSource.im|GRID.eSource.re|GRID.eStart.im|GRID.eStart.re|GRID.i.im|GRID.i.re|GRID.localInit|GRID.port.IBase|GRID.port.IStart|GRID.port.PStart|GRID.port.QStart|GRID.port.SBase|GRID.port.SNom|GRID.port.UBase|GRID.port.UNom|GRID.port.UPhaseStart|GRID.port.UStart|GRID.port.VBase|GRID.port.VStart|GRID.port.i.im|GRID.port.i.re|GRID.port.iStart.im|GRID.port.iStart.re|GRID.port.portVariablesPhases|GRID.port.v.im|GRID.port.v.re|GRID.port.vStart.im|GRID.port.vStart.re|GRID.portVariablesPhases|GRIDL.PRef|GRIDL.PRefConst|GRIDL.PStart|GRIDL.QRef|GRIDL.QRefConst|GRIDL.QStart|GRIDL.SNom|GRIDL.UNom|GRIDL.UPhaseStart|GRIDL.URef|GRIDL.UStart|GRIDL.i.im|GRIDL.i.re|GRIDL.localInit|GRIDL.port.IBase|GRIDL.port.IStart|GRIDL.port.PStart|GRIDL.port.QStart|GRIDL.port.SBase|GRIDL.port.SNom|GRIDL.port.UBase|GRIDL.port.UNom|GRIDL.port.UPhaseStart|GRIDL.port.UStart|GRIDL.port.VBase|GRIDL.port.VStart|GRIDL.port.i.im|GRIDL.port.i.re|GRIDL.port.iStart.im|GRIDL.port.iStart.re|GRIDL.port.portVariablesPhases|GRIDL.port.v.im|GRIDL.port.v.re|GRIDL.port.vStart.im|GRIDL.port.vStart.re|GRIDL.portVariablesPhases|NTHV.PStart|NTHV.QStart|NTHV.R|NTHV.SNom|NTHV.UNom|NTHV.UPhaseStart|NTHV.UStart|NTHV.X|NTHV.i.im|NTHV.i.re|NTHV.localInit|NTHV.port.IBase|NTHV.port.IStart|NTHV.port.PStart|NTHV.port.QStart|NTHV.port.SBase|NTHV.port.SNom|NTHV.port.UBase|NTHV.port.UNom|NTHV.port.UPhaseStart|NTHV.port.UStart|NTHV.port.VBase|NTHV.port.VStart|NTHV.port.i.im|NTHV.port.i.re|NTHV.port.iStart.im|NTHV.port.iStart.re|NTHV.port.portVariablesPhases|NTHV.port.v.im|NTHV.port.v.re|NTHV.port.vStart.im|NTHV.port.vStart.re|NTHV.portVariablesPhases|NTHV.startTime|NTHV.stopTime|NTHV.v.im|NTHV.v.re|NTLV.PStart|NTLV.QStart|NTLV.SNom|NTLV.UNom|NTLV.UPhaseStart|NTLV.UStart|NTLV.localInit|NTLV.port.IBase|NTLV.port.IStart|NTLV.port.PStart|NTLV.port.QStart|NTLV.port.SBase|NTLV.port.SNom|NTLV.port.UBase|NTLV.port.UNom|NTLV.port.UPhaseStart|NTLV.port.UStart|NTLV.port.VBase|NTLV.port.VStart|NTLV.port.i.im|NTLV.port.i.re|NTLV.port.iStart.im|NTLV.port.iStart.re|NTLV.port.portVariablesPhases|NTLV.port.v.im|NTLV.port.v.re|NTLV.port.vStart.im|NTLV.port.vStart.re|NTLV.portVariablesPhases|PSS.Ks1|PSS.Ks2|PSS.Ks3|PSS.M|PSS.N|PSS.T1|PSS.T2|PSS.T3|PSS.T4|PSS.T6|PSS.T7|PSS.T8|PSS.T9|PSS.Tw1|PSS.Tw2|PSS.Tw3|PSS.Tw4|PSS.VstMax|PSS.VstMin|PSS.add1.k1|PSS.add1.k2|PSS.add2.k1|PSS.add2.k2|PSS.deltaPu.u2|PSS.firstOrderVsi1.T|PSS.firstOrderVsi1.gain.k|PSS.firstOrderVsi1.initType|PSS.firstOrderVsi1.k|PSS.firstOrderVsi1.noDynamics|PSS.firstOrderVsi1.y_start|PSS.firstOrderVsi2.T|PSS.firstOrderVsi2.firstOrder1.T|PSS.firstOrderVsi2.firstOrder1.initType|PSS.firstOrderVsi2.firstOrder1.k|PSS.firstOrderVsi2.firstOrder1.y_start|PSS.firstOrderVsi2.initType|PSS.firstOrderVsi2.k|PSS.firstOrderVsi2.noDynamics|PSS.firstOrderVsi2.y_start|PSS.firstWashoutVsi1.T1|PSS.firstWashoutVsi1.Td|PSS.firstWashoutVsi1.gain.k|PSS.firstWashoutVsi1.initType|PSS.firstWashoutVsi1.integrator.initType|PSS.firstWashoutVsi1.integrator.k|PSS.firstWashoutVsi1.integrator.y_start|PSS.firstWashoutVsi1.noDynamics|PSS.firstWashoutVsi1.yStart|PSS.firstWashoutVsi2.T1|PSS.firstWashoutVsi2.Td|PSS.firstWashoutVsi2.gain.k|PSS.firstWashoutVsi2.initType|PSS.firstWashoutVsi2.integrator.initType|PSS.firstWashoutVsi2.integrator.k|PSS.firstWashoutVsi2.integrator.y_start|PSS.firstWashoutVsi2.noDynamics|PSS.firstWashoutVsi2.yStart|PSS.gainKs1.k|PSS.gainKs3.k|PSS.leadLag1.T1|PSS.leadLag1.T2|PSS.leadLag1.firstOrder.T|PSS.leadLag1.firstOrder.initType|PSS.leadLag1.firstOrder.k|PSS.leadLag1.firstOrder.y_start|PSS.leadLag1.gain.k|PSS.leadLag1.initType|PSS.leadLag1.k|PSS.leadLag1.noDynamics|PSS.leadLag1.yStart|PSS.leadLag2.T1|PSS.leadLag2.T2|PSS.leadLag2.firstOrder.T|PSS.leadLag2.firstOrder.initType|PSS.leadLag2.firstOrder.k|PSS.leadLag2.firstOrder.y_start|PSS.leadLag2.gain.k|PSS.leadLag2.initType|PSS.leadLag2.k|PSS.leadLag2.noDynamics|PSS.leadLag2.yStart|PSS.limiterVst.homotopyType|PSS.limiterVst.limitsAtInit|PSS.limiterVst.strict|PSS.limiterVst.uMax|PSS.limiterVst.uMin|PSS.rampTrackingFilter.M|PSS.rampTrackingFilter.N|PSS.rampTrackingFilter.T1|PSS.rampTrackingFilter.T2|PSS.rampTrackingFilter.gain.k|PSS.rampTrackingFilter.initType|PSS.rampTrackingFilter.k|PSS.rampTrackingFilter.yStart|PSS.refPu.k|PSS.refPu.y|PSS.secondWashoutVsi1.T1|PSS.secondWashoutVsi1.Td|PSS.secondWashoutVsi1.gain.k|PSS.secondWashoutVsi1.initType|PSS.secondWashoutVsi1.integrator.initType|PSS.secondWashoutVsi1.integrator.k|PSS.secondWashoutVsi1.integrator.y_start|PSS.secondWashoutVsi1.noDynamics|PSS.secondWashoutVsi1.yStart|PSS.secondWashoutVsi2.T1|PSS.secondWashoutVsi2.Td|PSS.secondWashoutVsi2.gain.k|PSS.secondWashoutVsi2.initType|PSS.secondWashoutVsi2.noDynamics|PSS.secondWashoutVsi2.yStart|TGEN.B|TGEN.G|TGEN.PStartA|TGEN.PStartB|TGEN.QStartA|TGEN.QStartB|TGEN.R|TGEN.SNom|TGEN.UNomA|TGEN.UNomB|TGEN.UPhaseStartA|TGEN.UPhaseStartB|TGEN.UStartA|TGEN.UStartB|TGEN.X|TGEN.YB.im|TGEN.YB.re|TGEN.computePowerBalance|TGEN.iA.im|TGEN.iA.re|TGEN.portA.IBase|TGEN.portA.IStart|TGEN.portA.PStart|TGEN.portA.QStart|TGEN.portA.SBase|TGEN.portA.SNom|TGEN.portA.UBase|TGEN.portA.UNom|TGEN.portA.UPhaseStart|TGEN.portA.UStart|TGEN.portA.VBase|TGEN.portA.VStart|TGEN.portA.iStart.im|TGEN.portA.iStart.re|TGEN.portA.portVariablesPhases|TGEN.portA.vStart.im|TGEN.portA.vStart.re|TGEN.portB.IBase|TGEN.portB.IStart|TGEN.portB.PStart|TGEN.portB.QStart|TGEN.portB.SBase|TGEN.portB.SNom|TGEN.portB.UBase|TGEN.portB.UNom|TGEN.portB.UPhaseStart|TGEN.portB.UStart|TGEN.portB.VBase|TGEN.portB.VStart|TGEN.portB.iStart.im|TGEN.portB.iStart.re|TGEN.portB.portVariablesPhases|TGEN.portB.vStart.im|TGEN.portB.vStart.re|TGEN.portVariablesPhases|TGEN.rFixed|TGEN.thetaFixed|TGEN.vA.im|TGEN.vA.re|TGOV.Dt|TGOV.PMechPuStart|TGOV.R|TGOV.T1|TGOV.T2|TGOV.T3|TGOV.VMax|TGOV.VMin|TGOV.delta|TGOV.deltaOmegaPu.u2|TGOV.firstOrderLim.G.k|TGOV.firstOrderLim.Gk.k|TGOV.firstOrderLim.I.initType|TGOV.firstOrderLim.I.k|TGOV.firstOrderLim.I.y_start|TGOV.firstOrderLim.T|TGOV.firstOrderLim.initType|TGOV.firstOrderLim.k|TGOV.firstOrderLim.lim.homotopyType|TGOV.firstOrderLim.lim.limitsAtInit|TGOV.firstOrderLim.lim.strict|TGOV.firstOrderLim.lim.uMax|TGOV.firstOrderLim.lim.uMin|TGOV.firstOrderLim.strict|TGOV.firstOrderLim.yMax|TGOV.firstOrderLim.yMin|TGOV.firstOrderLim.yStart|TGOV.fixInitialControlledVariable|TGOV.fixInitialControlledVariableDefault|TGOV.gainDivR.k|TGOV.gainDt.k|TGOV.leadLag.T1|TGOV.leadLag.T2|TGOV.leadLag.firstOrder.T|TGOV.leadLag.firstOrder.initType|TGOV.leadLag.firstOrder.k|TGOV.leadLag.firstOrder.y_start|TGOV.leadLag.gain.k|TGOV.leadLag.initType|TGOV.leadLag.k|TGOV.leadLag.noDynamics|TGOV.leadLag.yStart|TGOV.omegaRefPu.k|TGOV.omegaRefPu.y|TGOV.oversaturationPu|systemPowerGrids.fNom|systemPowerGrids.initOpt|systemPowerGrids.omegaNom|systemPowerGrids.omegaRef|systemPowerGrids.referenceFrequency|time",fileNamePrefix="PowerGrids_dev_PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.273e-06/1.273e-06, allocations: 0 / 403.6 MB, free: 9.086 MB / 330.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.772e-05/1.9e-05, allocations: 2.281 kB / 403.7 MB, free: 9.082 MB / 330.7 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS): time 0.1758/0.1758, allocations: 38.87 MB / 442.5 MB, free: 15.51 MB / 346.7 MB
Notification: Performance of NFInst.instExpressions: time 0.01845/0.1942, allocations: 11 MB / 453.5 MB, free: 6.809 MB / 346.7 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004955/0.1992, allocations: 168.7 kB / 453.7 MB, free: 6.719 MB / 346.7 MB
Notification: Performance of NFTyping.typeComponents: time 0.003133/0.2023, allocations: 1.496 MB / 455.2 MB, free: 5.5 MB / 346.7 MB
Notification: Performance of NFTyping.typeBindings: time 0.009331/0.2117, allocations: 4.675 MB / 459.9 MB, free: 1.625 MB / 346.7 MB
Notification: Performance of NFTyping.typeClassSections: time 0.004469/0.2161, allocations: 2.892 MB / 462.8 MB, free: 15.03 MB / 362.7 MB
Notification: Performance of NFFlatten.flatten: time 0.009994/0.2261, allocations: 10.41 MB / 473.2 MB, free: 4.59 MB / 362.7 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.00394/0.2301, allocations: 2.805 MB / 476 MB, free: 1.719 MB / 362.7 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.005649/0.2357, allocations: 4.906 MB / 480.9 MB, free: 12.8 MB / 378.7 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.002358/0.2381, allocations: 2.749 MB / 483.6 MB, free: 10.04 MB / 378.7 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005321/0.2386, allocations: 0.5078 MB / 484.1 MB, free: 9.535 MB / 378.7 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.001762/0.2404, allocations: 1.097 MB / 485.2 MB, free: 8.438 MB / 378.7 MB
Notification: Performance of NFScalarize.scalarize: time 0.0006496/0.241, allocations: 1.19 MB / 486.4 MB, free: 7.242 MB / 378.7 MB
Notification: Performance of NFVerifyModel.verify: time 0.001812/0.2428, allocations: 2.224 MB / 488.6 MB, free: 5.012 MB / 378.7 MB
Notification: Performance of NFConvertDAE.convert: time 0.008436/0.2513, allocations: 7.968 MB / 496.6 MB, free: 13.02 MB / 394.7 MB
Notification: Performance of FrontEnd - DAE generated: time 3.957e-06/0.2513, allocations: 0 / 496.6 MB, free: 13.02 MB / 394.7 MB
Notification: Performance of FrontEnd: time 1.614e-06/0.2513, allocations: 0 / 496.6 MB, free: 13.02 MB / 394.7 MB
Notification: Performance of Transformations before backend: time 0.0001476/0.2514, allocations: 0 / 496.6 MB, free: 13.02 MB / 394.7 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 962
 * Number of variables: 962
Notification: Performance of Generate backend data structure: time 0.01351/0.2649, allocations: 7.824 MB / 0.4926 GB, free: 5.121 MB / 394.7 MB
Notification: Performance of prepare preOptimizeDAE: time 4.421e-05/0.265, allocations: 11.97 kB / 0.4926 GB, free: 5.109 MB / 394.7 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002981/0.268, allocations: 1.196 MB / 0.4938 GB, free: 3.91 MB / 394.7 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004274/0.2722, allocations: 3.459 MB / 0.4972 GB, free: 392 kB / 394.7 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0001387/0.2724, allocations: 315.9 kB / 0.4975 GB, free: 76 kB / 394.7 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003642/0.2727, allocations: 412.4 kB / 0.4979 GB, free: 15.67 MB / 410.7 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.004635/0.2774, allocations: 5.495 MB / 0.5032 GB, free: 9.473 MB / 410.7 MB
Notification: Performance of preOpt findStateOrder (simulation): time 5.674e-05/0.2774, allocations: 19.81 kB / 0.5032 GB, free: 9.453 MB / 410.7 MB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002293/0.2776, allocations: 176 kB / 0.5034 GB, free: 9.281 MB / 410.7 MB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.816e-05/0.2777, allocations: 100 kB / 0.5035 GB, free: 9.184 MB / 410.7 MB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003847/0.2815, allocations: 3.556 MB / 0.507 GB, free: 5.605 MB / 410.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.01903/0.3006, allocations: 22.63 MB / 0.5291 GB, free: 14.29 MB / 442.7 MB
Notification: Performance of preOpt comSubExp (simulation): time 0.003554/0.3041, allocations: 2.945 MB / 0.532 GB, free: 11.34 MB / 442.7 MB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001333/0.3055, allocations: 1.245 MB / 0.5332 GB, free: 10.08 MB / 442.7 MB
Notification: Performance of preOpt evalFunc (simulation): time 0.0001155/0.3056, allocations: 50.97 kB / 0.5332 GB, free: 10.03 MB / 442.7 MB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002292/0.3079, allocations: 2.656 MB / 0.5358 GB, free: 7.043 MB / 442.7 MB
Notification: Performance of pre-optimization done (n=328): time 5.461e-06/0.3079, allocations: 3.938 kB / 0.5358 GB, free: 7.039 MB / 442.7 MB
Notification: Performance of matching and sorting (n=328): time 0.01016/0.318, allocations: 5.834 MB / 0.5415 GB, free: 1.16 MB / 442.7 MB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002468/0.3183, allocations: 0.8318 MB / 0.5423 GB, free: 136 kB / 442.7 MB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.004137/0.3224, allocations: 3.986 MB / 0.5462 GB, free: 12.13 MB / 458.7 MB
Notification: Performance of collectPreVariables (initialization): time 0.0002132/0.3226, allocations: 105.7 kB / 0.5463 GB, free: 12.02 MB / 458.7 MB
Notification: Performance of collectInitialEqns (initialization): time 0.001731/0.3244, allocations: 2.219 MB / 0.5485 GB, free: 9.781 MB / 458.7 MB
Notification: Performance of collectInitialBindings (initialization): time 0.001164/0.3255, allocations: 1.314 MB / 0.5498 GB, free: 8.457 MB / 458.7 MB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.00185/0.3274, allocations: 1.704 MB / 0.5514 GB, free: 6.742 MB / 458.7 MB
Notification: Performance of setup shared object (initialization): time 8.285e-05/0.3275, allocations: 305.1 kB / 0.5517 GB, free: 6.441 MB / 458.7 MB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003092/0.3305, allocations: 2.679 MB / 0.5543 GB, free: 3.734 MB / 458.7 MB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.003888/0.3344, allocations: 4.187 MB / 0.5584 GB, free: 15.17 MB / 474.7 MB
Notification: Performance of analyzeInitialSystem (initialization): time 0.005191/0.3396, allocations: 5.259 MB / 0.5636 GB, free: 9.637 MB / 474.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.733e-05/0.3396, allocations: 4 kB / 0.5636 GB, free: 9.633 MB / 474.7 MB
Notification: Performance of matching and sorting (n=1214) (initialization): time 0.01073/0.3504, allocations: 9.091 MB / 0.5725 GB, free: 416 kB / 474.7 MB
Notification: Performance of prepare postOptimizeDAE: time 5.392e-05/0.3504, allocations: 75.33 kB / 0.5725 GB, free: 328 kB / 474.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.594e-05/0.3505, allocations: 16 kB / 0.5725 GB, free: 312 kB / 474.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.005426/0.3559, allocations: 2.088 MB / 0.5746 GB, free: 14.17 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003763/0.3596, allocations: 1.806 MB / 0.5763 GB, free: 12.36 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.1658/0.5255, allocations: 24.61 MB / 0.6004 GB, free: 148.5 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004607/0.5301, allocations: 0.6625 MB / 0.601 GB, free: 148.4 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004576/0.5305, allocations: 308.4 kB / 0.6013 GB, free: 148.3 MB / 490.7 MB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.004307/0.5348, allocations: 3.275 MB / 0.6045 GB, free: 146.7 MB / 490.7 MB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.003558/0.5384, allocations: 4.106 MB / 0.6085 GB, free: 144.2 MB / 490.7 MB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00502/0.5434, allocations: 5.119 MB / 0.6135 GB, free: 141.2 MB / 490.7 MB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.057e-05/0.5434, allocations: 3.719 kB / 0.6135 GB, free: 141.2 MB / 490.7 MB
Notification: Performance of matching and sorting (n=1214) (initialization_lambda0): time 0.008987/0.5524, allocations: 8.601 MB / 0.6219 GB, free: 137.8 MB / 490.7 MB
Notification: Performance of prepare postOptimizeDAE: time 3.764e-05/0.5525, allocations: 65.66 kB / 0.622 GB, free: 137.7 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.274e-05/0.5525, allocations: 15.72 kB / 0.622 GB, free: 137.7 MB / 490.7 MB
Notification: Performance of postOpt tearingSystem (initialization): time 0.004537/0.557, allocations: 1.796 MB / 0.6238 GB, free: 137.5 MB / 490.7 MB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003175/0.5602, allocations: 1.831 MB / 0.6255 GB, free: 137.3 MB / 490.7 MB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.00982/0.57, allocations: 21.75 MB / 0.6468 GB, free: 116.1 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003623/0.5736, allocations: 0.5367 MB / 0.6473 GB, free: 115.8 MB / 490.7 MB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003023/0.5739, allocations: 302.8 kB / 0.6476 GB, free: 115.5 MB / 490.7 MB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 62
 * Number of states: 0 ()
 * Number of discrete variables: 20 (FAULT.fault,$PRE.FAULT.fault,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,FAULT.componentPF.isSlackBus,GRIDL.componentPF.isSlackBus,GRID.componentPF.isSlackBus,NTLV.componentPF.isSlackBus,GEN.componentPF.isSlackBus,FAULT.Y.im,$PRE.FAULT.Y.im,FAULT.Y.re,$PRE.FAULT.Y.re,PSS.firstWashoutVsi2.integrator.local_reset,PSS.secondWashoutVsi1.integrator.local_reset,PSS.firstWashoutVsi1.integrator.local_reset,AVR.firstOrderLim.I.local_reset,TGOV.firstOrderLim.I.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1140):
 * Single equations (assignments): 1124
 * Array equations: 0
 * Algorithm blocks: 2
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 14
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 7 systems
   {(2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (2,0,100.0%), (3,0,66.7%), (3,0,66.7%), (3,0,66.7%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 7 systems
   {(38,0), (19,0), (2,0), (2,0), (2,0), (2,0), (4,0)}
Notification: Performance of prepare postOptimizeDAE: time 0.0009769/0.5749, allocations: 0.6395 MB / 0.6482 GB, free: 114.8 MB / 490.7 MB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0004902/0.5754, allocations: 490.8 kB / 0.6487 GB, free: 114.5 MB / 490.7 MB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.003516/0.5789, allocations: 3.523 MB / 0.6521 GB, free: 111.4 MB / 490.7 MB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.907e-05/0.5789, allocations: 27.58 kB / 0.6522 GB, free: 111.4 MB / 490.7 MB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.32e-06/0.5789, allocations: 4 kB / 0.6522 GB, free: 111.4 MB / 490.7 MB
Notification: Performance of postOpt removeConstants (simulation): time 0.001125/0.5801, allocations: 0.8923 MB / 0.653 GB, free: 110.7 MB / 490.7 MB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0003393/0.5804, allocations: 71.17 kB / 0.6531 GB, free: 110.6 MB / 490.7 MB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001318/0.5817, allocations: 172.4 kB / 0.6533 GB, free: 110.5 MB / 490.7 MB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.000236/0.582, allocations: 259.8 kB / 0.6535 GB, free: 110.4 MB / 490.7 MB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.004093/0.5861, allocations: 4.407 MB / 0.6578 GB, free: 106.4 MB / 490.7 MB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.004007/0.5901, allocations: 5.44 MB / 0.6631 GB, free: 101 MB / 490.7 MB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001097/0.5912, allocations: 1.221 MB / 0.6643 GB, free: 99.81 MB / 490.7 MB
Notification: Performance of sorting global known variables: time 0.001841/0.593, allocations: 2.393 MB / 0.6667 GB, free: 97.41 MB / 490.7 MB
Notification: Performance of Backend: time 3.41e-07/0.593, allocations: 0 / 0.6667 GB, free: 97.41 MB / 490.7 MB
Notification: Performance of simCode: created initialization part: time 0.01386/0.6069, allocations: 13.78 MB / 0.6801 GB, free: 83.3 MB / 490.7 MB
Notification: Performance of SimCode: time 0.008283/0.6151, allocations: 9.962 MB / 0.6898 GB, free: 73.31 MB / 490.7 MB
[CodegenCpp.tpl:12351:11-12351:11:writable] Error: Template error: Unsupported zero crossing at 2: TGOV.firstOrderLim.G.y >= 0.0 and TGOV.firstOrderLim.I.y > TGOV.firstOrderLim.lim.uMax or TGOV.firstOrderLim.G.y <= 0.0 and TGOV.firstOrderLim.I.y < TGOV.firstOrderLim.lim.uMin.
[CodegenCpp.tpl:12351:11-12351:11:writable] Error: Template error: Unsupported zero crossing at 3: AVR.firstOrderLim.G.y >= 0.0 and AVR.firstOrderLim.I.y > AVR.firstOrderLim.lim.uMax or AVR.firstOrderLim.G.y <= 0.0 and AVR.firstOrderLim.I.y < AVR.firstOrderLim.lim.uMin.
Error: Internal error SimCode DAEmode: The model PowerGrids.Examples.Tutorial.GridOperation.Controlled.ControlledGridWithPSS could not be translated
"
[Timeout remaining time 659]
[Calling sys.exit(0), Time elapsed: 2.4980794824659824]
Failed to read output from testmodel.py, exit status != 0:
0.8761157058179379 0.880520865 0.627941893
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x77b8494d53d0>