Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_13_Buildings.Examples.Tutorial.CDL.System5.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.000919/0.000919, allocations: 80.81 kB / 21.55 MB, free: 2.945 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.0009364/0.0009364, allocations: 170.4 kB / 24.97 MB, free: 8.656 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.9218/0.9218, allocations: 177.1 MB / 205.4 MB, free: 5.488 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/package.mo): time 1.863/1.863, allocations: 389.6 MB / 0.6362 GB, free: 12.27 MB / 0.6042 GB
"
[Timeout remaining time 178]
Using package Buildings with version 13.0.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 13.0.2-maint.13.x/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(Buildings.Examples.Tutorial.CDL.System5,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Examples.Tutorial.CDL.System5")
translateModel(Buildings.Examples.Tutorial.CDL.System5,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="Buildings_13_Buildings.Examples.Tutorial.CDL.System5") [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.122e-06/1.122e-06, allocations: 0 / 0.776 GB, free: 5.336 MB / 0.7292 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.373e-05/1.485e-05, allocations: 3.188 kB / 0.776 GB, free: 5.332 MB / 0.7292 GB
Notification: Performance of NFInst.instantiate(Buildings.Examples.Tutorial.CDL.System5): time 0.1682/0.1682, allocations: 220.1 MB / 0.991 GB, free: 8.609 MB / 0.948 GB
Notification: Performance of NFInst.instExpressions: time 0.05938/0.2276, allocations: 35.66 MB / 1.026 GB, free: 4.879 MB / 0.9792 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01355/0.2412, allocations: 0.5966 MB / 1.026 GB, free: 4.277 MB / 0.9792 GB
Notification: Performance of NFTyping.typeComponents: time 0.009979/0.2511, allocations: 3.807 MB / 1.03 GB, free: 456 kB / 0.9792 GB
Notification: Performance of NFTyping.typeBindings: time 0.02322/0.2744, allocations: 10.63 MB / 1.04 GB, free: 5.75 MB / 0.9948 GB
Notification: Performance of NFTyping.typeClassSections: time 0.4283/0.7027, allocations: 9.597 MB / 1.05 GB, free: 43.68 MB / 0.9948 GB
Notification: Performance of NFFlatten.flatten: time 0.03552/0.7382, allocations: 39.52 MB / 1.088 GB, free: 39.35 MB / 0.9948 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0138/0.752, allocations: 9.699 MB / 1.098 GB, free: 38.17 MB / 0.9948 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.02617/0.7782, allocations: 16.23 MB / 1.114 GB, free: 36.71 MB / 0.9948 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01262/0.7908, allocations: 8.003 MB / 1.122 GB, free: 35.72 MB / 0.9948 GB
Notification: Performance of NFPackage.collectConstants: time 0.002781/0.7936, allocations: 1.172 MB / 1.123 GB, free: 35.72 MB / 0.9948 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006512/0.8001, allocations: 3.076 MB / 1.126 GB, free: 35.67 MB / 0.9948 GB
Notification: Performance of NFScalarize.scalarize: time 0.002244/0.8023, allocations: 3.805 MB / 1.129 GB, free: 34.97 MB / 0.9948 GB
Notification: Performance of NFVerifyModel.verify: time 0.006602/0.8089, allocations: 4.802 MB / 1.134 GB, free: 34.37 MB / 0.9948 GB
Notification: Performance of NFConvertDAE.convert: time 0.01149/0.8204, allocations: 11.78 MB / 1.146 GB, free: 32.77 MB / 0.9948 GB
Notification: Performance of FrontEnd - DAE generated: time 4.288e-06/0.8204, allocations: 0 / 1.146 GB, free: 32.77 MB / 0.9948 GB
Notification: Performance of FrontEnd: time 9.22e-07/0.8204, allocations: 0 / 1.146 GB, free: 32.77 MB / 0.9948 GB
Notification: Performance of Transformations before backend: time 0.0004148/0.8208, allocations: 0.9375 kB / 1.146 GB, free: 32.77 MB / 0.9948 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 1888
 * Number of variables: 1888
Notification: Performance of Generate backend data structure: time 0.01335/0.8342, allocations: 13.6 MB / 1.159 GB, free: 25.91 MB / 0.9948 GB
Notification: Performance of prepare preOptimizeDAE: time 3.68e-05/0.8342, allocations: 11.98 kB / 1.159 GB, free: 25.91 MB / 0.9948 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.004654/0.8389, allocations: 2.373 MB / 1.161 GB, free: 25.54 MB / 0.9948 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.009165/0.848, allocations: 8.19 MB / 1.169 GB, free: 22.12 MB / 0.9948 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002421/0.8483, allocations: 0.5721 MB / 1.17 GB, free: 21.82 MB / 0.9948 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0006234/0.8489, allocations: 0.809 MB / 1.171 GB, free: 21.02 MB / 0.9948 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.005134/0.854, allocations: 7.169 MB / 1.178 GB, free: 14.61 MB / 0.9948 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001008/0.8541, allocations: 21.59 kB / 1.178 GB, free: 14.6 MB / 0.9948 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003973/0.8545, allocations: 312 kB / 1.178 GB, free: 14.3 MB / 0.9948 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001292/0.8547, allocations: 294.3 kB / 1.178 GB, free: 14.01 MB / 0.9948 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002882/0.8575, allocations: 2.592 MB / 1.181 GB, free: 11.48 MB / 0.9948 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.02141/0.879, allocations: 22.53 MB / 1.203 GB, free: 6.27 MB / 1.01 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002523/0.8815, allocations: 2.26 MB / 1.205 GB, free: 4.102 MB / 1.01 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001069/0.8825, allocations: 1.003 MB / 1.206 GB, free: 3.137 MB / 1.01 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 evalFunc (simulation): time 0.009016/0.8916, allocations: 4.885 MB / 1.211 GB, free: 14.38 MB / 1.026 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.002258/0.8938, allocations: 2.991 MB / 1.214 GB, free: 11.11 MB / 1.026 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001382/0.8952, allocations: 377.2 kB / 1.214 GB, free: 10.74 MB / 1.026 GB
Notification: Performance of pre-optimization done (n=420): time 7.784e-06/0.8952, allocations: 0 / 1.214 GB, free: 10.74 MB / 1.026 GB
Notification: Performance of matching and sorting (n=427): time 0.01218/0.9074, allocations: 6.946 MB / 1.221 GB, free: 3.797 MB / 1.026 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001093/0.9075, allocations: 297.7 kB / 1.221 GB, free: 3.453 MB / 1.026 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00688/0.9144, allocations: 7.451 MB / 1.228 GB, free: 13.47 MB / 1.042 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003479/0.9147, allocations: 149.7 kB / 1.228 GB, free: 13.32 MB / 1.042 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002546/0.9173, allocations: 3.477 MB / 1.232 GB, free: 10.26 MB / 1.042 GB
Notification: Performance of collectInitialBindings (initialization): time 0.001058/0.9183, allocations: 1.532 MB / 1.233 GB, free: 8.82 MB / 1.042 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001234/0.9196, allocations: 1.121 MB / 1.234 GB, free: 7.82 MB / 1.042 GB
Notification: Performance of setup shared object (initialization): time 7.255e-05/0.9196, allocations: 301.1 kB / 1.235 GB, free: 7.523 MB / 1.042 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.002213/0.9219, allocations: 0.8567 MB / 1.236 GB, free: 6.645 MB / 1.042 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002525/0.9244, allocations: 3.845 MB / 1.239 GB, free: 1.898 MB / 1.042 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.003793/0.9282, allocations: 3.4 MB / 1.243 GB, free: 14.16 MB / 1.057 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 1.841e-05/0.9282, allocations: 16 kB / 1.243 GB, free: 14.14 MB / 1.057 GB
Notification: Performance of matching and sorting (n=732) (initialization): time 0.00671/0.9349, allocations: 4.114 MB / 1.247 GB, free: 10.24 MB / 1.057 GB
Notification: Performance of prepare postOptimizeDAE: time 2.979e-05/0.9349, allocations: 20 kB / 1.247 GB, free: 10.22 MB / 1.057 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.899e-05/0.935, allocations: 43.98 kB / 1.247 GB, free: 10.18 MB / 1.057 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003569/0.9385, allocations: 1.435 MB / 1.248 GB, free: 8.746 MB / 1.057 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002659/0.9412, allocations: 1.23 MB / 1.249 GB, free: 7.598 MB / 1.057 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004351/0.9455, allocations: 6.527 MB / 1.256 GB, free: 0.8281 MB / 1.057 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.003959/0.9495, allocations: 280.3 kB / 1.256 GB, free: 0.5547 MB / 1.057 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003079/0.9498, allocations: 207.9 kB / 1.256 GB, free: 360 kB / 1.057 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.003373/0.9532, allocations: 0.9756 MB / 1.257 GB, free: 15.36 MB / 1.073 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002594/0.9558, allocations: 3.822 MB / 1.261 GB, free: 10.64 MB / 1.073 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.003708/0.9595, allocations: 3.331 MB / 1.264 GB, free: 6.973 MB / 1.073 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.758e-05/0.9595, allocations: 12 kB / 1.264 GB, free: 6.961 MB / 1.073 GB
Notification: Performance of matching and sorting (n=732) (initialization_lambda0): time 0.006146/0.9656, allocations: 3.967 MB / 1.268 GB, free: 3.199 MB / 1.073 GB
Notification: Performance of prepare postOptimizeDAE: time 2.548e-05/0.9657, allocations: 24 kB / 1.268 GB, free: 3.176 MB / 1.073 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.557e-05/0.9657, allocations: 40 kB / 1.268 GB, free: 3.137 MB / 1.073 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.003018/0.9687, allocations: 1.122 MB / 1.269 GB, free: 2.016 MB / 1.073 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002308/0.971, allocations: 1.205 MB / 1.27 GB, free: 0.8867 MB / 1.073 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005455/0.9765, allocations: 7.674 MB / 1.278 GB, free: 8.965 MB / 1.089 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004285/0.9808, allocations: 292.8 kB / 1.278 GB, free: 8.68 MB / 1.089 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003779/0.9812, allocations: 199.9 kB / 1.278 GB, free: 8.484 MB / 1.089 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 184
 * Number of states: 0 ()
 * Number of discrete variables: 41 ($PRE.timTab.tab.nextTimeEvent,$whenCondition1,conBoiRet.conPID.con1.y,weaDat.conTim.canRepeatWeatherFile,weaDat.conTimMin.canRepeatWeatherFile,boiSigCon.u,radPumCon.u,conEquSta.hys.y,$PRE.conEquSta.hys.y,conSysSta.onTRoo.y,$PRE.conSysSta.onTRoo.y,conSysSta.onTOut.y,$PRE.conSysSta.onTOut.y,weaDat.decAng.k,$PRE.weaDat.decAng.k,weaDat.decAng.tNext,$PRE.weaDat.decAng.tNext,weaDat.eqnTim.k,$PRE.weaDat.eqnTim.k,weaDat.eqnTim.tNext,$PRE.weaDat.eqnTim.tNext,weaDat.conTim.k,$PRE.weaDat.conTim.k,weaDat.conTim.tNext,$PRE.weaDat.conTim.tNext,weaDat.conTimMin.k,$PRE.weaDat.conTimMin.k,weaDat.conTimMin.tNext,$PRE.weaDat.conTimMin.tNext,weaDat.locTim.k,$PRE.weaDat.locTim.k,weaDat.locTim.tNext,$PRE.weaDat.locTim.tNext,timTab.tab.nextTimeEventScaled,$PRE.timTab.tab.nextTimeEventScaled,timTab.tab.nextTimeEvent,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,$whenCondition6)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (675):
 * Single equations (assignments): 667
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 5
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 2 systems
   {(1,5,100.0%), (1,4,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(5,6), (13,22), (1,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001028/0.9822, allocations: 0.5488 MB / 1.279 GB, free: 8.004 MB / 1.089 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001293/0.9835, allocations: 0.7062 MB / 1.279 GB, free: 7.297 MB / 1.089 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005918/0.9894, allocations: 3.369 MB / 1.283 GB, free: 3.934 MB / 1.089 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003519/0.9929, allocations: 1.838 MB / 1.285 GB, free: 2.094 MB / 1.089 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.053e-05/0.9929, allocations: 0 / 1.285 GB, free: 2.094 MB / 1.089 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002446/0.9954, allocations: 1.178 MB / 1.286 GB, free: 0.9102 MB / 1.089 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009081/0.9963, allocations: 95.98 kB / 1.286 GB, free: 0.8164 MB / 1.089 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0116/1.008, allocations: 207.7 kB / 1.286 GB, free: 0.6133 MB / 1.089 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0005423/1.008, allocations: 338.9 kB / 1.286 GB, free: 288 kB / 1.089 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.02316/1.032, allocations: 2.511 MB / 1.289 GB, free: 13.86 MB / 1.104 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0141/1.046, allocations: 3.332 MB / 1.292 GB, free: 10.58 MB / 1.104 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.01019/1.056, allocations: 0.8925 MB / 1.293 GB, free: 9.719 MB / 1.104 GB
Notification: Performance of sorting global known variables: time 0.007547/1.063, allocations: 3.709 MB / 1.297 GB, free: 6.512 MB / 1.104 GB
Notification: Performance of Backend: time 5.11e-07/1.063, allocations: 0 / 1.297 GB, free: 6.512 MB / 1.104 GB
Notification: Performance of simCode: created initialization part: time 0.01336/1.077, allocations: 7.329 MB / 1.304 GB, free: 152 kB / 1.104 GB
Notification: Performance of SimCode: time 0.03087/1.108, allocations: 24.11 MB / 1.327 GB, free: 8.172 MB / 1.135 GB
Notification: Performance of Templates: time 0.6755/1.783, allocations: 231.8 MB / 1.554 GB, free: 340.5 MB / 1.323 GB
"
[Timeout remaining time 298]
make -j1 -f Buildings_13_Buildings.Examples.Tutorial.CDL.System5.makefile [Timeout 300]
(rm -f Buildings_13_Buildings.Examples.Tutorial.CDL.System5.pipe ; mkfifo Buildings_13_Buildings.Examples.Tutorial.CDL.System5.pipe ; head -c 1048576 < Buildings_13_Buildings.Examples.Tutorial.CDL.System5.pipe >> ../files/Buildings_13_Buildings.Examples.Tutorial.CDL.System5.sim & ./Buildings_13_Buildings.Examples.Tutorial.CDL.System5 -abortSlowSimulation -alarm=240  -lv LOG_STATS > Buildings_13_Buildings.Examples.Tutorial.CDL.System5.pipe 2>&1) [Timeout 240]
[Calling os._exit(0), Time elapsed: 10.552431251853704]