Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001617/0.001617, allocations: 109.7 kB / 16.45 MB, free: 6.035 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001873/0.001873, allocations: 192.5 kB / 19.41 MB, free: 3.539 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.364/1.364, allocations: 225.6 MB / 247.8 MB, free: 13.93 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/package.mo): time 2.419/2.419, allocations: 443.5 MB / 0.7287 GB, free: 13.24 MB / 0.5919 GB
"
[Timeout remaining time 177]
Using package Buildings with version 10.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/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: "" <> buildModelFMU(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer,fileNamePrefix="Buildings_10_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SingleFamilyHouse_HeatPumpRadiantHeatingGroundHeatTransfer",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer,fileNamePrefix="Buildings_10_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SingleFamilyHouse_HeatPumpRadiantHeatingGroundHeatTransfer",fmuType="me",version="2.0",platforms={"static"}) [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 0.001839/0.001839, allocations: 67.89 kB / 1.056 GB, free: 5.848 MB / 0.8575 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.7734/0.7753, allocations: 160.7 MB / 1.213 GB, free: 3.906 MB / 0.92 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer): time 0.4701/1.245, allocations: 404.1 MB / 1.608 GB, free: 2.668 MB / 1.311 GB
Notification: Performance of NFInst.instExpressions: time 0.8086/2.054, allocations: 70.28 MB / 1.676 GB, free: 25.58 MB / 1.311 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.01407/2.068, allocations: 324.7 kB / 1.677 GB, free: 25.58 MB / 1.311 GB
Notification: Performance of NFTyping.typeComponents: time 0.01516/2.083, allocations: 5.766 MB / 1.682 GB, free: 25.48 MB / 1.311 GB
Notification: Performance of NFTyping.typeBindings: time 0.03226/2.115, allocations: 12.28 MB / 1.694 GB, free: 25.08 MB / 1.311 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 10.1.1-maint.10.0.x/Fluid/Geothermal/Boreholes/BaseClasses/temperatureDrop.mo:2:1-109:20:writable] Warning: Pure function 'Buildings.Fluid.Geothermal.Boreholes.BaseClasses.temperatureDrop' contains a call to impure function 'Buildings.Fluid.Geothermal.Boreholes.BaseClasses.exchangeValues'.
Notification: Performance of NFTyping.typeClassSections: time 0.02149/2.137, allocations: 8.394 MB / 1.702 GB, free: 24.86 MB / 1.311 GB
Notification: Performance of NFFlatten.flatten: time 0.09017/2.227, allocations: 84.14 MB / 1.785 GB, free: 5.316 MB / 1.311 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.04955/2.277, allocations: 27 MB / 1.811 GB, free: 10.52 MB / 1.342 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.06607/2.343, allocations: 29.5 MB / 1.84 GB, free: 14.45 MB / 1.373 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.04483/2.388, allocations: 22.97 MB / 1.862 GB, free: 8.676 MB / 1.389 GB
Notification: Performance of NFPackage.collectConstants: time 0.01396/2.401, allocations: 4.254 MB / 1.866 GB, free: 4.422 MB / 1.389 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.04216/2.444, allocations: 13.89 MB / 1.88 GB, free: 7.07 MB / 1.404 GB
Notification: Performance of NFScalarize.scalarize: time 0.02113/2.465, allocations: 10.99 MB / 1.891 GB, free: 12.23 MB / 1.42 GB
Notification: Performance of NFVerifyModel.verify: time 0.04702/2.512, allocations: 19.15 MB / 1.909 GB, free: 9.168 MB / 1.436 GB
Notification: Performance of NFConvertDAE.convert: time 0.1052/2.617, allocations: 64.65 MB / 1.972 GB, free: 9.77 MB / 1.498 GB
Notification: Performance of FrontEnd - DAE generated: time 9.718e-06/2.617, allocations: 8 kB / 1.972 GB, free: 9.762 MB / 1.498 GB
Notification: Performance of FrontEnd: time 2.134e-06/2.617, allocations: 0 / 1.972 GB, free: 9.762 MB / 1.498 GB
Notification: Performance of Transformations before backend: time 0.001752/2.619, allocations: 0 / 1.972 GB, free: 9.762 MB / 1.498 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 4141
 * Number of variables: 4141
Notification: Performance of Generate backend data structure: time 0.1234/2.742, allocations: 38.83 MB / 2.01 GB, free: 2.918 MB / 1.529 GB
Notification: Performance of prepare preOptimizeDAE: time 5.137e-05/2.742, allocations: 12.92 kB / 2.01 GB, free: 2.906 MB / 1.529 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.009183/2.751, allocations: 2.187 MB / 2.012 GB, free: 0.7148 MB / 1.529 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.02997/2.781, allocations: 4.653 MB / 2.017 GB, free: 12.04 MB / 1.545 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.09285/2.874, allocations: 38.61 MB / 2.055 GB, free: 6.09 MB / 1.576 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.001958/2.876, allocations: 1.501 MB / 2.056 GB, free: 4.574 MB / 1.576 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.009251/2.885, allocations: 1.984 MB / 2.058 GB, free: 2.594 MB / 1.576 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.06595/2.951, allocations: 24.95 MB / 2.083 GB, free: 9.086 MB / 1.607 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0006848/2.952, allocations: 47.72 kB / 2.083 GB, free: 9.039 MB / 1.607 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.005581/2.958, allocations: 0.6654 MB / 2.083 GB, free: 8.379 MB / 1.607 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0005654/2.958, allocations: 0.6004 MB / 2.084 GB, free: 7.777 MB / 1.607 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.05374/3.012, allocations: 22.34 MB / 2.106 GB, free: 1.422 MB / 1.623 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.2657/3.278, allocations: 132.8 MB / 2.235 GB, free: 7.004 MB / 1.764 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.7408/4.018, allocations: 23.72 MB / 2.258 GB, free: 0.7302 GB / 1.764 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.01636/4.035, allocations: 9.434 MB / 2.268 GB, free: 0.727 GB / 1.764 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.1388/4.174, allocations: 59.2 MB / 2.325 GB, free: 0.6968 GB / 1.764 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.01748/4.191, allocations: 9.756 MB / 2.335 GB, free: 0.6913 GB / 1.764 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.01139/4.202, allocations: 1.245 MB / 2.336 GB, free: 0.6911 GB / 1.764 GB
Notification: Performance of pre-optimization done (n=1172): time 2.803e-05/4.202, allocations: 0 / 2.336 GB, free: 0.6911 GB / 1.764 GB
Notification: Performance of matching and sorting (n=1172): time 0.08446/4.287, allocations: 33.14 MB / 2.369 GB, free: 0.6653 GB / 1.764 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0002312/4.287, allocations: 0.5857 MB / 2.369 GB, free: 0.6646 GB / 1.764 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.04234/4.329, allocations: 24.71 MB / 2.393 GB, free: 0.641 GB / 1.764 GB
Notification: Performance of collectPreVariables (initialization): time 0.005059/4.335, allocations: 417.7 kB / 2.394 GB, free: 0.6406 GB / 1.764 GB
Notification: Performance of collectInitialEqns (initialization): time 0.01546/4.35, allocations: 11.2 MB / 2.405 GB, free: 0.6305 GB / 1.764 GB
Notification: Performance of collectInitialBindings (initialization): time 0.007615/4.358, allocations: 4.377 MB / 2.409 GB, free: 0.6262 GB / 1.764 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0153/4.373, allocations: 4.552 MB / 2.413 GB, free: 0.6218 GB / 1.764 GB
Notification: Performance of setup shared object (initialization): time 4.954e-05/4.373, allocations: 305.1 kB / 2.414 GB, free: 0.6215 GB / 1.764 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.02413/4.397, allocations: 14.44 MB / 2.428 GB, free: 0.6074 GB / 1.764 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.02881/4.426, allocations: 19 MB / 2.446 GB, free: 0.5873 GB / 1.764 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.04608/4.472, allocations: 27.83 MB / 2.473 GB, free: 0.5589 GB / 1.764 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 0.0001639/4.472, allocations: 28 kB / 2.473 GB, free: 0.5589 GB / 1.764 GB
Notification: Performance of matching and sorting (n=2597) (initialization): time 0.0903/4.562, allocations: 39.08 MB / 2.512 GB, free: 0.5207 GB / 1.764 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002095/4.563, allocations: 103.4 kB / 2.512 GB, free: 0.5206 GB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002837/4.563, allocations: 116 kB / 2.512 GB, free: 0.5204 GB / 1.764 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007405/4.57, allocations: 3.387 MB / 2.515 GB, free: 0.5171 GB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02292/4.593, allocations: 4.965 MB / 2.52 GB, free: 0.5123 GB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04177/4.635, allocations: 69.2 MB / 2.588 GB, free: 451.1 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0409/4.676, allocations: 4.393 MB / 2.592 GB, free: 446.8 MB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.006375/4.682, allocations: 0.8512 MB / 2.593 GB, free: 446 MB / 1.764 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.04408/4.726, allocations: 22.73 MB / 2.615 GB, free: 423.2 MB / 1.764 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.03028/4.757, allocations: 18.8 MB / 2.633 GB, free: 402.8 MB / 1.764 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.04658/4.803, allocations: 27.43 MB / 2.66 GB, free: 374.2 MB / 1.764 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 0.0001528/4.803, allocations: 28 kB / 2.66 GB, free: 374.2 MB / 1.764 GB
Notification: Performance of matching and sorting (n=2597) (initialization_lambda0): time 0.09039/4.894, allocations: 38.75 MB / 2.698 GB, free: 335.4 MB / 1.764 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001954/4.894, allocations: 107.4 kB / 2.698 GB, free: 335.2 MB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 0.0002914/4.894, allocations: 112 kB / 2.698 GB, free: 335.1 MB / 1.764 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.007626/4.902, allocations: 3.402 MB / 2.701 GB, free: 331.6 MB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.02294/4.925, allocations: 4.949 MB / 2.706 GB, free: 326.7 MB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.04765/4.973, allocations: 73.77 MB / 2.778 GB, free: 248.1 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.04069/5.013, allocations: 4.501 MB / 2.783 GB, free: 243.6 MB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.005884/5.019, allocations: 0.8317 MB / 2.784 GB, free: 242.8 MB / 1.764 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 446
 * Number of states: 0 ()
 * Number of discrete variables: 159 (borHol.borHol[1].TBouCon.iSam,$PRE.borHol.borHol[1].TBouCon.iSam,borHol.borHol[1].TBouCon.UOld,$PRE.borHol.borHol[1].TBouCon.UOld,borHol.borHol[1].TBouCon.QAve_flow,$PRE.borHol.borHol[1].TBouCon.QAve_flow,borHol.borHol[2].TBouCon.iSam,$PRE.borHol.borHol[2].TBouCon.iSam,borHol.borHol[2].TBouCon.UOld,$PRE.borHol.borHol[2].TBouCon.UOld,borHol.borHol[2].TBouCon.QAve_flow,$PRE.borHol.borHol[2].TBouCon.QAve_flow,borHol.borHol[3].TBouCon.iSam,$PRE.borHol.borHol[3].TBouCon.iSam,borHol.borHol[3].TBouCon.UOld,$PRE.borHol.borHol[3].TBouCon.UOld,borHol.borHol[3].TBouCon.QAve_flow,$PRE.borHol.borHol[3].TBouCon.QAve_flow,borHol.borHol[4].TBouCon.iSam,$PRE.borHol.borHol[4].TBouCon.iSam,borHol.borHol[4].TBouCon.UOld,$PRE.borHol.borHol[4].TBouCon.UOld,borHol.borHol[4].TBouCon.QAve_flow,$PRE.borHol.borHol[4].TBouCon.QAve_flow,borHol.borHol[5].TBouCon.iSam,$PRE.borHol.borHol[5].TBouCon.iSam,borHol.borHol[5].TBouCon.UOld,$PRE.borHol.borHol[5].TBouCon.UOld,borHol.borHol[5].TBouCon.QAve_flow,$PRE.borHol.borHol[5].TBouCon.QAve_flow,borHol.borHol[6].TBouCon.iSam,$PRE.borHol.borHol[6].TBouCon.iSam,borHol.borHol[6].TBouCon.UOld,$PRE.borHol.borHol[6].TBouCon.UOld,borHol.borHol[6].TBouCon.QAve_flow,$PRE.borHol.borHol[6].TBouCon.QAve_flow,borHol.borHol[7].TBouCon.iSam,$PRE.borHol.borHol[7].TBouCon.iSam,borHol.borHol[7].TBouCon.UOld,$PRE.borHol.borHol[7].TBouCon.UOld,borHol.borHol[7].TBouCon.QAve_flow,$PRE.borHol.borHol[7].TBouCon.QAve_flow,borHol.borHol[8].TBouCon.iSam,$PRE.borHol.borHol[8].TBouCon.iSam,borHol.borHol[8].TBouCon.UOld,$PRE.borHol.borHol[8].TBouCon.UOld,borHol.borHol[8].TBouCon.QAve_flow,$PRE.borHol.borHol[8].TBouCon.QAve_flow,borHol.borHol[9].TBouCon.iSam,$PRE.borHol.borHol[9].TBouCon.iSam,borHol.borHol[9].TBouCon.UOld,$PRE.borHol.borHol[9].TBouCon.UOld,borHol.borHol[9].TBouCon.QAve_flow,$PRE.borHol.borHol[9].TBouCon.QAve_flow,borHol.borHol[10].TBouCon.iSam,$PRE.borHol.borHol[10].TBouCon.iSam,borHol.borHol[10].TBouCon.UOld,$PRE.borHol.borHol[10].TBouCon.UOld,borHol.borHol[10].TBouCon.QAve_flow,$PRE.borHol.borHol[10].TBouCon.QAve_flow,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition1,$whenCondition2,zon.fmuZon.tLast,livFlo.tLast,EEle.local_reset,EHea.local_reset,conSup.cheYMinMax.y,conHea.conHea.cheYMinMax.y,livFlo.nObj,zon.vol.computeCSen,zon.fmuZon.nObj,onHeaPum.y,ava.y,$PRE.ava.y,borHol.borHol[10].TBouCon.port.T,$PRE.borHol.borHol[10].TBouCon.port.T,borHol.borHol[9].TBouCon.port.T,$PRE.borHol.borHol[9].TBouCon.port.T,borHol.borHol[8].TBouCon.port.T,$PRE.borHol.borHol[8].TBouCon.port.T,borHol.borHol[7].TBouCon.port.T,$PRE.borHol.borHol[7].TBouCon.port.T,borHol.borHol[6].TBouCon.port.T,$PRE.borHol.borHol[6].TBouCon.port.T,borHol.borHol[5].TBouCon.port.T,$PRE.borHol.borHol[5].TBouCon.port.T,borHol.borHol[4].TBouCon.port.T,$PRE.borHol.borHol[4].TBouCon.port.T,borHol.borHol[3].TBouCon.port.T,$PRE.borHol.borHol[3].TBouCon.port.T,borHol.borHol[2].TBouCon.port.T,$PRE.borHol.borHol[2].TBouCon.port.T,borHol.borHol[1].TBouCon.port.T,$PRE.borHol.borHol[1].TBouCon.port.T,heaPum.temPro.hysdTConEva.y,$PRE.heaPum.temPro.hysdTConEva.y,heaPum.temPro.on.y,heaPum.temPro.hysLow.y,$PRE.heaPum.temPro.hysLow.y,heaPum.temPro.hysHig.y,$PRE.heaPum.temPro.hysHig.y,heaPum.com.isOn,$PRE.heaPum.com.isOn,conHea.hysHea.y,$PRE.conHea.hysHea.y,livFlo.QLast_flow,livFlo.TLast,livFlo.dtLast,$PRE.livFlo.tLast,livFlo.tNext,$PRE.livFlo.tNext,livFlo.yEP[2],livFlo.yEP[1],zon.fmuZon.QConLast_flow,zon.fmuZon.TRooLast,zon.fmuZon.TAveInlet,$PRE.zon.fmuZon.TAveInlet,zon.fmuZon.mInlet_flow,$PRE.zon.fmuZon.mInlet_flow,zon.fmuZon.dtLast,$PRE.zon.fmuZon.tLast,zon.fmuZon.tNext,$PRE.zon.fmuZon.tNext,zon.fmuZon.yEP[5],zon.fmuZon.yEP[4],zon.fmuZon.yEP[3],zon.fmuZon.yEP[2],zon.fmuZon.yEP[1],zon.fmuZon.QPeo_flow,zon.fmuZon.QLat_flow,zon.fmuZon.TRad,building.weaDat.decAng.tNext,$PRE.building.weaDat.decAng.tNext,building.weaDat.eqnTim.tNext,$PRE.building.weaDat.eqnTim.tNext,building.weaDat.conTim.tNext,$PRE.building.weaDat.conTim.tNext,building.weaDat.conTimMin.tNext,$PRE.building.weaDat.conTimMin.tNext,building.weaDat.locTim.tNext,$PRE.building.weaDat.locTim.tNext,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16,$whenCondition17,$whenCondition18,$whenCondition19,$whenCondition20)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (2305):
 * Single equations (assignments): 2250
 * Array equations: 6
 * Algorithm blocks: 1
 * Record equations: 12
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 36
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 32 systems
   {(1,1,100.0%), (18,19,16.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 4 systems
   {(2,19), (1,5), (2,8), (1,6)}
Notification: Performance of prepare postOptimizeDAE: time 0.008183/5.027, allocations: 1.38 MB / 2.785 GB, free: 241.4 MB / 1.764 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.007412/5.035, allocations: 2.161 MB / 2.787 GB, free: 239.2 MB / 1.764 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.04964/5.084, allocations: 21.05 MB / 2.808 GB, free: 218 MB / 1.764 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 0.0001/5.084, allocations: 119.9 kB / 2.808 GB, free: 217.9 MB / 1.764 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 0.0001124/5.085, allocations: 20 kB / 2.808 GB, free: 217.9 MB / 1.764 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.03916/5.124, allocations: 17.31 MB / 2.825 GB, free: 200.4 MB / 1.764 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.1115/5.235, allocations: 50.58 MB / 2.874 GB, free: 149.8 MB / 1.764 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.648e-05/5.235, allocations: 3.938 kB / 2.874 GB, free: 149.8 MB / 1.764 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.01179/5.247, allocations: 2.329 MB / 2.876 GB, free: 147.4 MB / 1.764 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.005784/5.253, allocations: 2.522 MB / 2.879 GB, free: 144.8 MB / 1.764 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.003276/5.256, allocations: 0.4957 MB / 2.879 GB, free: 144.3 MB / 1.764 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.03594/5.292, allocations: 64.18 MB / 2.942 GB, free: 75.62 MB / 1.764 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 4.258e-06/5.292, allocations: 4 kB / 2.942 GB, free: 75.62 MB / 1.764 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.06994/5.362, allocations: 31.21 MB / 2.972 GB, free: 44.13 MB / 1.764 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.01676/5.379, allocations: 5.051 MB / 2.977 GB, free: 39.05 MB / 1.764 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.007971/5.387, allocations: 467.7 kB / 2.978 GB, free: 38.59 MB / 1.764 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.02247/5.409, allocations: 2.084 MB / 2.98 GB, free: 36.52 MB / 1.764 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.00315/5.412, allocations: 0.7656 MB / 2.981 GB, free: 35.75 MB / 1.764 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.002617/5.415, allocations: 463.6 kB / 2.981 GB, free: 35.29 MB / 1.764 GB
Notification: Performance of sorting global known variables: time 0.03528/5.45, allocations: 17 MB / 2.998 GB, free: 18.4 MB / 1.764 GB
Notification: Performance of sort global known variables: time 2.41e-07/5.45, allocations: 0 / 2.998 GB, free: 18.4 MB / 1.764 GB
Notification: Performance of remove unused functions: time 0.7266/6.177, allocations: 19.22 MB / 3.016 GB, free: 0.6593 GB / 1.764 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 10
 * Number of states: 190 (zon.vol.medium.Xi[1],zon.vol.U,zon.vol.m,slaFlo.sla.vol[1].dynBal.U,slaFlo.sla.con_a[1].lay[1].T[1],slaFlo.sla.con_a[1].lay[1].T[2],slaFlo.sla.con_b[1].lay[1].T[1],slaFlo.sla.con_b[1].lay[1].T[2],slaFlo.sla.con_b[1].lay[1].T[3],slaFlo.sla.con_b[1].lay[2].T[1],slaFlo.sla.con_b[1].lay[2].T[2],slaFlo.sla.con_b[1].lay[2].T[3],pum.filter.s[1],pum.filter.s[2],soi.T[1],soi.T[2],soi.T[3],soi.T[4],soi.T[5],soi.T[6],soi.T[7],soi.T[8],soi.T[9],soi.T[10],soi.T[11],soi.T[12],soi.T[13],soi.T[14],soi.T[15],soi.T[16],soi.T[17],soi.T[18],conSup.I.y,heaPum.con.vol.dynBal.U,heaPum.eva.vol.dynBal.U,pumBor.filter.s[1],pumBor.filter.s[2],borHol.borHol[1].pipFil.vol1.dynBal.U,borHol.borHol[1].pipFil.vol2.dynBal.U,borHol.borHol[1].pipFil.capFil1.T,borHol.borHol[1].pipFil.capFil2.T,borHol.borHol[1].soi.T[1],borHol.borHol[1].soi.T[2],borHol.borHol[1].soi.T[3],borHol.borHol[1].soi.T[4],borHol.borHol[1].soi.T[5],borHol.borHol[1].soi.T[6],borHol.borHol[1].soi.T[7],borHol.borHol[1].soi.T[8],borHol.borHol[1].soi.T[9],borHol.borHol[1].soi.T[10],borHol.borHol[1].TBouCon.U,borHol.borHol[2].pipFil.vol1.dynBal.U,borHol.borHol[2].pipFil.vol2.dynBal.U,borHol.borHol[2].pipFil.capFil1.T,borHol.borHol[2].pipFil.capFil2.T,borHol.borHol[2].soi.T[1],borHol.borHol[2].soi.T[2],borHol.borHol[2].soi.T[3],borHol.borHol[2].soi.T[4],borHol.borHol[2].soi.T[5],borHol.borHol[2].soi.T[6],borHol.borHol[2].soi.T[7],borHol.borHol[2].soi.T[8],borHol.borHol[2].soi.T[9],borHol.borHol[2].soi.T[10],borHol.borHol[2].TBouCon.U,borHol.borHol[3].pipFil.vol1.dynBal.U,borHol.borHol[3].pipFil.vol2.dynBal.U,borHol.borHol[3].pipFil.capFil1.T,borHol.borHol[3].pipFil.capFil2.T,borHol.borHol[3].soi.T[1],borHol.borHol[3].soi.T[2],borHol.borHol[3].soi.T[3],borHol.borHol[3].soi.T[4],borHol.borHol[3].soi.T[5],borHol.borHol[3].soi.T[6],borHol.borHol[3].soi.T[7],borHol.borHol[3].soi.T[8],borHol.borHol[3].soi.T[9],borHol.borHol[3].soi.T[10],borHol.borHol[3].TBouCon.U,borHol.borHol[4].pipFil.vol1.dynBal.U,borHol.borHol[4].pipFil.vol2.dynBal.U,borHol.borHol[4].pipFil.capFil1.T,borHol.borHol[4].pipFil.capFil2.T,borHol.borHol[4].soi.T[1],borHol.borHol[4].soi.T[2],borHol.borHol[4].soi.T[3],borHol.borHol[4].soi.T[4],borHol.borHol[4].soi.T[5],borHol.borHol[4].soi.T[6],borHol.borHol[4].soi.T[7],borHol.borHol[4].soi.T[8],borHol.borHol[4].soi.T[9],borHol.borHol[4].soi.T[10],borHol.borHol[4].TBouCon.U,borHol.borHol[5].pipFil.vol1.dynBal.U,borHol.borHol[5].pipFil.vol2.dynBal.U,borHol.borHol[5].pipFil.capFil1.T,borHol.borHol[5].pipFil.capFil2.T,borHol.borHol[5].soi.T[1],borHol.borHol[5].soi.T[2],borHol.borHol[5].soi.T[3],borHol.borHol[5].soi.T[4],borHol.borHol[5].soi.T[5],borHol.borHol[5].soi.T[6],borHol.borHol[5].soi.T[7],borHol.borHol[5].soi.T[8],borHol.borHol[5].soi.T[9],borHol.borHol[5].soi.T[10],borHol.borHol[5].TBouCon.U,borHol.borHol[6].pipFil.vol1.dynBal.U,borHol.borHol[6].pipFil.vol2.dynBal.U,borHol.borHol[6].pipFil.capFil1.T,borHol.borHol[6].pipFil.capFil2.T,borHol.borHol[6].soi.T[1],borHol.borHol[6].soi.T[2],borHol.borHol[6].soi.T[3],borHol.borHol[6].soi.T[4],borHol.borHol[6].soi.T[5],borHol.borHol[6].soi.T[6],borHol.borHol[6].soi.T[7],borHol.borHol[6].soi.T[8],borHol.borHol[6].soi.T[9],borHol.borHol[6].soi.T[10],borHol.borHol[6].TBouCon.U,borHol.borHol[7].pipFil.vol1.dynBal.U,borHol.borHol[7].pipFil.vol2.dynBal.U,borHol.borHol[7].pipFil.capFil1.T,borHol.borHol[7].pipFil.capFil2.T,borHol.borHol[7].soi.T[1],borHol.borHol[7].soi.T[2],borHol.borHol[7].soi.T[3],borHol.borHol[7].soi.T[4],borHol.borHol[7].soi.T[5],borHol.borHol[7].soi.T[6],borHol.borHol[7].soi.T[7],borHol.borHol[7].soi.T[8],borHol.borHol[7].soi.T[9],borHol.borHol[7].soi.T[10],borHol.borHol[7].TBouCon.U,borHol.borHol[8].pipFil.vol1.dynBal.U,borHol.borHol[8].pipFil.vol2.dynBal.U,borHol.borHol[8].pipFil.capFil1.T,borHol.borHol[8].pipFil.capFil2.T,borHol.borHol[8].soi.T[1],borHol.borHol[8].soi.T[2],borHol.borHol[8].soi.T[3],borHol.borHol[8].soi.T[4],borHol.borHol[8].soi.T[5],borHol.borHol[8].soi.T[6],borHol.borHol[8].soi.T[7],borHol.borHol[8].soi.T[8],borHol.borHol[8].soi.T[9],borHol.borHol[8].soi.T[10],borHol.borHol[8].TBouCon.U,borHol.borHol[9].pipFil.vol1.dynBal.U,borHol.borHol[9].pipFil.vol2.dynBal.U,borHol.borHol[9].pipFil.capFil1.T,borHol.borHol[9].pipFil.capFil2.T,borHol.borHol[9].soi.T[1],borHol.borHol[9].soi.T[2],borHol.borHol[9].soi.T[3],borHol.borHol[9].soi.T[4],borHol.borHol[9].soi.T[5],borHol.borHol[9].soi.T[6],borHol.borHol[9].soi.T[7],borHol.borHol[9].soi.T[8],borHol.borHol[9].soi.T[9],borHol.borHol[9].soi.T[10],borHol.borHol[9].TBouCon.U,borHol.borHol[10].pipFil.vol1.dynBal.U,borHol.borHol[10].pipFil.vol2.dynBal.U,borHol.borHol[10].pipFil.capFil1.T,borHol.borHol[10].pipFil.capFil2.T,borHol.borHol[10].soi.T[1],borHol.borHol[10].soi.T[2],borHol.borHol[10].soi.T[3],borHol.borHol[10].soi.T[4],borHol.borHol[10].soi.T[5],borHol.borHol[10].soi.T[6],borHol.borHol[10].soi.T[7],borHol.borHol[10].soi.T[8],borHol.borHol[10].soi.T[9],borHol.borHol[10].soi.T[10],borHol.borHol[10].TBouCon.U,EHea.y,EEle.y,firOrdTRad.y)
 * Number of discrete variables: 95 (onHeaPum.y,$whenCondition20,$whenCondition19,$whenCondition18,$whenCondition17,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,$whenCondition6,$whenCondition5,$whenCondition4,$whenCondition3,$whenCondition1,$whenCondition2,building.weaDat.locTim.tNext,building.weaDat.conTimMin.tNext,building.weaDat.conTim.tNext,building.weaDat.eqnTim.tNext,building.weaDat.decAng.tNext,zon.fmuZon.TRad,zon.fmuZon.QLat_flow,zon.fmuZon.QPeo_flow,zon.fmuZon.yEP[1],zon.fmuZon.yEP[2],zon.fmuZon.yEP[3],zon.fmuZon.yEP[4],zon.fmuZon.yEP[5],zon.fmuZon.tNext,zon.fmuZon.tLast,zon.fmuZon.dtLast,zon.fmuZon.mInlet_flow,zon.fmuZon.TAveInlet,zon.fmuZon.TRooLast,zon.fmuZon.QConLast_flow,livFlo.yEP[1],livFlo.yEP[2],livFlo.tNext,livFlo.tLast,livFlo.dtLast,livFlo.TLast,livFlo.QLast_flow,conHea.hysHea.y,heaPum.com.isOn,heaPum.temPro.hysHig.y,heaPum.temPro.hysLow.y,heaPum.temPro.on.y,heaPum.temPro.hysdTConEva.y,borHol.borHol[1].TBouCon.QAve_flow,borHol.borHol[1].TBouCon.port.T,borHol.borHol[1].TBouCon.UOld,borHol.borHol[1].TBouCon.iSam,borHol.borHol[2].TBouCon.QAve_flow,borHol.borHol[2].TBouCon.port.T,borHol.borHol[2].TBouCon.UOld,borHol.borHol[2].TBouCon.iSam,borHol.borHol[3].TBouCon.QAve_flow,borHol.borHol[3].TBouCon.port.T,borHol.borHol[3].TBouCon.UOld,borHol.borHol[3].TBouCon.iSam,borHol.borHol[4].TBouCon.QAve_flow,borHol.borHol[4].TBouCon.port.T,borHol.borHol[4].TBouCon.UOld,borHol.borHol[4].TBouCon.iSam,borHol.borHol[5].TBouCon.QAve_flow,borHol.borHol[5].TBouCon.port.T,borHol.borHol[5].TBouCon.UOld,borHol.borHol[5].TBouCon.iSam,borHol.borHol[6].TBouCon.QAve_flow,borHol.borHol[6].TBouCon.port.T,borHol.borHol[6].TBouCon.UOld,borHol.borHol[6].TBouCon.iSam,borHol.borHol[7].TBouCon.QAve_flow,borHol.borHol[7].TBouCon.port.T,borHol.borHol[7].TBouCon.UOld,borHol.borHol[7].TBouCon.iSam,borHol.borHol[8].TBouCon.QAve_flow,borHol.borHol[8].TBouCon.port.T,borHol.borHol[8].TBouCon.UOld,borHol.borHol[8].TBouCon.iSam,borHol.borHol[9].TBouCon.QAve_flow,borHol.borHol[9].TBouCon.port.T,borHol.borHol[9].TBouCon.UOld,borHol.borHol[9].TBouCon.iSam,borHol.borHol[10].TBouCon.QAve_flow,borHol.borHol[10].TBouCon.port.T,borHol.borHol[10].TBouCon.UOld,borHol.borHol[10].TBouCon.iSam,ava.y)
 * Number of discrete states: 37 (zon.fmuZon.tNext,livFlo.tNext,zon.fmuZon.tLast,livFlo.tLast,borHol.borHol[1].TBouCon.iSam,borHol.borHol[1].TBouCon.UOld,borHol.borHol[2].TBouCon.iSam,borHol.borHol[2].TBouCon.UOld,borHol.borHol[3].TBouCon.iSam,borHol.borHol[3].TBouCon.UOld,borHol.borHol[4].TBouCon.iSam,borHol.borHol[4].TBouCon.UOld,borHol.borHol[5].TBouCon.iSam,borHol.borHol[5].TBouCon.UOld,borHol.borHol[6].TBouCon.iSam,borHol.borHol[6].TBouCon.UOld,borHol.borHol[7].TBouCon.iSam,borHol.borHol[7].TBouCon.UOld,borHol.borHol[8].TBouCon.iSam,borHol.borHol[8].TBouCon.UOld,borHol.borHol[9].TBouCon.iSam,borHol.borHol[9].TBouCon.UOld,borHol.borHol[10].TBouCon.iSam,borHol.borHol[10].TBouCon.UOld,heaPum.temPro.hysLow.y,heaPum.temPro.hysHig.y,heaPum.temPro.hysdTConEva.y,conHea.hysHea.y,heaPum.com.isOn,building.weaDat.conTimMin.tNext,building.weaDat.decAng.tNext,building.weaDat.eqnTim.tNext,building.weaDat.conTim.tNext,building.weaDat.locTim.tNext,zon.fmuZon.TAveInlet,zon.fmuZon.mInlet_flow,zon.fmuZon.QGaiRad_flow)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (1045):
 * Single equations (assignments): 941
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 5
 * When equations: 63
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 35
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 32 systems
   {(1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,3,100.0%), (1,1,100.0%), (1,2,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%), (1,5,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(2,19), (1,7), (2,9)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.08326/6.26, allocations: 34.67 MB / 3.05 GB, free: 0.6569 GB / 1.764 GB
Notification: Performance of simCode: created initialization part: time 0.1014/6.362, allocations: 52.66 MB / 3.102 GB, free: 0.6347 GB / 1.764 GB
Notification: Performance of simCode: created event and clocks part: time 2.289e-05/6.362, allocations: 6.375 kB / 3.102 GB, free: 0.6347 GB / 1.764 GB
Notification: Performance of simCode: created simulation system equations: time 0.02299/6.385, allocations: 12.62 MB / 3.114 GB, free: 0.6281 GB / 1.764 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.05829/6.443, allocations: 10.74 MB / 3.124 GB, free: 0.6252 GB / 1.764 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.4647/6.908, allocations: 250.7 MB / 3.369 GB, free: 407.7 MB / 1.764 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.02249/6.93, allocations: 15.85 MB / 3.385 GB, free: 391.8 MB / 1.764 GB
Notification: Performance of simCode: alias equations: time 0.04266/6.973, allocations: 15.91 MB / 3.4 GB, free: 376 MB / 1.764 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.02292/6.996, allocations: 11.82 MB / 3.412 GB, free: 364.1 MB / 1.764 GB
Notification: Performance of SimCode: time 1.462e-06/6.996, allocations: 0 / 3.412 GB, free: 364.1 MB / 1.764 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 2.241/9.236, allocations: 0.716 GB / 4.128 GB, free: 292.6 MB / 1.764 GB
Notification: Performance of buildModelFMU: Generate platform static: time 148.8/158, allocations: 6.188 kB / 4.128 GB, free: 292.6 MB / 1.764 GB
"
[Timeout remaining time 141]
(rm -f Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer.pipe ; mkfifo Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer.pipe ; head -c 1048576 < Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer.pipe >> ../files/Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer.sim & /home/hudson/saved_omc/OMSimulator/install/bin/OMSimulator -r=Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer_res.mat --tempDir=temp_Buildings_10_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SingleFamilyHouse_HeatPumpRadiantHeatingGroundHeatTransfer_fmu --startTime=0 --stopTime=172800 --stepSize=69.12 --timeout=400 --tolerance=1e-06 Buildings_10_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SingleFamilyHouse_HeatPumpRadiantHeatingGroundHeatTransfer.fmu > Buildings_10_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SingleFamilyHouse.HeatPumpRadiantHeatingGroundHeatTransfer.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 165.86125641595572]
Failed to read output from testmodel.py, exit status != 0:
158.75258380826563 158.767372202 156.146878419
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f6aa87ee020>