Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/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/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/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 ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo)
Running command: translateModel(IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3")
translateModel(IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001221/0.001222, allocations: 107.1 kB / 16.4 MB, free: 6.504 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001355/0.001355, allocations: 193.2 kB / 17.34 MB, free: 5.742 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.412/1.412, allocations: 222.9 MB / 241 MB, free: 15.14 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.7271/0.7271, allocations: 115.3 MB / 406.5 MB, free: 6.137 MB / 318.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001304/0.001304, allocations: 62.31 kB / 0.5577 GB, free: 32.32 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1731/0.1744, allocations: 76.18 MB / 0.6321 GB, free: 4.516 MB / 478.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3): time 0.6446/0.8191, allocations: 166 MB / 0.7942 GB, free: 13.26 MB / 0.5919 GB
Notification: Performance of NFInst.instExpressions: time 0.01213/0.8313, allocations: 7.8 MB / 0.8018 GB, free: 12.45 MB / 0.5919 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002215/0.8335, allocations: 32.06 kB / 0.8019 GB, free: 12.45 MB / 0.5919 GB
Notification: Performance of NFTyping.typeComponents: time 0.001429/0.835, allocations: 415.2 kB / 0.8023 GB, free: 12.27 MB / 0.5919 GB
Notification: Performance of NFTyping.typeBindings: time 0.005108/0.8402, allocations: 1.353 MB / 0.8036 GB, free: 11.9 MB / 0.5919 GB
Notification: Performance of NFTyping.typeClassSections: time 0.00371/0.8439, allocations: 1.179 MB / 0.8047 GB, free: 11.77 MB / 0.5919 GB
Notification: Performance of NFFlatten.flatten: time 0.004065/0.848, allocations: 2.366 MB / 0.807 GB, free: 11.41 MB / 0.5919 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.002058/0.85, allocations: 1.193 MB / 0.8082 GB, free: 11.12 MB / 0.5919 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001667/0.8517, allocations: 0.8344 MB / 0.809 GB, free: 10.86 MB / 0.5919 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.001712/0.8535, allocations: 0.79 MB / 0.8098 GB, free: 10.55 MB / 0.5919 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002624/0.8537, allocations: 148.9 kB / 0.8099 GB, free: 10.55 MB / 0.5919 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.002974/0.8567, allocations: 1.129 MB / 0.811 GB, free: 10.17 MB / 0.5919 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004873/0.8572, allocations: 429.9 kB / 0.8114 GB, free: 9.859 MB / 0.5919 GB
Notification: Performance of NFVerifyModel.verify: time 0.0009412/0.8582, allocations: 0.7817 MB / 0.8122 GB, free: 9.312 MB / 0.5919 GB
Notification: Performance of NFConvertDAE.convert: time 0.004195/0.8624, allocations: 2.406 MB / 0.8146 GB, free: 7.75 MB / 0.5919 GB
Notification: Performance of FrontEnd - DAE generated: time 6.533e-06/0.8624, allocations: 0 / 0.8146 GB, free: 7.75 MB / 0.5919 GB
Notification: Performance of FrontEnd: time 2.054e-06/0.8624, allocations: 0 / 0.8146 GB, free: 7.75 MB / 0.5919 GB
Notification: Performance of Transformations before backend: time 3.345e-05/0.8624, allocations: 0 / 0.8146 GB, free: 7.75 MB / 0.5919 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 279
 * Number of variables: 279
Notification: Performance of Generate backend data structure: time 0.003943/0.8664, allocations: 1.878 MB / 0.8164 GB, free: 6.328 MB / 0.5919 GB
Notification: Performance of prepare preOptimizeDAE: time 4.525e-05/0.8665, allocations: 9.688 kB / 0.8164 GB, free: 6.328 MB / 0.5919 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002078/0.8685, allocations: 325.3 kB / 0.8167 GB, free: 6.176 MB / 0.5919 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001822/0.8704, allocations: 1.021 MB / 0.8177 GB, free: 5.391 MB / 0.5919 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 7.45e-05/0.8705, allocations: 83.39 kB / 0.8178 GB, free: 5.352 MB / 0.5919 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001557/0.8706, allocations: 114.3 kB / 0.8179 GB, free: 5.309 MB / 0.5919 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002228/0.8729, allocations: 1.166 MB / 0.819 GB, free: 4.551 MB / 0.5919 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.126e-05/0.8729, allocations: 2.75 kB / 0.819 GB, free: 4.551 MB / 0.5919 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001128/0.873, allocations: 50.03 kB / 0.8191 GB, free: 4.512 MB / 0.5919 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.553e-05/0.8731, allocations: 43.5 kB / 0.8191 GB, free: 4.504 MB / 0.5919 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002182/0.8753, allocations: 1.315 MB / 0.8204 GB, free: 3.863 MB / 0.5919 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.006325/0.8816, allocations: 3.8 MB / 0.8241 GB, free: 0.7812 MB / 0.5919 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001761/0.8834, allocations: 0.9857 MB / 0.8251 GB, free: 296 kB / 0.5919 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0007208/0.8841, allocations: 413.7 kB / 0.8255 GB, free: 80 kB / 0.5919 GB
Notification: Performance of preOpt evalFunc (simulation): time 8.221e-05/0.8842, allocations: 34.45 kB / 0.8255 GB, free: 72 kB / 0.5919 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.001054/0.8853, allocations: 0.5872 MB / 0.8261 GB, free: 15.69 MB / 0.6075 GB
Notification: Performance of pre-optimization done (n=102): time 2.775e-06/0.8853, allocations: 2.719 kB / 0.8261 GB, free: 15.69 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=102): time 0.003811/0.8891, allocations: 1.439 MB / 0.8275 GB, free: 14.93 MB / 0.6075 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001767/0.8893, allocations: 195.3 kB / 0.8277 GB, free: 14.71 MB / 0.6075 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001233/0.8905, allocations: 0.8336 MB / 0.8285 GB, free: 14.22 MB / 0.6075 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001083/0.8907, allocations: 45.45 kB / 0.8285 GB, free: 14.18 MB / 0.6075 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004392/0.8911, allocations: 0.528 MB / 0.8291 GB, free: 13.71 MB / 0.6075 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0003006/0.8914, allocations: 333.4 kB / 0.8294 GB, free: 13.43 MB / 0.6075 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0003098/0.8917, allocations: 208.1 kB / 0.8296 GB, free: 13.3 MB / 0.6075 GB
Notification: Performance of setup shared object (initialization): time 0.0001373/0.8919, allocations: 344.1 kB / 0.8299 GB, free: 12.97 MB / 0.6075 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0006786/0.8926, allocations: 339.9 kB / 0.8302 GB, free: 12.8 MB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0008201/0.8934, allocations: 476.9 kB / 0.8307 GB, free: 12.5 MB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001342/0.8948, allocations: 0.8019 MB / 0.8315 GB, free: 12.04 MB / 0.6075 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.047e-06/0.8948, allocations: 0 / 0.8315 GB, free: 12.04 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=126) (initialization): time 0.002413/0.8972, allocations: 1.07 MB / 0.8325 GB, free: 11.5 MB / 0.6075 GB
Notification: Performance of prepare postOptimizeDAE: time 2.448e-05/0.8972, allocations: 8.891 kB / 0.8325 GB, free: 11.5 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.036e-05/0.8972, allocations: 9.438 kB / 0.8325 GB, free: 11.5 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0005855/0.8978, allocations: 82.59 kB / 0.8326 GB, free: 11.46 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008303/0.8987, allocations: 215.2 kB / 0.8328 GB, free: 11.41 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002193/0.9009, allocations: 1.632 MB / 0.8344 GB, free: 9.801 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001116/0.902, allocations: 110.5 kB / 0.8345 GB, free: 9.727 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.516e-05/0.9021, allocations: 50.03 kB / 0.8346 GB, free: 9.707 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * Number of states: 0 ()
 * Number of discrete variables: 17 (weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (124):
 * Single equations (assignments): 123
 * Array equations: 0
 * Algorithm blocks: 0
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006498/0.9028, allocations: 322.6 kB / 0.8349 GB, free: 9.461 MB / 0.6075 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002256/0.903, allocations: 113.9 kB / 0.835 GB, free: 9.402 MB / 0.6075 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002708/0.9057, allocations: 1.244 MB / 0.8362 GB, free: 8.707 MB / 0.6075 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.947e-06/0.9057, allocations: 12.25 kB / 0.8362 GB, free: 8.707 MB / 0.6075 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.297e-06/0.9057, allocations: 4 kB / 0.8362 GB, free: 8.703 MB / 0.6075 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 2.15e-05/0.9058, allocations: 7.969 kB / 0.8362 GB, free: 8.695 MB / 0.6075 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001543/0.9073, allocations: 1.166 MB / 0.8373 GB, free: 7.859 MB / 0.6075 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.438e-06/0.9073, allocations: 0 / 0.8373 GB, free: 7.859 MB / 0.6075 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0008612/0.9082, allocations: 205.2 kB / 0.8375 GB, free: 7.816 MB / 0.6075 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0004471/0.9087, allocations: 70.73 kB / 0.8376 GB, free: 7.781 MB / 0.6075 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 7.062e-05/0.9087, allocations: 34.12 kB / 0.8376 GB, free: 7.758 MB / 0.6075 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001925/0.9107, allocations: 1.657 MB / 0.8393 GB, free: 6.074 MB / 0.6075 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.033e-05/0.9107, allocations: 11.81 kB / 0.8393 GB, free: 6.062 MB / 0.6075 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003204/0.9139, allocations: 1.581 MB / 0.8408 GB, free: 4.461 MB / 0.6075 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005421/0.9145, allocations: 319.3 kB / 0.8411 GB, free: 4.129 MB / 0.6075 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001629/0.9146, allocations: 27.91 kB / 0.8411 GB, free: 4.102 MB / 0.6075 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008367/0.9155, allocations: 71.92 kB / 0.8412 GB, free: 4.031 MB / 0.6075 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001859/0.9157, allocations: 80.89 kB / 0.8413 GB, free: 3.953 MB / 0.6075 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 8.053e-05/0.9158, allocations: 43.97 kB / 0.8413 GB, free: 3.91 MB / 0.6075 GB
Notification: Performance of sorting global known variables: time 0.0008622/0.9166, allocations: 0.578 MB / 0.8419 GB, free: 3.32 MB / 0.6075 GB
Notification: Performance of sort global known variables: time 2.91e-07/0.9166, allocations: 0 / 0.8419 GB, free: 3.32 MB / 0.6075 GB
Notification: Performance of remove unused functions: time 0.001224/0.9179, allocations: 431 kB / 0.8423 GB, free: 2.898 MB / 0.6075 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 4 (walCap.T,zon.dynBal.medium.Xi[1],zon.dynBal.U,zon.dynBal.m)
 * Number of discrete variables: 10 ($whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,weaDat.locTim.tNext,weaDat.conTimMin.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext)
 * Number of discrete states: 5 (weaDat.locTim.tNext,weaDat.conTimMin.tNext,weaDat.conTim.tNext,weaDat.eqnTim.tNext,weaDat.decAng.tNext)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (113):
 * Single equations (assignments): 107
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 5
 * 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): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.00147/0.9193, allocations: 0.6218 MB / 0.8429 GB, free: 2.473 MB / 0.6075 GB
Notification: Performance of simCode: created initialization part: time 0.002011/0.9214, allocations: 1 MB / 0.8439 GB, free: 1.457 MB / 0.6075 GB
Notification: Performance of simCode: created event and clocks part: time 5.911e-06/0.9214, allocations: 4 kB / 0.8439 GB, free: 1.453 MB / 0.6075 GB
Notification: Performance of simCode: created simulation system equations: time 0.001053/0.9224, allocations: 0.6038 MB / 0.8445 GB, free: 0.8438 MB / 0.6075 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002002/0.9245, allocations: 480.5 kB / 0.8449 GB, free: 400 kB / 0.6075 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004243/0.9287, allocations: 2.44 MB / 0.8473 GB, free: 13.93 MB / 0.6231 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.000726/0.9295, allocations: 1.15 MB / 0.8485 GB, free: 12.76 MB / 0.6231 GB
Notification: Performance of simCode: alias equations: time 0.001126/0.9306, allocations: 325.2 kB / 0.8488 GB, free: 12.44 MB / 0.6231 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003114/0.9309, allocations: 127.4 kB / 0.8489 GB, free: 12.31 MB / 0.6231 GB
Notification: Performance of SimCode: time 8.92e-07/0.9309, allocations: 0 / 0.8489 GB, free: 12.31 MB / 0.6231 GB
Notification: Performance of Templates: time 0.03513/0.9661, allocations: 21.05 MB / 0.8694 GB, free: 7.527 MB / 0.6387 GB
make -j1 -f IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.makefile
(rm -f IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.pipe ; mkfifo IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.pipe >> ../files/IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.sim & ./IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Examples.Tutorial.SimpleHouse.SimpleHouse3.pipe 2>&1)