Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Buildings_latest_Buildings.Fluid.CHPs.Validation.ElectricalFollowing.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.0.0-master/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Using package Modelica with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo)
Using package Complex with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo)
Using package Buildings with version 10.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.0.0-master/package.mo)
Running command: translateModel(Buildings.Fluid.CHPs.Validation.ElectricalFollowing,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|EEle.y|EEleVal.y|EThe.y|ETheVal.y|eleFol.PEleNet|PEleNetVal.y|eleFol.eneCon.QGen_flow|QGenVal_flow.y|eleFol.QWat_flow|QWatVal_flow.y|eleFol.QLos.Q_flow|QLosVal_flow.y|eleFol.opeMod.opeMod|eleFol.TWatOut.T|TWatOutVal.y|eleFol.eng.TEng|TEngVal.y",fileNamePrefix="Buildings_latest_Buildings.Fluid.CHPs.Validation.ElectricalFollowing")
translateModel(Buildings.Fluid.CHPs.Validation.ElectricalFollowing,tolerance=1e-06,outputFormat="mat",numberOfIntervals=5000,variableFilter="time|EEle.y|EEleVal.y|EThe.y|ETheVal.y|eleFol.PEleNet|PEleNetVal.y|eleFol.eneCon.QGen_flow|QGenVal_flow.y|eleFol.QWat_flow|QWatVal_flow.y|eleFol.QLos.Q_flow|QLosVal_flow.y|eleFol.opeMod.opeMod|eleFol.TWatOut.T|TWatOutVal.y|eleFol.eng.TEng|TEngVal.y",fileNamePrefix="Buildings_latest_Buildings.Fluid.CHPs.Validation.ElectricalFollowing")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.0.0-master/package.mo): time 2.274/2.274, allocations: 393.5 MB / 408.4 MB, free: 10.97 MB / 314.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001116/0.001116, allocations: 190.8 kB / 0.4902 GB, free: 2.254 MB / 394.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.572/1.572, allocations: 223.2 MB / 0.7992 GB, free: 14.71 MB / 0.5573 GB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001158/0.001157, allocations: 90.95 kB / 0.9396 GB, free: 1.934 MB / 0.6823 GB
Notification: Performance of FrontEnd - loaded program: time 0.000384/0.000384, allocations: 7.156 kB / 1.09 GB, free: 216.2 MB / 0.823 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3743/0.3747, allocations: 143 MB / 1.229 GB, free: 105.8 MB / 0.823 GB
Notification: Performance of FrontEnd - scodeFlatten: time 1.299/1.673, allocations: 447.8 MB / 1.667 GB, free: 14.93 MB / 0.9948 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003732/1.674, allocations: 96.22 kB / 1.667 GB, free: 14.88 MB / 0.9948 GB
Notification: Performance of FrontEnd - DAE generated: time 1.525/3.199, allocations: 298.3 MB / 1.958 GB, free: 337.2 MB / 1.167 GB
Notification: Performance of FrontEnd: time 1.884e-06/3.199, allocations: 0 / 1.958 GB, free: 337.2 MB / 1.167 GB
Notification: Performance of Transformations before backend: time 0.000571/3.199, allocations: 91.5 kB / 1.958 GB, free: 337.2 MB / 1.167 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 649
 * Number of variables: 649
Notification: Performance of Generate backend data structure: time 0.01197/3.211, allocations: 4.057 MB / 1.962 GB, free: 335 MB / 1.167 GB
Notification: Performance of prepare preOptimizeDAE: time 4.694e-05/3.211, allocations: 10.91 kB / 1.962 GB, free: 335 MB / 1.167 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002624/3.214, allocations: 0.5037 MB / 1.962 GB, free: 334.8 MB / 1.167 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008442/3.223, allocations: 2.827 MB / 1.965 GB, free: 334.2 MB / 1.167 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002535/3.223, allocations: 259.2 kB / 1.965 GB, free: 334.1 MB / 1.167 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004937/3.223, allocations: 280.6 kB / 1.966 GB, free: 334.1 MB / 1.167 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005288/3.229, allocations: 2.98 MB / 1.969 GB, free: 333.6 MB / 1.167 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.372e-05/3.229, allocations: 7.875 kB / 1.969 GB, free: 333.6 MB / 1.167 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0002361/3.229, allocations: 108.1 kB / 1.969 GB, free: 333.6 MB / 1.167 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.124e-05/3.229, allocations: 71.61 kB / 1.969 GB, free: 333.6 MB / 1.167 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.004416/3.234, allocations: 2.497 MB / 1.971 GB, free: 333.5 MB / 1.167 GB
Warning: The model contains alias variables with redundant start and/or conflicting nominal values. It is recommended to resolve the conflicts, because otherwise the system could be hard to solve. To print the conflicting alias sets and the chosen candidates please use -d=aliasConflicts.
Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.01768/3.251, allocations: 13.24 MB / 1.984 GB, free: 324.9 MB / 1.167 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003632/3.255, allocations: 2.184 MB / 1.986 GB, free: 323.9 MB / 1.167 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001053/3.256, allocations: 0.8706 MB / 1.987 GB, free: 323.3 MB / 1.167 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001954/3.258, allocations: 1.252 MB / 1.988 GB, free: 322.7 MB / 1.167 GB
Notification: Performance of pre-optimization done (n=181): time 3.988e-06/3.258, allocations: 0 / 1.988 GB, free: 322.7 MB / 1.167 GB
Notification: Performance of matching and sorting (n=181): time 0.005578/3.264, allocations: 2.988 MB / 1.991 GB, free: 321.4 MB / 1.167 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001257/3.264, allocations: 403.1 kB / 1.992 GB, free: 321 MB / 1.167 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005681/3.269, allocations: 3.435 MB / 1.995 GB, free: 319.7 MB / 1.167 GB
Notification: Performance of collectPreVariables (initialization): time 0.0006781/3.27, allocations: 125.4 kB / 1.995 GB, free: 319.6 MB / 1.167 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001513/3.272, allocations: 1.679 MB / 1.997 GB, free: 318.3 MB / 1.167 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0007302/3.272, allocations: 0.5148 MB / 1.997 GB, free: 317.9 MB / 1.167 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001093/3.272, allocations: 42.59 kB / 1.997 GB, free: 317.9 MB / 1.167 GB
Notification: Performance of setup shared object (initialization): time 0.0001306/3.273, allocations: 394.2 kB / 1.998 GB, free: 317.5 MB / 1.167 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001436/3.274, allocations: 0.8477 MB / 1.999 GB, free: 317.2 MB / 1.167 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.001577/3.276, allocations: 1.479 MB / 2 GB, free: 316.1 MB / 1.167 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001362/3.277, allocations: 0.8947 MB / 2.001 GB, free: 315.6 MB / 1.167 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.537e-06/3.277, allocations: 3.938 kB / 2.001 GB, free: 315.6 MB / 1.167 GB
Notification: Performance of matching and sorting (n=285) (initialization): time 0.004785/3.282, allocations: 2.643 MB / 2.003 GB, free: 314.5 MB / 1.167 GB
Notification: Performance of prepare postOptimizeDAE: time 0.001163/3.283, allocations: 6.913 MB / 2.01 GB, free: 307.2 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.339e-05/3.283, allocations: 17.25 kB / 2.01 GB, free: 307.2 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003121/3.283, allocations: 87.86 kB / 2.01 GB, free: 307.1 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001117/3.285, allocations: 341.2 kB / 2.011 GB, free: 307.1 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0004357/3.285, allocations: 0.6607 MB / 2.011 GB, free: 306.5 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002045/3.287, allocations: 256.9 kB / 2.012 GB, free: 306.4 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002125/3.287, allocations: 115.2 kB / 2.012 GB, free: 306.4 MB / 1.167 GB
Notification: Performance of prepare postOptimizeDAE: time 1.032e-05/3.287, allocations: 3.375 kB / 2.012 GB, free: 306.4 MB / 1.167 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.003417/3.291, allocations: 2.279 MB / 2.014 GB, free: 305.4 MB / 1.167 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.269e-05/3.291, allocations: 19.47 kB / 2.014 GB, free: 305.4 MB / 1.167 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002103/3.291, allocations: 88.55 kB / 2.014 GB, free: 305.4 MB / 1.167 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001019/3.292, allocations: 324.5 kB / 2.014 GB, free: 305.3 MB / 1.167 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0003613/3.292, allocations: 0.6713 MB / 2.015 GB, free: 304.8 MB / 1.167 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0021/3.294, allocations: 237.7 kB / 2.015 GB, free: 304.7 MB / 1.167 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001942/3.295, allocations: 106.2 kB / 2.015 GB, free: 304.6 MB / 1.167 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 70
 * Number of states: 0 ()
 * Number of discrete variables: 154 ($PRE.eleFol.opeMod.transition3.enableFire,$PRE.eleFol.opeMod.transition5.enableFire,$PRE.eleFol.opeMod.transition9.enableFire,$PRE.eleFol.opeMod.transition7.enableFire,$PRE.eleFol.opeMod.transition10.enableFire,$PRE.eleFol.opeMod.transition1.enableFire,$PRE.eleFol.opeMod.transition2.enableFire,$PRE.eleFol.opeMod.transition8.enableFire,$PRE.eleFol.opeMod.transition6.enableFire,$PRE.eleFol.opeMod.transition4.enableFire,$whenCondition2,$PRE.avaSig.combiTimeTable.nextTimeEvent,$whenCondition1,$PRE.valDat.nextTimeEvent,$PRE.eleFol.opeMod.transition3.t_start,eleFol.opeMod.transition3.t_start,$PRE.eleFol.opeMod.transition5.t_start,eleFol.opeMod.transition5.t_start,$PRE.eleFol.opeMod.transition9.t_start,eleFol.opeMod.transition9.t_start,$PRE.eleFol.opeMod.transition7.t_start,eleFol.opeMod.transition7.t_start,$PRE.eleFol.opeMod.transition10.t_start,eleFol.opeMod.transition10.t_start,$PRE.eleFol.opeMod.transition1.t_start,eleFol.opeMod.transition1.t_start,$PRE.eleFol.opeMod.transition2.t_start,eleFol.opeMod.transition2.t_start,$PRE.eleFol.opeMod.transition8.t_start,eleFol.opeMod.transition8.t_start,$PRE.eleFol.opeMod.transition6.t_start,eleFol.opeMod.transition6.t_start,eleFol.eneCon.and1.u2,eleFol.eneCon.engTemWarUp.y,eleFol.stateGraphRoot.resume,eleFol.stateGraphRoot.suspend,eleFol.fil.limDp.y,eleFol.eneCon.delWarUp.y,eleFol.eneCon.and1.y,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition6,$whenCondition3,$whenCondition4,$whenCondition5,eleFol.eneCon.booExp.y,eleFol.eneCon.and1.u1,eleFol.opeMod.runSig,$PRE.eleFol.opeMod.staBy.localActive,eleFol.opeMod.staBy.localActive,eleFol.opeMod.staBy.outPort[2].available,$PRE.eleFol.opeMod.staBy.newActive,eleFol.opeMod.staBy.newActive,$PRE.eleFol.opeMod.staBy.oldActive,eleFol.opeMod.staBy.oldActive,eleFol.opeMod.staBy.active,$PRE.eleFol.opeMod.pumOn.localActive,eleFol.opeMod.pumOn.localActive,eleFol.opeMod.pumOn.outPort[2].available,$PRE.eleFol.opeMod.pumOn.newActive,eleFol.opeMod.pumOn.newActive,$PRE.eleFol.opeMod.pumOn.oldActive,eleFol.opeMod.pumOn.oldActive,eleFol.opeMod.pumOn.active,$PRE.eleFol.opeMod.warUp.localActive,eleFol.opeMod.warUp.localActive,eleFol.opeMod.warUp.inPort[2].occupied,eleFol.opeMod.warUp.outPort[2].available,$PRE.eleFol.opeMod.warUp.newActive,eleFol.opeMod.warUp.newActive,$PRE.eleFol.opeMod.warUp.oldActive,eleFol.opeMod.warUp.oldActive,eleFol.opeMod.warUp.active,$PRE.eleFol.opeMod.cooDow.localActive,eleFol.opeMod.cooDow.localActive,eleFol.opeMod.cooDow.inPort[2].occupied,eleFol.opeMod.cooDow.outPort[2].available,$PRE.eleFol.opeMod.cooDow.newActive,eleFol.opeMod.cooDow.newActive,$PRE.eleFol.opeMod.cooDow.oldActive,eleFol.opeMod.cooDow.oldActive,eleFol.opeMod.cooDow.active,$PRE.eleFol.opeMod.hys.y,eleFol.opeMod.hys.y,eleFol.opeMod.and1.y,eleFol.opeMod.transition3.enableFire,eleFol.opeMod.transition5.enableFire,eleFol.opeMod.transition9.enableFire,eleFol.opeMod.transition7.enableFire,eleFol.opeMod.and3.y,eleFol.opeMod.transition10.enableFire,eleFol.opeMod.and4.u2,eleFol.opeMod.and4.y,eleFol.opeMod.assWatMas.assMes.u,$PRE.eleFol.opeMod.assWatMas.hys.y,eleFol.opeMod.assWatMas.hys.y,eleFol.opeMod.transition1.enableFire,eleFol.opeMod.transition2.enableFire,$PRE.eleFol.opeMod.nor.localActive,eleFol.opeMod.nor.localActive,$PRE.eleFol.opeMod.nor.newActive,eleFol.opeMod.nor.newActive,$PRE.eleFol.opeMod.nor.oldActive,eleFol.opeMod.nor.oldActive,eleFol.opeMod.nor.active,eleFol.opeMod.transition8.enableFire,$PRE.eleFol.opeMod.warUpCtr.timer.passed,eleFol.opeMod.warUpCtr.timer.passed,$PRE.eleFol.opeMod.warUpCtr.timer.entryTime,eleFol.opeMod.warUpCtr.timer.entryTime,eleFol.opeMod.transition6.enableFire,$PRE.eleFol.opeMod.plaOff.localActive,eleFol.opeMod.plaOff.localActive,eleFol.opeMod.plaOff.inPort[2].occupied,eleFol.opeMod.plaOff.inPort[3].occupied,$PRE.eleFol.opeMod.plaOff.newActive,eleFol.opeMod.plaOff.newActive,$PRE.eleFol.opeMod.plaOff.oldActive,eleFol.opeMod.plaOff.oldActive,eleFol.opeMod.plaOff.active,eleFol.opeMod.transition4.enableFire,eleFol.opeMod.transition4.fire,$PRE.eleFol.opeMod.transition4.t_start,eleFol.opeMod.transition4.t_start,$PRE.eleFol.opeMod.timer.passed,eleFol.opeMod.timer.passed,$PRE.eleFol.opeMod.timer.entryTime,eleFol.opeMod.timer.entryTime,$PRE.eleFol.hys.y,eleFol.hys.y,eleFol.fil.assPow.assMesP.u,$PRE.eleFol.fil.assPow.maxPow.y,eleFol.fil.assPow.maxPow.y,$PRE.eleFol.fil.assPow.minPow.y,eleFol.fil.assPow.minPow.y,$PRE.eleFol.assWatTem.hys.y,eleFol.assWatTem.hys.y,eleFol.powCon.staBy.y,eleFol.powCon.cooDow.y,eleFol.stateGraphRoot.activeSteps,eleFol.conWat.opeMod,eleFol.conWat.offStaBy.y,avaSig.y,avaSig.combiTimeTable.nextTimeEvent,$PRE.avaSig.combiTimeTable.nextTimeEventScaled,avaSig.combiTimeTable.nextTimeEventScaled,valDat.nextTimeEvent,$PRE.valDat.nextTimeEventScaled,valDat.nextTimeEventScaled,ETheVal.local_reset,EEleVal.local_reset,EThe.local_reset,EEle.local_reset)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (280):
 * Single equations (assignments): 279
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 1
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems: 0
 * Non-linear torn systems: 1 {1 5}
Notification: Performance of prepare postOptimizeDAE: time 0.002314/3.297, allocations: 1.128 MB / 2.016 GB, free: 304 MB / 1.167 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0008671/3.298, allocations: 348.5 kB / 2.017 GB, free: 303.8 MB / 1.167 GB
Warning: After manipulating the system with postOptModule wrapFunctionCalls the system is unbalanced. This indicates that the original system is singular. You can use -d=dumpCSE and -d=dumpCSE_verbose for more information.
Error: Too many equations, over-determined system. The model has 201 equation(s) and 200 variable(s).
Error: Internal error Transformation Module PFPlusExt index Reduction Method Pantelides failed!
Notification: Performance of <failed> postOpt wrapFunctionCalls (simulation): time 0.002707/3.301, allocations: 1.256 MB / 2.018 GB, free: 303.2 MB / 1.167 GB
Error: post-optimization module wrapFunctionCalls (simulation) failed.