Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.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.002471/0.002471, allocations: 107.3 kB / 20.52 MB, free: 1.301 MB / 14.72 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.002569/0.002569, allocations: 222.5 kB / 23.83 MB, free: 4.438 MB / 14.72 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.61/1.61, allocations: 207.7 MB / 234.7 MB, free: 9.754 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/package.mo): time 0.06514/0.06514, allocations: 14.4 MB / 301.3 MB, free: 7.203 MB / 238.1 MB
"
[Timeout remaining time 180]
Using package PowerGrids with version 1.0.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/PowerGrids 1.0.3/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 -homotopyOnFirstTry=() since it seems broken on HelloWorld
Running command: translateModel(PowerGrids.Examples.ENTSOE.TestCase2,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.iGen.im|GEN.port.iGen.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|LOAD.PRefConst|LOAD.PStart|LOAD.QRefConst|LOAD.QStart|LOAD.SNom|LOAD.UNom|LOAD.UPhaseStart|LOAD.URef|LOAD.UStart|LOAD.i.im|LOAD.i.re|LOAD.localInit|LOAD.port.IBase|LOAD.port.IStart|LOAD.port.PStart|LOAD.port.QStart|LOAD.port.SBase|LOAD.port.SNom|LOAD.port.UBase|LOAD.port.UNom|LOAD.port.UPhaseStart|LOAD.port.UStart|LOAD.port.VBase|LOAD.port.VStart|LOAD.port.i.im|LOAD.port.i.re|LOAD.port.iStart.im|LOAD.port.iStart.re|LOAD.port.portVariablesPhases|LOAD.port.v.im|LOAD.port.v.re|LOAD.port.vStart.im|LOAD.port.vStart.re|LOAD.portVariablesPhases|LOAD.v.im|LOAD.v.re|NGEN.PSlack|NGEN.PStart|NGEN.QSlack|NGEN.QStart|NGEN.SNom|NGEN.UNom|NGEN.UPhaseStart|NGEN.UStart|NGEN.initOpt|NGEN.localInit|NGEN.nStart.im|NGEN.nStart.re|NGEN.port.IBase|NGEN.port.IStart|NGEN.port.P|NGEN.port.PGen|NGEN.port.PStart|NGEN.port.Q|NGEN.port.QGen|NGEN.port.QStart|NGEN.port.S.im|NGEN.port.S.re|NGEN.port.SBase|NGEN.port.SNom|NGEN.port.UBase|NGEN.port.UNom|NGEN.port.UPhaseStart|NGEN.port.UStart|NGEN.port.VBase|NGEN.port.VStart|NGEN.port.i.im|NGEN.port.i.re|NGEN.port.iGen.im|NGEN.port.iGen.re|NGEN.port.iStart.im|NGEN.port.iStart.re|NGEN.port.portVariablesPhases|NGEN.port.v.im|NGEN.port.v.re|NGEN.port.vStart.im|NGEN.port.vStart.re|NGEN.portVariablesPhases|NGEN.setPhaseOnly|PLoad.height|PLoad.offset|PLoad.startTime|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_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2")
translateModel(PowerGrids.Examples.ENTSOE.TestCase2,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.iGen.im|GEN.port.iGen.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|LOAD.PRefConst|LOAD.PStart|LOAD.QRefConst|LOAD.QStart|LOAD.SNom|LOAD.UNom|LOAD.UPhaseStart|LOAD.URef|LOAD.UStart|LOAD.i.im|LOAD.i.re|LOAD.localInit|LOAD.port.IBase|LOAD.port.IStart|LOAD.port.PStart|LOAD.port.QStart|LOAD.port.SBase|LOAD.port.SNom|LOAD.port.UBase|LOAD.port.UNom|LOAD.port.UPhaseStart|LOAD.port.UStart|LOAD.port.VBase|LOAD.port.VStart|LOAD.port.i.im|LOAD.port.i.re|LOAD.port.iStart.im|LOAD.port.iStart.re|LOAD.port.portVariablesPhases|LOAD.port.v.im|LOAD.port.v.re|LOAD.port.vStart.im|LOAD.port.vStart.re|LOAD.portVariablesPhases|LOAD.v.im|LOAD.v.re|NGEN.PSlack|NGEN.PStart|NGEN.QSlack|NGEN.QStart|NGEN.SNom|NGEN.UNom|NGEN.UPhaseStart|NGEN.UStart|NGEN.initOpt|NGEN.localInit|NGEN.nStart.im|NGEN.nStart.re|NGEN.port.IBase|NGEN.port.IStart|NGEN.port.P|NGEN.port.PGen|NGEN.port.PStart|NGEN.port.Q|NGEN.port.QGen|NGEN.port.QStart|NGEN.port.S.im|NGEN.port.S.re|NGEN.port.SBase|NGEN.port.SNom|NGEN.port.UBase|NGEN.port.UNom|NGEN.port.UPhaseStart|NGEN.port.UStart|NGEN.port.VBase|NGEN.port.VStart|NGEN.port.i.im|NGEN.port.i.re|NGEN.port.iGen.im|NGEN.port.iGen.re|NGEN.port.iStart.im|NGEN.port.iStart.re|NGEN.port.portVariablesPhases|NGEN.port.v.im|NGEN.port.v.re|NGEN.port.vStart.im|NGEN.port.vStart.re|NGEN.portVariablesPhases|NGEN.setPhaseOnly|PLoad.height|PLoad.offset|PLoad.startTime|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_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2") [Timeout 660]
"Notification: Performance of FrontEnd - Absyn->SCode: time 2.101e-05/2.101e-05, allocations: 2.281 kB / 425.8 MB, free: 35.41 MB / 318.1 MB
Notification: Performance of NFInst.instantiate(PowerGrids.Examples.ENTSOE.TestCase2): time 0.006494/0.006515, allocations: 7.311 MB / 433.2 MB, free: 28.05 MB / 318.1 MB
Notification: Performance of NFInst.instExpressions: time 0.006/0.01251, allocations: 3.786 MB / 436.9 MB, free: 24.26 MB / 318.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0005948/0.01311, allocations: 39.69 kB / 437 MB, free: 24.22 MB / 318.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.0008882/0.014, allocations: 385.2 kB / 437.4 MB, free: 23.84 MB / 318.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.002802/0.0168, allocations: 1.486 MB / 438.8 MB, free: 22.36 MB / 318.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.001506/0.01831, allocations: 0.8453 MB / 439.7 MB, free: 21.51 MB / 318.1 MB
Notification: Performance of NFFlatten.flatten: time 0.002294/0.0206, allocations: 3.188 MB / 442.9 MB, free: 18.31 MB / 318.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.0004035/0.021, allocations: 223.4 kB / 443.1 MB, free: 18.09 MB / 318.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.0008927/0.0219, allocations: 1.074 MB / 444.2 MB, free: 17.01 MB / 318.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007036/0.0226, allocations: 0.8943 MB / 445.1 MB, free: 16.11 MB / 318.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0001611/0.02276, allocations: 144 kB / 445.2 MB, free: 15.97 MB / 318.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.0007063/0.02347, allocations: 431.7 kB / 445.6 MB, free: 15.55 MB / 318.1 MB
Notification: Performance of combineBinaries: time 0.001968/0.02544, allocations: 3.733 MB / 449.4 MB, free: 11.78 MB / 318.1 MB
Notification: Performance of replaceArrayConstructors: time 0.0008686/0.0263, allocations: 2.114 MB / 451.5 MB, free: 9.641 MB / 318.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0001885/0.02649, allocations: 120 kB / 451.6 MB, free: 9.523 MB / 318.1 MB
Notification: Performance of FrontEnd: time 0.000255/0.02675, allocations: 107.6 kB / 451.7 MB, free: 9.418 MB / 318.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 241 (212)
 * Number of variables: 241 (241)
Notification: Performance of [SIM] Bindings: time 0.005122/0.03187, allocations: 8.432 MB / 460.1 MB, free: 0.7109 MB / 318.1 MB
Notification: Performance of [SIM] FunctionAlias: time 0.0008078/0.03268, allocations: 0.9098 MB / 461 MB, free: 15.76 MB / 334.1 MB
Notification: Performance of [SIM] Early Inline: time 0.005373/0.03805, allocations: 4.647 MB / 465.7 MB, free: 11.03 MB / 334.1 MB
Notification: Performance of [SIM] Simplify 1: time 0.001295/0.03935, allocations: 0.6903 MB / 466.4 MB, free: 10.25 MB / 334.1 MB
Warning: NBAlias.setStartFixed: Alias set with conflicting unfixed start values detected. Use -d=dumprepl for more information.
Notification: Performance of [SIM] Alias: time 0.007332/0.04668, allocations: 4.757 MB / 471.1 MB, free: 4.996 MB / 334.1 MB
Notification: Performance of [SIM] Simplify 2: time 0.0009493/0.04763, allocations: 0.5677 MB / 471.7 MB, free: 4.355 MB / 334.1 MB
Notification: Performance of [SIM] Remove Stream: time 0.0004423/0.04807, allocations: 373.3 kB / 472.1 MB, free: 3.953 MB / 334.1 MB
Notification: Performance of [SIM] Detect States: time 0.001077/0.04915, allocations: 0.9024 MB / 473 MB, free: 2.984 MB / 334.1 MB
Notification: Performance of [SIM] Events: time 0.0003642/0.04951, allocations: 215.9 kB / 473.2 MB, free: 2.777 MB / 334.1 MB
Notification: Performance of [SIM] Partitioning: time 0.001468/0.05098, allocations: 1.193 MB / 474.4 MB, free: 1.539 MB / 334.1 MB
Notification: Performance of [SIM] Causalize: time 0.004016/0.05499, allocations: 2.679 MB / 477.1 MB, free: 14.79 MB / 350.1 MB
Notification: Performance of [SIM] After Index Reduction Inline: time 0.002922/0.05792, allocations: 2.592 MB / 479.6 MB, free: 12.2 MB / 350.1 MB
Notification: Performance of [INI] Simplify: time 0.004166/0.06208, allocations: 2.17 MB / 481.8 MB, free: 9.816 MB / 350.1 MB
Notification: Performance of [INI] Inline: time 0.005845/0.06793, allocations: 5.298 MB / 487.1 MB, free: 4.492 MB / 350.1 MB
Notification: Performance of [INI] Partitioning: time 0.0002203/0.06815, allocations: 101.3 kB / 487.2 MB, free: 4.363 MB / 350.1 MB
Notification: Performance of [INI] Cleanup: time 0.001234/0.06938, allocations: 1.066 MB / 488.3 MB, free: 3.281 MB / 350.1 MB
Notification: Performance of [INI] Causalize: time 0.0174/0.08678, allocations: 11.07 MB / 499.3 MB, free: 7.906 MB / 366.1 MB
Notification: Performance of [INI] Tearing: time 0.002584/0.08936, allocations: 1.774 MB / 0.4894 GB, free: 6.066 MB / 366.1 MB
Notification: Performance of [SIM] Initialization: time 3.065e-06/0.08937, allocations: 4 kB / 0.4894 GB, free: 6.062 MB / 366.1 MB
Notification: Performance of [SIM] DAE-Mode: time 0.001663/0.09103, allocations: 0.8987 MB / 0.4903 GB, free: 5.117 MB / 366.1 MB
Notification: Performance of [SIM] Remove Dummies: time 1.386e-05/0.09104, allocations: 1 kB / 0.4903 GB, free: 5.117 MB / 366.1 MB
Notification: Performance of [SIM] Tearing: time 0.0006402/0.09168, allocations: 462.9 kB / 0.4907 GB, free: 4.668 MB / 366.1 MB
Notification: Performance of [SIM] Categorize: time 2.934e-05/0.09171, allocations: 4 kB / 0.4907 GB, free: 4.664 MB / 366.1 MB
Notification: Performance of [SIM] Solve: time 0.009475/0.1012, allocations: 5.117 MB / 0.4957 GB, free: 15.52 MB / 382.1 MB
Notification: Performance of [SIM] Jacobian: time 0.01697/0.1182, allocations: 12.61 MB / 0.508 GB, free: 2.254 MB / 382.1 MB
Notification: Partition statistics after passing the back-end:
 * Number of ODE partitions: ..................... 0
 * Number of algebraic partitions: ............... 0
 * Number of ODE event partitions: ............... 1
 * Number of algebraic event partitions: ......... 4
 * Number of clocked partitions: ................. 0
 * Number of initial partitions: ................. 1
 * Number of initial(lambda=0) partitions: ....... 1
Notification: Variable statistics after passing the back-end:
 * Number of states: ............................. 10 (10) {GEN.theta, AA_04_GEN_omegaPu, GEN.lambdafPu, GEN.lambdaDPu, GEN.lambdaQ1Pu, GEN.lambdaQ2Pu, TGOV.firstOrderLim.lim.simplifiedExpr, TGOV.leadLag.firstOrder.y, AVR.leadLag.firstOrder.y, AVR.firstOrderLim.lim.simplifiedExpr}
 * Number of discrete states: .................... 0 (0) {}
 * Number of clocked states: ..................... 0 (0) {}
 * Number of discrete variables: ................. 15 (15) {$SEV_13, $SEV_12, $SEV_11, $SEV_10, $SEV_9, $SEV_8, $SEV_7, $SEV_6, $SEV_5, $SEV_4, $SEV_3, $SEV_2, $SEV_1, $SEV_0, $TEV_0}
 * Number of clocks: ............................. 0 (0) {}
 * Number of top-level inputs: ................... 0 (0) {}
Notification: [Simulation] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 123 (scalar:123, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:0)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 1 (linear: 1, nonlinear:0)
Notification: [Initialization] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 241 (scalar:241, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:8)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 12 (linear: 12, nonlinear:0)
Notification: [Initialization (lambda=0)] Strong Component statistics after passing the back-end:
 * Number of single strong components: ........... 255 (scalar:255, array:0, record:0)
 * Number of multi strong components: ............ 0 (algorithm:0, when:0, if:0, tuple:8)
 * Number of for-loop strong components: ......... 0 (resizable: 0, generic: 0, entwined:0)
 * Number of algebraic-loop strong components: ... 10 (linear: 10, nonlinear:0)
Notification: Performance of backend: time 0.0002115/0.1184, allocations: 110.4 kB / 0.5081 GB, free: 2.148 MB / 382.1 MB
Warning: NSimJacobian.SimJacobian.create could not generate sparsity pattern.
Notification: Performance of SimCode: time 0.00976/0.1281, allocations: 6.266 MB / 0.5142 GB, free: 11.74 MB / 398.1 MB
Notification: Performance of Templates: time 0.2933/0.4214, allocations: 20.93 MB / 0.5347 GB, free: 75.88 MB / 398.1 MB
"
[Timeout remaining time 660]
make -j1 -f PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.makefile [Timeout 660]
(rm -f PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.pipe ; mkfifo PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.pipe ; head -c 1048576 < PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.pipe >> ../files/PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.sim & ./PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2  -nls=kinsol -lv=LOG_INIT_HOMOTOPY -abortSlowSimulation -alarm=480  -emit_protected -lv LOG_STATS > PowerGrids_symb_jac_PowerGrids.Examples.ENTSOE.TestCase2.pipe 2>&1) [Timeout 480]
[Calling os._exit(0), Time elapsed: 6.09632357704686]
Failed to read output from testmodel.py, exit status != 0:
0.4292524170014076 0.44277870599999997 0.41228940199999997
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7fbfb7452dd0>