Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.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.001875/0.001875, allocations: 118.7 kB / 17.13 MB, free: 5.781 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.002504/0.002504, allocations: 193.2 kB / 20.08 MB, free: 2.871 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.453/1.453, allocations: 225.6 MB / 248.5 MB, free: 3.598 MB / 190.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0-master/package.mo", uses=false) [Timeout 180]
"[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0-master/Building/BuildingEnvelope/HeatTransfer/package.mo:2:1-40:17:writable] Warning: Soil_case_nappe was referenced in the package.order file, but was not found in package.mo, Soil_case_nappe/package.mo or Soil_case_nappe.mo.
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0-master/package.mo): time 0.9146/0.9146, allocations: 145.2 MB / 448.6 MB, free: 13.95 MB / 350.1 MB
"
[Timeout remaining time 179]
Using package BuildSysPro with version 3.6.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.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(BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom")
translateModel(BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 0.001949/0.001949, allocations: 64.27 kB / 0.6211 GB, free: 34.86 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1627/0.1646, allocations: 86.63 MB / 0.7057 GB, free: 2.656 MB / 0.5137 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom): time 0.7335/0.8981, allocations: 214.6 MB / 0.9153 GB, free: 3.441 MB / 0.6856 GB
Notification: Performance of NFInst.instExpressions: time 0.01343/0.9115, allocations: 10.25 MB / 0.9253 GB, free: 3.336 MB / 0.6856 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002159/0.9137, allocations: 40.69 kB / 0.9253 GB, free: 3.336 MB / 0.6856 GB
Notification: Performance of NFTyping.typeComponents: time 0.002293/0.916, allocations: 0.8879 MB / 0.9262 GB, free: 3.312 MB / 0.6856 GB
Notification: Performance of NFTyping.typeBindings: time 0.007597/0.9236, allocations: 2.983 MB / 0.9291 GB, free: 3.191 MB / 0.6856 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003644/0.9272, allocations: 1.425 MB / 0.9305 GB, free: 3.156 MB / 0.6856 GB
Notification: Performance of NFFlatten.flatten: time 0.004374/0.9316, allocations: 3.172 MB / 0.9336 GB, free: 3.148 MB / 0.6856 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001354/0.933, allocations: 0.849 MB / 0.9344 GB, free: 3.086 MB / 0.6856 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.002457/0.9354, allocations: 1.172 MB / 0.9356 GB, free: 3.078 MB / 0.6856 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002748/0.9382, allocations: 1.148 MB / 0.9367 GB, free: 3.047 MB / 0.6856 GB
Notification: Performance of NFPackage.collectConstants: time 0.00025/0.9384, allocations: 121.4 kB / 0.9368 GB, free: 3.047 MB / 0.6856 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.006005/0.9444, allocations: 2.912 MB / 0.9396 GB, free: 3.02 MB / 0.6856 GB
Notification: Performance of NFScalarize.scalarize: time 0.0007562/0.9452, allocations: 0.4959 MB / 0.9401 GB, free: 2.957 MB / 0.6856 GB
Notification: Performance of NFVerifyModel.verify: time 0.001232/0.9464, allocations: 0.7283 MB / 0.9408 GB, free: 2.777 MB / 0.6856 GB
Notification: Performance of NFConvertDAE.convert: time 0.006438/0.9528, allocations: 2.901 MB / 0.9437 GB, free: 2.465 MB / 0.6856 GB
Notification: Performance of FrontEnd - DAE generated: time 5.49e-06/0.9529, allocations: 1.406 kB / 0.9437 GB, free: 2.465 MB / 0.6856 GB
Notification: Performance of FrontEnd: time 2.254e-06/0.9529, allocations: 3.094 kB / 0.9437 GB, free: 2.465 MB / 0.6856 GB
Notification: Performance of Transformations before backend: time 3.331e-05/0.9529, allocations: 1 kB / 0.9437 GB, free: 2.465 MB / 0.6856 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 244
 * Number of variables: 244
Notification: Performance of Generate backend data structure: time 0.004647/0.9575, allocations: 1.83 MB / 0.9455 GB, free: 1.547 MB / 0.6856 GB
Notification: Performance of prepare preOptimizeDAE: time 4.566e-05/0.9576, allocations: 14.19 kB / 0.9455 GB, free: 1.543 MB / 0.6856 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.00306/0.9606, allocations: 438.1 kB / 0.9459 GB, free: 1.469 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001798/0.9624, allocations: 0.893 MB / 0.9468 GB, free: 17.13 MB / 0.7012 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 5.3e-05/0.9625, allocations: 79.77 kB / 0.9468 GB, free: 17.13 MB / 0.7012 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001582/0.9626, allocations: 96.7 kB / 0.9469 GB, free: 17.12 MB / 0.7012 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002012/0.9647, allocations: 1.203 MB / 0.9481 GB, free: 16.99 MB / 0.7012 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.127e-05/0.9647, allocations: 2.969 kB / 0.9481 GB, free: 16.99 MB / 0.7012 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001118/0.9648, allocations: 39.78 kB / 0.9481 GB, free: 16.99 MB / 0.7012 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 6.205e-05/0.9649, allocations: 52.58 kB / 0.9482 GB, free: 16.99 MB / 0.7012 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001733/0.9666, allocations: 1.075 MB / 0.9492 GB, free: 16.98 MB / 0.7012 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.005934/0.9725, allocations: 4.758 MB / 0.9539 GB, free: 14.29 MB / 0.7012 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.001433/0.974, allocations: 0.9483 MB / 0.9548 GB, free: 14.07 MB / 0.7012 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0009707/0.9749, allocations: 0.5673 MB / 0.9554 GB, free: 14.06 MB / 0.7012 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0006839/0.9756, allocations: 0.6804 MB / 0.956 GB, free: 13.3 MB / 0.7012 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.34e-05/0.9757, allocations: 44.22 kB / 0.9561 GB, free: 13.26 MB / 0.7012 GB
Notification: Performance of pre-optimization done (n=82): time 3.246e-06/0.9757, allocations: 0 / 0.9561 GB, free: 13.26 MB / 0.7012 GB
Notification: Performance of matching and sorting (n=83): time 0.004322/0.98, allocations: 2.313 MB / 0.9583 GB, free: 12.75 MB / 0.7012 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.78e-05/0.98, allocations: 59.22 kB / 0.9584 GB, free: 12.68 MB / 0.7012 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0008451/0.9809, allocations: 0.6385 MB / 0.959 GB, free: 12.55 MB / 0.7012 GB
Notification: Performance of collectPreVariables (initialization): time 8.765e-05/0.981, allocations: 43.95 kB / 0.9591 GB, free: 12.52 MB / 0.7012 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0002804/0.9813, allocations: 467.7 kB / 0.9595 GB, free: 12.16 MB / 0.7012 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002868/0.9815, allocations: 311.9 kB / 0.9598 GB, free: 11.92 MB / 0.7012 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002289/0.9818, allocations: 132.2 kB / 0.9599 GB, free: 11.85 MB / 0.7012 GB
Notification: Performance of setup shared object (initialization): time 0.0002111/0.982, allocations: 392.5 kB / 0.9603 GB, free: 11.5 MB / 0.7012 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005212/0.9825, allocations: 324.8 kB / 0.9606 GB, free: 11.38 MB / 0.7012 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005929/0.9831, allocations: 426.8 kB / 0.961 GB, free: 11.17 MB / 0.7012 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001214/0.9843, allocations: 0.783 MB / 0.9618 GB, free: 10.78 MB / 0.7012 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.831e-06/0.9843, allocations: 0.625 kB / 0.9618 GB, free: 10.78 MB / 0.7012 GB
Notification: Performance of matching and sorting (n=97) (initialization): time 0.001685/0.986, allocations: 0.9527 MB / 0.9627 GB, free: 10.38 MB / 0.7012 GB
Notification: Performance of prepare postOptimizeDAE: time 2.007e-05/0.986, allocations: 5.312 kB / 0.9627 GB, free: 10.38 MB / 0.7012 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.001e-05/0.986, allocations: 3.219 kB / 0.9627 GB, free: 10.38 MB / 0.7012 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0002997/0.9863, allocations: 53.75 kB / 0.9628 GB, free: 10.36 MB / 0.7012 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006882/0.987, allocations: 159.3 kB / 0.9629 GB, free: 10.35 MB / 0.7012 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0006243/0.9876, allocations: 0.6134 MB / 0.9635 GB, free: 9.785 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009315/0.9886, allocations: 25.81 kB / 0.9636 GB, free: 9.77 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.19e-05/0.9886, allocations: 27.5 kB / 0.9636 GB, free: 9.762 MB / 0.7012 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 9
 * Number of states: 0 ()
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (92):
 * Single equations (assignments): 91
 * 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,5)}
Notification: Performance of prepare postOptimizeDAE: time 0.0005946/0.9892, allocations: 302.8 kB / 0.9639 GB, free: 9.562 MB / 0.7012 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001877/0.9894, allocations: 92.06 kB / 0.964 GB, free: 9.516 MB / 0.7012 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001654/0.9911, allocations: 0.9296 MB / 0.9649 GB, free: 9.098 MB / 0.7012 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.957e-06/0.9911, allocations: 10.78 kB / 0.9649 GB, free: 9.098 MB / 0.7012 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.963e-06/0.9911, allocations: 3.031 kB / 0.9649 GB, free: 9.098 MB / 0.7012 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001261/0.9923, allocations: 0.9286 MB / 0.9658 GB, free: 8.566 MB / 0.7012 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.003582/0.9959, allocations: 2.124 MB / 0.9679 GB, free: 7.418 MB / 0.7012 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 5.18e-06/0.9959, allocations: 1.812 kB / 0.9679 GB, free: 7.418 MB / 0.7012 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001011/0.9969, allocations: 186.2 kB / 0.968 GB, free: 7.391 MB / 0.7012 GB
Notification: Performance of postOpt tearingSystem (simulation): time 8.496e-06/0.997, allocations: 2.531 kB / 0.968 GB, free: 7.391 MB / 0.7012 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 6.575e-05/0.997, allocations: 23.2 kB / 0.9681 GB, free: 7.375 MB / 0.7012 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 6.462e-06/0.997, allocations: 2.625 kB / 0.9681 GB, free: 7.375 MB / 0.7012 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 1.863e-06/0.997, allocations: 0.9062 kB / 0.9681 GB, free: 7.375 MB / 0.7012 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.002388/0.9994, allocations: 1.399 MB / 0.9694 GB, free: 6.594 MB / 0.7012 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.000581/1, allocations: 251.4 kB / 0.9697 GB, free: 6.383 MB / 0.7012 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001215/1, allocations: 19.45 kB / 0.9697 GB, free: 6.367 MB / 0.7012 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0006997/1.001, allocations: 20.94 kB / 0.9697 GB, free: 6.355 MB / 0.7012 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001378/1.001, allocations: 56.33 kB / 0.9698 GB, free: 6.324 MB / 0.7012 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.857e-05/1.001, allocations: 28.42 kB / 0.9698 GB, free: 6.316 MB / 0.7012 GB
Notification: Performance of sorting global known variables: time 0.000637/1.002, allocations: 455.6 kB / 0.9702 GB, free: 6.055 MB / 0.7012 GB
Notification: Performance of sort global known variables: time 9e-08/1.002, allocations: 0 / 0.9702 GB, free: 6.055 MB / 0.7012 GB
Notification: Performance of remove unused functions: time 0.001021/1.003, allocations: 324.1 kB / 0.9705 GB, free: 6.02 MB / 0.7012 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 1
 * Number of states: 8 (volEas.dynBal.medium.Xi[1],volEas.dynBal.U,volEas.dynBal.m,oriOutBot.mExc,oriOutTop.mExc,volOut.dynBal.medium.Xi[1],volOut.dynBal.U,volOut.dynBal.m)
 * Number of discrete variables: 0 ()
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (83):
 * Single equations (assignments): 83
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0004209/1.003, allocations: 212.8 kB / 0.9707 GB, free: 5.902 MB / 0.7012 GB
Notification: Performance of simCode: created initialization part: time 0.001476/1.005, allocations: 0.7713 MB / 0.9715 GB, free: 5.555 MB / 0.7012 GB
Notification: Performance of simCode: created event and clocks part: time 4.979e-06/1.005, allocations: 3.594 kB / 0.9715 GB, free: 5.555 MB / 0.7012 GB
Notification: Performance of simCode: created simulation system equations: time 0.0006174/1.005, allocations: 416.2 kB / 0.9719 GB, free: 5.379 MB / 0.7012 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002686/1.008, allocations: 0.6033 MB / 0.9725 GB, free: 5.078 MB / 0.7012 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.004246/1.012, allocations: 2.389 MB / 0.9748 GB, free: 3.555 MB / 0.7012 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0007842/1.013, allocations: 1.126 MB / 0.9759 GB, free: 2.656 MB / 0.7012 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004366/1.013, allocations: 87.17 kB / 0.976 GB, free: 2.641 MB / 0.7012 GB
Notification: Performance of SimCode: time 1.282e-06/1.013, allocations: 0 / 0.976 GB, free: 2.641 MB / 0.7012 GB
Notification: Performance of Templates: time 0.03332/1.047, allocations: 18.97 MB / 0.9945 GB, free: 0.8008 MB / 0.7169 GB
"
[Timeout remaining time 659]
make -j1 -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.makefile [Timeout 660]
(rm -f BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.sim & ./BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildSysPro_BuildSysPro.IBPSA.Airflow.Multizone.Examples.OneRoom.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 12.3255183941219]
Failed to read output from testmodel.py, exit status != 0:
1.0554325350094587 1.070346229 0.114184801
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x7f9b9954e050>