Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned.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.0008108/0.0008108, allocations: 80.44 kB / 19.17 MB, free: 0.7422 MB / 13.93 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.0007873/0.0007873, allocations: 170.3 kB / 22.46 MB, free: 2.105 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.8768/0.8768, allocations: 177.1 MB / 202.8 MB, free: 5.742 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.x/package.mo): time 1.6/1.6, allocations: 359 MB / 0.6037 GB, free: 11.08 MB / 0.573 GB
"
[Timeout remaining time 178]
Using package Buildings with version 11.1.2 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 11.1.2-maint.11.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: "" <> buildModelFMU(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned,fileNamePrefix="Buildings_11_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SmallOffice_Unconditioned",fmuType="me",version="2.0",platforms={"static"})
"" <> buildModelFMU(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned,fileNamePrefix="Buildings_11_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SmallOffice_Unconditioned",fmuType="me",version="2.0",platforms={"static"}) [Timeout 300]
"Notification: Performance of FrontEnd - loaded program: time 1.433e-06/1.433e-06, allocations: 0 / 0.9505 GB, free: 3.496 MB / 0.9011 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 1.209e-05/1.353e-05, allocations: 2.281 kB / 0.9505 GB, free: 3.492 MB / 0.9011 GB
Notification: Performance of NFInst.instantiate(Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned): time 0.7287/0.7288, allocations: 359 MB / 1.301 GB, free: 2.344 MB / 1.104 GB
Notification: Performance of NFInst.instExpressions: time 0.05754/0.7863, allocations: 62.73 MB / 1.362 GB, free: 3.457 MB / 1.167 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.007553/0.7939, allocations: 242.2 kB / 1.363 GB, free: 3.219 MB / 1.167 GB
Notification: Performance of NFTyping.typeComponents: time 0.007339/0.8012, allocations: 3.954 MB / 1.366 GB, free: 15.24 MB / 1.182 GB
Notification: Performance of NFTyping.typeBindings: time 0.0234/0.8246, allocations: 13.26 MB / 1.379 GB, free: 1.918 MB / 1.182 GB
Notification: Performance of NFTyping.typeClassSections: time 0.009736/0.8343, allocations: 6.67 MB / 1.386 GB, free: 11.28 MB / 1.198 GB
Notification: Performance of NFFlatten.flatten: time 0.024/0.8583, allocations: 30.27 MB / 1.415 GB, free: 12.91 MB / 1.229 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.01473/0.8731, allocations: 12.19 MB / 1.427 GB, free: 0.5703 MB / 1.229 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0285/0.9016, allocations: 18.77 MB / 1.446 GB, free: 13.86 MB / 1.26 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.01112/0.9127, allocations: 8.914 MB / 1.454 GB, free: 4.945 MB / 1.26 GB
Notification: Performance of NFPackage.collectConstants: time 0.002325/0.915, allocations: 1.938 MB / 1.456 GB, free: 3.008 MB / 1.26 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01425/0.9293, allocations: 9.188 MB / 1.465 GB, free: 9.797 MB / 1.276 GB
Notification: Performance of NFScalarize.scalarize: time 0.003524/0.9328, allocations: 5.325 MB / 1.47 GB, free: 4.457 MB / 1.276 GB
Notification: Performance of NFVerifyModel.verify: time 0.007654/0.9404, allocations: 5.842 MB / 1.476 GB, free: 14.61 MB / 1.292 GB
Notification: Performance of NFConvertDAE.convert: time 0.02804/0.9685, allocations: 28.81 MB / 1.504 GB, free: 1.719 MB / 1.307 GB
Notification: Performance of FrontEnd - DAE generated: time 6.723e-06/0.9685, allocations: 4 kB / 1.504 GB, free: 1.715 MB / 1.307 GB
Notification: Performance of FrontEnd: time 1.814e-06/0.9685, allocations: 0 / 1.504 GB, free: 1.715 MB / 1.307 GB
Notification: Performance of Transformations before backend: time 0.0004394/0.9689, allocations: 0 / 1.504 GB, free: 1.715 MB / 1.307 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 2913
 * Number of variables: 2913
Notification: Performance of Generate backend data structure: time 0.02093/0.9898, allocations: 14.77 MB / 1.519 GB, free: 2.805 MB / 1.323 GB
Notification: Performance of prepare preOptimizeDAE: time 4.84e-05/0.9899, allocations: 8.031 kB / 1.519 GB, free: 2.797 MB / 1.323 GB
Notification: Performance of preOpt introduceOutputAliases (simulation): time 0.001578/0.9915, allocations: 1.509 MB / 1.52 GB, free: 1.277 MB / 1.323 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01133/1.003, allocations: 3.504 MB / 1.524 GB, free: 13.76 MB / 1.339 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01246/1.015, allocations: 8.214 MB / 1.532 GB, free: 5.445 MB / 1.339 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0006884/1.016, allocations: 1.212 MB / 1.533 GB, free: 4.16 MB / 1.339 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001938/1.018, allocations: 1.164 MB / 1.534 GB, free: 2.996 MB / 1.339 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.02091/1.039, allocations: 16.77 MB / 1.55 GB, free: 1.516 MB / 1.354 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001367/1.039, allocations: 11.89 kB / 1.55 GB, free: 1.504 MB / 1.354 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0008707/1.04, allocations: 472 kB / 1.551 GB, free: 1.043 MB / 1.354 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0002239/1.04, allocations: 416.5 kB / 1.551 GB, free: 0.6328 MB / 1.354 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0174/1.057, allocations: 12.84 MB / 1.564 GB, free: 3.766 MB / 1.37 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.5413/1.599, allocations: 35.42 MB / 1.598 GB, free: 468.5 MB / 1.37 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.009678/1.608, allocations: 8.081 MB / 1.606 GB, free: 465.4 MB / 1.37 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.004864/1.613, allocations: 4.24 MB / 1.61 GB, free: 463.7 MB / 1.37 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.001019/1.614, allocations: 410.5 kB / 1.611 GB, free: 463.6 MB / 1.37 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.006266/1.621, allocations: 7.086 MB / 1.618 GB, free: 459.2 MB / 1.37 GB
Notification: Performance of pre-optimization done (n=753): time 1.268e-05/1.621, allocations: 0 / 1.618 GB, free: 459.2 MB / 1.37 GB
Notification: Performance of matching and sorting (n=753): time 0.01977/1.64, allocations: 13.17 MB / 1.631 GB, free: 454.1 MB / 1.37 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 0.0001944/1.641, allocations: 0.6023 MB / 1.631 GB, free: 453.5 MB / 1.37 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007237/1.648, allocations: 6.21 MB / 1.637 GB, free: 451.3 MB / 1.37 GB
Notification: Performance of collectPreVariables (initialization): time 0.0007777/1.649, allocations: 282.7 kB / 1.637 GB, free: 451.1 MB / 1.37 GB
Notification: Performance of collectInitialEqns (initialization): time 0.003154/1.652, allocations: 4.627 MB / 1.642 GB, free: 447.9 MB / 1.37 GB
Notification: Performance of collectInitialBindings (initialization): time 0.00174/1.653, allocations: 2.512 MB / 1.644 GB, free: 446 MB / 1.37 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.002114/1.656, allocations: 1.685 MB / 1.646 GB, free: 445.2 MB / 1.37 GB
Notification: Performance of setup shared object (initialization): time 3.497e-05/1.656, allocations: 305.1 kB / 1.646 GB, free: 444.9 MB / 1.37 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005087/1.661, allocations: 3.719 MB / 1.65 GB, free: 443.4 MB / 1.37 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005802/1.666, allocations: 7.32 MB / 1.657 GB, free: 437.8 MB / 1.37 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.009575/1.676, allocations: 8.628 MB / 1.666 GB, free: 433.4 MB / 1.37 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 3.671e-05/1.676, allocations: 5.719 kB / 1.666 GB, free: 433.4 MB / 1.37 GB
Notification: Performance of matching and sorting (n=1111) (initialization): time 0.014/1.69, allocations: 10.42 MB / 1.676 GB, free: 429 MB / 1.37 GB
Notification: Performance of prepare postOptimizeDAE: time 4.418e-05/1.69, allocations: 46.41 kB / 1.676 GB, free: 429 MB / 1.37 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.125e-05/1.69, allocations: 18.69 kB / 1.676 GB, free: 429 MB / 1.37 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0004561/1.691, allocations: 338.9 kB / 1.676 GB, free: 428.8 MB / 1.37 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003904/1.695, allocations: 1.772 MB / 1.678 GB, free: 428.7 MB / 1.37 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002311/1.697, allocations: 7.979 MB / 1.686 GB, free: 420.9 MB / 1.37 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004313/1.701, allocations: 0.8847 MB / 1.687 GB, free: 420.4 MB / 1.37 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0004064/1.702, allocations: 369.7 kB / 1.687 GB, free: 420.3 MB / 1.37 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.007113/1.709, allocations: 6.305 MB / 1.693 GB, free: 416.4 MB / 1.37 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004267/1.713, allocations: 7.312 MB / 1.7 GB, free: 408.8 MB / 1.37 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0104/1.723, allocations: 8.622 MB / 1.709 GB, free: 400.4 MB / 1.37 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 3.976e-05/1.723, allocations: 4 kB / 1.709 GB, free: 400.4 MB / 1.37 GB
Notification: Performance of matching and sorting (n=1111) (initialization_lambda0): time 0.01506/1.738, allocations: 10.42 MB / 1.719 GB, free: 390 MB / 1.37 GB
Notification: Performance of prepare postOptimizeDAE: time 4.257e-05/1.738, allocations: 42.64 kB / 1.719 GB, free: 389.9 MB / 1.37 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.384e-05/1.739, allocations: 20 kB / 1.719 GB, free: 389.9 MB / 1.37 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0003855/1.739, allocations: 337.8 kB / 1.719 GB, free: 389.6 MB / 1.37 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00458/1.743, allocations: 1.714 MB / 1.721 GB, free: 387.8 MB / 1.37 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001992/1.745, allocations: 5.593 MB / 1.726 GB, free: 381.8 MB / 1.37 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.006371/1.752, allocations: 0.8011 MB / 1.727 GB, free: 381 MB / 1.37 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.001515/1.753, allocations: 371.9 kB / 1.727 GB, free: 380.7 MB / 1.37 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 93
 * Number of states: 0 ()
 * Number of discrete variables: 272 (flo.att.fmuZon.EGaiRadLast,$PRE.flo.att.fmuZon.EGaiRadLast,flo.att.fmuZon.tLast,$PRE.flo.att.fmuZon.tLast,flo.att.fmuZon.dtLast,$PRE.flo.att.fmuZon.dtLast,flo.sou.fmuZon.EGaiRadLast,$PRE.flo.sou.fmuZon.EGaiRadLast,flo.sou.fmuZon.tLast,$PRE.flo.sou.fmuZon.tLast,flo.sou.fmuZon.dtLast,$PRE.flo.sou.fmuZon.dtLast,flo.eas.fmuZon.EGaiRadLast,$PRE.flo.eas.fmuZon.EGaiRadLast,flo.eas.fmuZon.tLast,$PRE.flo.eas.fmuZon.tLast,flo.eas.fmuZon.dtLast,$PRE.flo.eas.fmuZon.dtLast,flo.nor.fmuZon.EGaiRadLast,$PRE.flo.nor.fmuZon.EGaiRadLast,flo.nor.fmuZon.tLast,$PRE.flo.nor.fmuZon.tLast,flo.nor.fmuZon.dtLast,$PRE.flo.nor.fmuZon.dtLast,flo.wes.fmuZon.EGaiRadLast,$PRE.flo.wes.fmuZon.EGaiRadLast,flo.wes.fmuZon.tLast,$PRE.flo.wes.fmuZon.tLast,flo.wes.fmuZon.dtLast,$PRE.flo.wes.fmuZon.dtLast,flo.cor.fmuZon.EGaiRadLast,$PRE.flo.cor.fmuZon.EGaiRadLast,flo.cor.fmuZon.tLast,$PRE.flo.cor.fmuZon.tLast,flo.cor.fmuZon.dtLast,$PRE.flo.cor.fmuZon.dtLast,flo.building.weaDat.decAng.k,$PRE.flo.building.weaDat.decAng.k,flo.building.weaDat.decAng.tNext,$PRE.flo.building.weaDat.decAng.tNext,flo.building.weaDat.eqnTim.k,$PRE.flo.building.weaDat.eqnTim.k,flo.building.weaDat.eqnTim.tNext,$PRE.flo.building.weaDat.eqnTim.tNext,flo.building.weaDat.conTim.k,$PRE.flo.building.weaDat.conTim.k,flo.building.weaDat.conTim.tNext,$PRE.flo.building.weaDat.conTim.tNext,flo.building.weaDat.conTimMin.k,$PRE.flo.building.weaDat.conTimMin.k,flo.building.weaDat.conTimMin.tNext,$PRE.flo.building.weaDat.conTimMin.tNext,flo.building.weaDat.locTim.k,$PRE.flo.building.weaDat.locTim.k,flo.building.weaDat.locTim.tNext,$PRE.flo.building.weaDat.locTim.tNext,$whenCondition1,$whenCondition2,$whenCondition3,$whenCondition4,$whenCondition5,flo.att.vol.computeCSen,flo.att.fmuZon.nObj,flo.cor.vol.computeCSen,flo.cor.fmuZon.nObj,flo.wes.vol.computeCSen,flo.wes.fmuZon.nObj,flo.nor.vol.computeCSen,flo.nor.fmuZon.nObj,flo.eas.vol.computeCSen,flo.eas.fmuZon.nObj,flo.sou.vol.computeCSen,flo.sou.fmuZon.nObj,flo.att.fmuZon.QConLast_flow,$PRE.flo.att.fmuZon.QConLast_flow,flo.att.fmuZon.QGaiRadAve_flow,$PRE.flo.att.fmuZon.QGaiRadAve_flow,flo.att.fmuZon.TRooLast,$PRE.flo.att.fmuZon.TRooLast,flo.att.fmuZon.TAveInlet,$PRE.flo.att.fmuZon.TAveInlet,flo.att.fmuZon.mInlet_flow,$PRE.flo.att.fmuZon.mInlet_flow,flo.att.fmuZon.tNext,$PRE.flo.att.fmuZon.tNext,flo.att.fmuZon.yEP[5],$PRE.flo.att.fmuZon.yEP[5],flo.att.fmuZon.yEP[4],$PRE.flo.att.fmuZon.yEP[4],flo.att.fmuZon.yEP[3],$PRE.flo.att.fmuZon.yEP[3],flo.att.fmuZon.yEP[2],$PRE.flo.att.fmuZon.yEP[2],flo.att.fmuZon.yEP[1],$PRE.flo.att.fmuZon.yEP[1],flo.att.fmuZon.QPeo_flow,$PRE.flo.att.fmuZon.QPeo_flow,flo.att.fmuZon.QLat_flow,$PRE.flo.att.fmuZon.QLat_flow,flo.att.fmuZon.TRad,$PRE.flo.att.fmuZon.TRad,$whenCondition6,flo.cor.fmuZon.QConLast_flow,$PRE.flo.cor.fmuZon.QConLast_flow,flo.cor.fmuZon.QGaiRadAve_flow,$PRE.flo.cor.fmuZon.QGaiRadAve_flow,flo.cor.fmuZon.TRooLast,$PRE.flo.cor.fmuZon.TRooLast,flo.cor.fmuZon.TAveInlet,$PRE.flo.cor.fmuZon.TAveInlet,flo.cor.fmuZon.mInlet_flow,$PRE.flo.cor.fmuZon.mInlet_flow,flo.cor.fmuZon.tNext,$PRE.flo.cor.fmuZon.tNext,flo.cor.fmuZon.yEP[5],$PRE.flo.cor.fmuZon.yEP[5],flo.cor.fmuZon.yEP[4],$PRE.flo.cor.fmuZon.yEP[4],flo.cor.fmuZon.yEP[3],$PRE.flo.cor.fmuZon.yEP[3],flo.cor.fmuZon.yEP[2],$PRE.flo.cor.fmuZon.yEP[2],flo.cor.fmuZon.yEP[1],$PRE.flo.cor.fmuZon.yEP[1],flo.cor.fmuZon.QPeo_flow,$PRE.flo.cor.fmuZon.QPeo_flow,flo.cor.fmuZon.QLat_flow,$PRE.flo.cor.fmuZon.QLat_flow,flo.cor.fmuZon.TRad,$PRE.flo.cor.fmuZon.TRad,flo.wes.fmuZon.QConLast_flow,$PRE.flo.wes.fmuZon.QConLast_flow,flo.wes.fmuZon.QGaiRadAve_flow,$PRE.flo.wes.fmuZon.QGaiRadAve_flow,flo.wes.fmuZon.TRooLast,$PRE.flo.wes.fmuZon.TRooLast,flo.wes.fmuZon.TAveInlet,$PRE.flo.wes.fmuZon.TAveInlet,flo.wes.fmuZon.mInlet_flow,$PRE.flo.wes.fmuZon.mInlet_flow,flo.wes.fmuZon.tNext,$PRE.flo.wes.fmuZon.tNext,flo.wes.fmuZon.yEP[5],$PRE.flo.wes.fmuZon.yEP[5],flo.wes.fmuZon.yEP[4],$PRE.flo.wes.fmuZon.yEP[4],flo.wes.fmuZon.yEP[3],$PRE.flo.wes.fmuZon.yEP[3],flo.wes.fmuZon.yEP[2],$PRE.flo.wes.fmuZon.yEP[2],flo.wes.fmuZon.yEP[1],$PRE.flo.wes.fmuZon.yEP[1],flo.wes.fmuZon.QPeo_flow,$PRE.flo.wes.fmuZon.QPeo_flow,flo.wes.fmuZon.QLat_flow,$PRE.flo.wes.fmuZon.QLat_flow,flo.wes.fmuZon.TRad,$PRE.flo.wes.fmuZon.TRad,flo.nor.fmuZon.QConLast_flow,$PRE.flo.nor.fmuZon.QConLast_flow,flo.nor.fmuZon.QGaiRadAve_flow,$PRE.flo.nor.fmuZon.QGaiRadAve_flow,flo.nor.fmuZon.TRooLast,$PRE.flo.nor.fmuZon.TRooLast,flo.nor.fmuZon.TAveInlet,$PRE.flo.nor.fmuZon.TAveInlet,flo.nor.fmuZon.mInlet_flow,$PRE.flo.nor.fmuZon.mInlet_flow,flo.nor.fmuZon.tNext,$PRE.flo.nor.fmuZon.tNext,flo.nor.fmuZon.yEP[5],$PRE.flo.nor.fmuZon.yEP[5],flo.nor.fmuZon.yEP[4],$PRE.flo.nor.fmuZon.yEP[4],flo.nor.fmuZon.yEP[3],$PRE.flo.nor.fmuZon.yEP[3],flo.nor.fmuZon.yEP[2],$PRE.flo.nor.fmuZon.yEP[2],flo.nor.fmuZon.yEP[1],$PRE.flo.nor.fmuZon.yEP[1],flo.nor.fmuZon.QPeo_flow,$PRE.flo.nor.fmuZon.QPeo_flow,flo.nor.fmuZon.QLat_flow,$PRE.flo.nor.fmuZon.QLat_flow,flo.nor.fmuZon.TRad,$PRE.flo.nor.fmuZon.TRad,flo.eas.fmuZon.QConLast_flow,$PRE.flo.eas.fmuZon.QConLast_flow,flo.eas.fmuZon.QGaiRadAve_flow,$PRE.flo.eas.fmuZon.QGaiRadAve_flow,flo.eas.fmuZon.TRooLast,$PRE.flo.eas.fmuZon.TRooLast,flo.eas.fmuZon.TAveInlet,$PRE.flo.eas.fmuZon.TAveInlet,flo.eas.fmuZon.mInlet_flow,$PRE.flo.eas.fmuZon.mInlet_flow,flo.eas.fmuZon.tNext,$PRE.flo.eas.fmuZon.tNext,flo.eas.fmuZon.yEP[5],$PRE.flo.eas.fmuZon.yEP[5],flo.eas.fmuZon.yEP[4],$PRE.flo.eas.fmuZon.yEP[4],flo.eas.fmuZon.yEP[3],$PRE.flo.eas.fmuZon.yEP[3],flo.eas.fmuZon.yEP[2],$PRE.flo.eas.fmuZon.yEP[2],flo.eas.fmuZon.yEP[1],$PRE.flo.eas.fmuZon.yEP[1],flo.eas.fmuZon.QPeo_flow,$PRE.flo.eas.fmuZon.QPeo_flow,flo.eas.fmuZon.QLat_flow,$PRE.flo.eas.fmuZon.QLat_flow,flo.eas.fmuZon.TRad,$PRE.flo.eas.fmuZon.TRad,flo.sou.fmuZon.QConLast_flow,$PRE.flo.sou.fmuZon.QConLast_flow,flo.sou.fmuZon.QGaiRadAve_flow,$PRE.flo.sou.fmuZon.QGaiRadAve_flow,flo.sou.fmuZon.TRooLast,$PRE.flo.sou.fmuZon.TRooLast,flo.sou.fmuZon.TAveInlet,$PRE.flo.sou.fmuZon.TAveInlet,flo.sou.fmuZon.mInlet_flow,$PRE.flo.sou.fmuZon.mInlet_flow,flo.sou.fmuZon.tNext,$PRE.flo.sou.fmuZon.tNext,flo.sou.fmuZon.yEP[5],$PRE.flo.sou.fmuZon.yEP[5],flo.sou.fmuZon.yEP[4],$PRE.flo.sou.fmuZon.yEP[4],flo.sou.fmuZon.yEP[3],$PRE.flo.sou.fmuZon.yEP[3],flo.sou.fmuZon.yEP[2],$PRE.flo.sou.fmuZon.yEP[2],flo.sou.fmuZon.yEP[1],$PRE.flo.sou.fmuZon.yEP[1],flo.sou.fmuZon.QPeo_flow,$PRE.flo.sou.fmuZon.QPeo_flow,flo.sou.fmuZon.QLat_flow,$PRE.flo.sou.fmuZon.QLat_flow,flo.sou.fmuZon.TRad,$PRE.flo.sou.fmuZon.TRad,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,$whenCondition7,$whenCondition8,$whenCondition9,$whenCondition10,$whenCondition11,$whenCondition12,$whenCondition13,$whenCondition14,$whenCondition15,$whenCondition16)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (1054):
 * Single equations (assignments): 1033
 * Array equations: 12
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 4
 * 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): 4 systems
   {1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 4 systems
   {(1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 1 system
   {(1,13)}
Notification: Performance of prepare postOptimizeDAE: time 0.002027/1.755, allocations: 0.8694 MB / 1.728 GB, free: 379.8 MB / 1.37 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.002108/1.757, allocations: 0.8191 MB / 1.729 GB, free: 379 MB / 1.37 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01353/1.771, allocations: 10.31 MB / 1.739 GB, free: 368.6 MB / 1.37 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.996e-05/1.771, allocations: 76 kB / 1.739 GB, free: 368.5 MB / 1.37 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 3.334e-05/1.771, allocations: 11.98 kB / 1.739 GB, free: 368.5 MB / 1.37 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.009213/1.78, allocations: 7.644 MB / 1.747 GB, free: 360.7 MB / 1.37 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.03637/1.817, allocations: 21.8 MB / 1.768 GB, free: 338.9 MB / 1.37 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.84e-05/1.817, allocations: 4 kB / 1.768 GB, free: 338.9 MB / 1.37 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00382/1.821, allocations: 1.306 MB / 1.769 GB, free: 337.6 MB / 1.37 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001002/1.822, allocations: 0.5073 MB / 1.77 GB, free: 337.1 MB / 1.37 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.001121/1.823, allocations: 215.8 kB / 1.77 GB, free: 336.9 MB / 1.37 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.007696/1.83, allocations: 17.94 MB / 1.787 GB, free: 317.7 MB / 1.37 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.775e-06/1.83, allocations: 4 kB / 1.787 GB, free: 317.7 MB / 1.37 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.02078/1.851, allocations: 15.3 MB / 1.802 GB, free: 302.2 MB / 1.37 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.005538/1.857, allocations: 2.092 MB / 1.804 GB, free: 300.1 MB / 1.37 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.002318/1.859, allocations: 184.1 kB / 1.805 GB, free: 300 MB / 1.37 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.005198/1.864, allocations: 363.5 kB / 1.805 GB, free: 299.6 MB / 1.37 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.001178/1.865, allocations: 0.5007 MB / 1.805 GB, free: 299.1 MB / 1.37 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0008027/1.866, allocations: 331.8 kB / 1.806 GB, free: 298.8 MB / 1.37 GB
Notification: Performance of sorting global known variables: time 0.005317/1.871, allocations: 4.065 MB / 1.81 GB, free: 294.7 MB / 1.37 GB
Notification: Performance of sort global known variables: time 2.01e-07/1.871, allocations: 0 / 1.81 GB, free: 294.7 MB / 1.37 GB
Notification: Performance of remove unused functions: time 0.01707/1.889, allocations: 6.252 MB / 1.816 GB, free: 288.5 MB / 1.37 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * Number of states: 24 (flo.sou.fmuZon.EGaiRad,flo.sou.vol.medium.Xi[1],flo.sou.vol.U,flo.sou.vol.m,flo.eas.fmuZon.EGaiRad,flo.eas.vol.medium.Xi[1],flo.eas.vol.U,flo.eas.vol.m,flo.nor.fmuZon.EGaiRad,flo.nor.vol.medium.Xi[1],flo.nor.vol.U,flo.nor.vol.m,flo.wes.fmuZon.EGaiRad,flo.wes.vol.medium.Xi[1],flo.wes.vol.U,flo.wes.vol.m,flo.cor.fmuZon.EGaiRad,flo.cor.vol.medium.Xi[1],flo.cor.vol.U,flo.cor.vol.m,flo.att.fmuZon.EGaiRad,flo.att.vol.medium.Xi[1],flo.att.vol.U,flo.att.vol.m)
 * Number of discrete variables: 138 ($whenCondition5,$whenCondition4,$whenCondition3,$whenCondition2,$whenCondition1,flo.building.weaDat.locTim.tNext,flo.building.weaDat.locTim.k,flo.building.weaDat.conTimMin.tNext,flo.building.weaDat.conTimMin.k,flo.building.weaDat.conTim.tNext,flo.building.weaDat.conTim.k,flo.building.weaDat.eqnTim.tNext,flo.building.weaDat.eqnTim.k,flo.building.weaDat.decAng.tNext,flo.building.weaDat.decAng.k,$whenCondition16,$whenCondition15,$whenCondition14,$whenCondition13,$whenCondition12,$whenCondition11,$whenCondition10,$whenCondition9,$whenCondition8,$whenCondition7,weaDat.locTim.tNext,weaDat.locTim.k,weaDat.conTimMin.tNext,weaDat.conTimMin.k,weaDat.conTim.tNext,weaDat.conTim.k,weaDat.eqnTim.tNext,weaDat.eqnTim.k,weaDat.decAng.tNext,weaDat.decAng.k,flo.sou.fmuZon.TRad,flo.sou.fmuZon.QLat_flow,flo.sou.fmuZon.QPeo_flow,flo.sou.fmuZon.yEP[1],flo.sou.fmuZon.yEP[2],flo.sou.fmuZon.yEP[3],flo.sou.fmuZon.yEP[4],flo.sou.fmuZon.yEP[5],flo.sou.fmuZon.tNext,flo.sou.fmuZon.tLast,flo.sou.fmuZon.dtLast,flo.sou.fmuZon.mInlet_flow,flo.sou.fmuZon.TAveInlet,flo.sou.fmuZon.TRooLast,flo.sou.fmuZon.QGaiRadAve_flow,flo.sou.fmuZon.EGaiRadLast,flo.sou.fmuZon.QConLast_flow,flo.eas.fmuZon.TRad,flo.eas.fmuZon.QLat_flow,flo.eas.fmuZon.QPeo_flow,flo.eas.fmuZon.yEP[1],flo.eas.fmuZon.yEP[2],flo.eas.fmuZon.yEP[3],flo.eas.fmuZon.yEP[4],flo.eas.fmuZon.yEP[5],flo.eas.fmuZon.tNext,flo.eas.fmuZon.tLast,flo.eas.fmuZon.dtLast,flo.eas.fmuZon.mInlet_flow,flo.eas.fmuZon.TAveInlet,flo.eas.fmuZon.TRooLast,flo.eas.fmuZon.QGaiRadAve_flow,flo.eas.fmuZon.EGaiRadLast,flo.eas.fmuZon.QConLast_flow,flo.nor.fmuZon.TRad,flo.nor.fmuZon.QLat_flow,flo.nor.fmuZon.QPeo_flow,flo.nor.fmuZon.yEP[1],flo.nor.fmuZon.yEP[2],flo.nor.fmuZon.yEP[3],flo.nor.fmuZon.yEP[4],flo.nor.fmuZon.yEP[5],flo.nor.fmuZon.tNext,flo.nor.fmuZon.tLast,flo.nor.fmuZon.dtLast,flo.nor.fmuZon.mInlet_flow,flo.nor.fmuZon.TAveInlet,flo.nor.fmuZon.TRooLast,flo.nor.fmuZon.QGaiRadAve_flow,flo.nor.fmuZon.EGaiRadLast,flo.nor.fmuZon.QConLast_flow,flo.wes.fmuZon.TRad,flo.wes.fmuZon.QLat_flow,flo.wes.fmuZon.QPeo_flow,flo.wes.fmuZon.yEP[1],flo.wes.fmuZon.yEP[2],flo.wes.fmuZon.yEP[3],flo.wes.fmuZon.yEP[4],flo.wes.fmuZon.yEP[5],flo.wes.fmuZon.tNext,flo.wes.fmuZon.tLast,flo.wes.fmuZon.dtLast,flo.wes.fmuZon.mInlet_flow,flo.wes.fmuZon.TAveInlet,flo.wes.fmuZon.TRooLast,flo.wes.fmuZon.QGaiRadAve_flow,flo.wes.fmuZon.EGaiRadLast,flo.wes.fmuZon.QConLast_flow,flo.cor.fmuZon.TRad,flo.cor.fmuZon.QLat_flow,flo.cor.fmuZon.QPeo_flow,flo.cor.fmuZon.yEP[1],flo.cor.fmuZon.yEP[2],flo.cor.fmuZon.yEP[3],flo.cor.fmuZon.yEP[4],flo.cor.fmuZon.yEP[5],flo.cor.fmuZon.tNext,flo.cor.fmuZon.tLast,flo.cor.fmuZon.dtLast,flo.cor.fmuZon.mInlet_flow,flo.cor.fmuZon.TAveInlet,flo.cor.fmuZon.TRooLast,flo.cor.fmuZon.QGaiRadAve_flow,flo.cor.fmuZon.EGaiRadLast,flo.cor.fmuZon.QConLast_flow,$whenCondition6,flo.att.fmuZon.TRad,flo.att.fmuZon.QLat_flow,flo.att.fmuZon.QPeo_flow,flo.att.fmuZon.yEP[1],flo.att.fmuZon.yEP[2],flo.att.fmuZon.yEP[3],flo.att.fmuZon.yEP[4],flo.att.fmuZon.yEP[5],flo.att.fmuZon.tNext,flo.att.fmuZon.tLast,flo.att.fmuZon.dtLast,flo.att.fmuZon.mInlet_flow,flo.att.fmuZon.TAveInlet,flo.att.fmuZon.TRooLast,flo.att.fmuZon.QGaiRadAve_flow,flo.att.fmuZon.EGaiRadLast,flo.att.fmuZon.QConLast_flow)
 * Number of discrete states: 50 (flo.att.fmuZon.tNext,flo.att.fmuZon.TAveInlet,flo.att.fmuZon.tLast,flo.att.fmuZon.EGaiRadLast,flo.att.fmuZon.mInlet_flow,flo.sou.fmuZon.tNext,flo.eas.fmuZon.tNext,flo.nor.fmuZon.tNext,flo.wes.fmuZon.tNext,flo.cor.fmuZon.tNext,flo.sou.fmuZon.tLast,flo.sou.fmuZon.EGaiRadLast,flo.eas.fmuZon.tLast,flo.eas.fmuZon.EGaiRadLast,flo.nor.fmuZon.tLast,flo.nor.fmuZon.EGaiRadLast,flo.wes.fmuZon.tLast,flo.wes.fmuZon.EGaiRadLast,flo.cor.fmuZon.tLast,flo.cor.fmuZon.EGaiRadLast,weaDat.conTimMin.tNext,weaDat.conTimMin.k,weaDat.locTim.tNext,weaDat.locTim.k,weaDat.conTim.tNext,weaDat.conTim.k,weaDat.eqnTim.tNext,weaDat.eqnTim.k,weaDat.decAng.tNext,weaDat.decAng.k,flo.sou.fmuZon.TAveInlet,flo.sou.fmuZon.mInlet_flow,flo.eas.fmuZon.TAveInlet,flo.eas.fmuZon.mInlet_flow,flo.nor.fmuZon.TAveInlet,flo.nor.fmuZon.mInlet_flow,flo.wes.fmuZon.TAveInlet,flo.wes.fmuZon.mInlet_flow,flo.cor.fmuZon.TAveInlet,flo.cor.fmuZon.mInlet_flow,flo.building.weaDat.conTim.tNext,flo.building.weaDat.eqnTim.tNext,flo.building.weaDat.locTim.tNext,flo.building.weaDat.decAng.tNext,flo.building.weaDat.conTimMin.tNext,flo.building.weaDat.decAng.k,flo.building.weaDat.eqnTim.k,flo.building.weaDat.conTim.k,flo.building.weaDat.conTimMin.k,flo.building.weaDat.locTim.k)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (749):
 * Single equations (assignments): 636
 * Array equations: 0
 * Algorithm blocks: 1
 * Record equations: 0
 * When equations: 98
 * If-equations: 0
 * Equation systems (not torn): 4
 * Torn equation systems: 10
 * 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): 4 systems
   {1, 1, 1, 1}
 * Without analytic Jacobian (size): 0 systems
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 10 systems
   {(1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%), (1,2,100.0%)}
 * Non-linear torn systems (#iteration vars, #inner vars): 0 systems
Notification: Performance of Backend phase and start with SimCode phase: time 0.02346/1.912, allocations: 17.35 MB / 1.833 GB, free: 271.2 MB / 1.37 GB
Notification: Performance of simCode: created initialization part: time 0.02238/1.934, allocations: 14.45 MB / 1.847 GB, free: 256.7 MB / 1.37 GB
Notification: Performance of simCode: created event and clocks part: time 1.819e-05/1.934, allocations: 9 kB / 1.847 GB, free: 256.7 MB / 1.37 GB
Notification: Performance of simCode: created simulation system equations: time 0.007558/1.942, allocations: 5.48 MB / 1.852 GB, free: 251 MB / 1.37 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01353/1.955, allocations: 3.318 MB / 1.856 GB, free: 247.8 MB / 1.37 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.1147/2.07, allocations: 69.65 MB / 1.924 GB, free: 177.1 MB / 1.37 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00628/2.076, allocations: 5.607 MB / 1.929 GB, free: 171.4 MB / 1.37 GB
Notification: Performance of simCode: alias equations: time 0.009912/2.086, allocations: 3.408 MB / 1.932 GB, free: 168 MB / 1.37 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.006949/2.093, allocations: 5.061 MB / 1.937 GB, free: 162.9 MB / 1.37 GB
Notification: Performance of SimCode: time 2.104e-06/2.093, allocations: 0 / 1.937 GB, free: 162.9 MB / 1.37 GB
Notification: Performance of buildModelFMU: Generate the FMI files: time 0.6942/2.788, allocations: 232.3 MB / 2.164 GB, free: 433.4 MB / 1.37 GB
Notification: Performance of buildModelFMU: Generate platform static: time 19.28/22.07, allocations: 9.359 kB / 2.164 GB, free: 433.4 MB / 1.37 GB
"
[Timeout remaining time 278]
(rm -f Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned.pipe ; mkfifo Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned.pipe ; head -c 1048576 < Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned.pipe >> ../files/Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned.sim & python3 -m fmpy simulate  --output-file Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned_res.csv --start-time 0 --stop-time 172800 --timeout 400 --relative-tolerance 1e-06 --interface-type ModelExchange --solver CVode --output-interval 69.12 Buildings_11_Buildings_ThermalZones_EnergyPlus_9_6_0_Examples_SmallOffice_Unconditioned.fmu > Buildings_11_Buildings.ThermalZones.EnergyPlus_9_6_0.Examples.SmallOffice.Unconditioned.pipe 2>&1) [Timeout 420.0]
[Calling os._exit(0), Time elapsed: 26.460329307243228]
Failed to read output from testmodel.py, exit status != 0:
22.394951455295086 22.402549721 21.432313004
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x74f1a20c4290>