Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0/package.mo", uses=false)
Using package BuildSysPro with version 3.5.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0/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.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4")
translateModel(BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.002057/0.002057, allocations: 111.3 kB / 15.23 MB, free: 0.6406 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001957/0.001957, allocations: 197.8 kB / 16.14 MB, free: 6.688 MB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.405/1.405, allocations: 222.9 MB / 239.8 MB, free: 4.613 MB / 190.1 MB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/BuildSysPro 3.6.0/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/package.mo): time 0.9733/0.9733, allocations: 143 MB / 433 MB, free: 11.64 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001276/0.001277, allocations: 63.89 kB / 0.5135 GB, free: 12.14 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1541/0.1554, allocations: 82.03 MB / 0.5936 GB, free: 10.08 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4): time 0.469/0.6244, allocations: 235.5 MB / 0.8236 GB, free: 2.086 MB / 0.6387 GB
Notification: Performance of NFInst.instExpressions: time 0.02031/0.6448, allocations: 15.96 MB / 0.8392 GB, free: 2.078 MB / 0.6544 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002176/0.647, allocations: 55.62 kB / 0.8393 GB, free: 2.023 MB / 0.6544 GB
Notification: Performance of NFTyping.typeComponents: time 0.002481/0.6495, allocations: 0.8666 MB / 0.8401 GB, free: 1.152 MB / 0.6544 GB
Notification: Performance of NFTyping.typeBindings: time 0.006902/0.6565, allocations: 2.795 MB / 0.8428 GB, free: 14.35 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.003713/0.6602, allocations: 1.518 MB / 0.8443 GB, free: 12.85 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.004975/0.6652, allocations: 4.652 MB / 0.8489 GB, free: 8.184 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.001423/0.6666, allocations: 1.074 MB / 0.8499 GB, free: 7.078 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.005756/0.6724, allocations: 2.253 MB / 0.8521 GB, free: 4.828 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002269/0.6747, allocations: 1.127 MB / 0.8532 GB, free: 3.711 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001629/0.6748, allocations: 64 kB / 0.8533 GB, free: 3.648 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.005728/0.6806, allocations: 3.597 MB / 0.8568 GB, free: 44 kB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.3537/1.034, allocations: 430 kB / 0.8572 GB, free: 8.535 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.001233/1.035, allocations: 0.7514 MB / 0.8579 GB, free: 8.535 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.004966/1.04, allocations: 3.198 MB / 0.8611 GB, free: 8.457 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 4.929e-06/1.04, allocations: 0 / 0.8611 GB, free: 8.457 MB / 0.67 GB
Notification: Performance of FrontEnd: time 1.974e-06/1.041, allocations: 0 / 0.8611 GB, free: 8.457 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 1.841e-05/1.041, allocations: 1.203 kB / 0.8611 GB, free: 8.457 MB / 0.67 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 251
 * Number of variables: 251
Notification: Performance of Generate backend data structure: time 0.003221/1.044, allocations: 2.003 MB / 0.863 GB, free: 7.59 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.236e-05/1.044, allocations: 8.75 kB / 0.863 GB, free: 7.59 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.002785/1.047, allocations: 0.495 MB / 0.8635 GB, free: 7.504 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001855/1.048, allocations: 1.11 MB / 0.8646 GB, free: 7.109 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0008478/1.049, allocations: 247.1 kB / 0.8648 GB, free: 7.094 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001296/1.049, allocations: 100.6 kB / 0.8649 GB, free: 7.094 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.002197/1.052, allocations: 1.316 MB / 0.8662 GB, free: 6.805 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.373e-05/1.052, allocations: 8.734 kB / 0.8662 GB, free: 6.805 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001015/1.052, allocations: 40.88 kB / 0.8663 GB, free: 6.805 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 4.984e-05/1.052, allocations: 51.17 kB / 0.8663 GB, free: 6.801 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.002342/1.054, allocations: 1.076 MB / 0.8674 GB, free: 6.777 MB / 0.67 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.007195/1.061, allocations: 6.634 MB / 0.8738 GB, free: 18.62 MB / 0.6856 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00157/1.063, allocations: 0.5624 MB / 0.8744 GB, free: 18.57 MB / 0.6856 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.000605/1.064, allocations: 269.3 kB / 0.8746 GB, free: 18.56 MB / 0.6856 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003777/1.064, allocations: 286.7 kB / 0.8749 GB, free: 18.28 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.069e-05/1.064, allocations: 38.12 kB / 0.8749 GB, free: 18.24 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=33): time 3.657e-06/1.064, allocations: 3 kB / 0.875 GB, free: 18.24 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=33): time 0.002607/1.067, allocations: 0.8628 MB / 0.8758 GB, free: 18.11 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.845e-05/1.067, allocations: 54.53 kB / 0.8758 GB, free: 18.04 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001861/1.069, allocations: 1.365 MB / 0.8772 GB, free: 17.87 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 7.341e-05/1.069, allocations: 34.77 kB / 0.8772 GB, free: 17.84 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0004335/1.069, allocations: 0.6811 MB / 0.8779 GB, free: 17.58 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 8.93e-05/1.069, allocations: 119.8 kB / 0.878 GB, free: 17.51 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002314/1.069, allocations: 154.2 kB / 0.8781 GB, free: 17.48 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 0.0001094/1.07, allocations: 340.3 kB / 0.8785 GB, free: 17.18 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0008157/1.07, allocations: 312 kB / 0.8788 GB, free: 17.16 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0009844/1.071, allocations: 0.6382 MB / 0.8794 GB, free: 16.75 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001588/1.073, allocations: 0.8733 MB / 0.8802 GB, free: 16.38 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 7.494e-06/1.073, allocations: 3.969 kB / 0.8802 GB, free: 16.38 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=81) (initialization): time 0.002418/1.075, allocations: 1.008 MB / 0.8812 GB, free: 16.27 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 2.506e-05/1.075, allocations: 13.44 kB / 0.8812 GB, free: 16.26 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.82e-05/1.075, allocations: 10.28 kB / 0.8812 GB, free: 16.26 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.999e-05/1.076, allocations: 13.22 kB / 0.8813 GB, free: 16.26 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008882/1.076, allocations: 198.7 kB / 0.8815 GB, free: 16.26 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.668e-05/1.076, allocations: 22.23 kB / 0.8815 GB, free: 16.26 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001513/1.078, allocations: 220.1 kB / 0.8817 GB, free: 16.19 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.758e-05/1.078, allocations: 42.27 kB / 0.8817 GB, free: 16.19 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.001289/1.079, allocations: 0.5681 MB / 0.8823 GB, free: 16.09 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.001028/1.08, allocations: 0.6366 MB / 0.8829 GB, free: 15.68 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.00164/1.082, allocations: 0.8685 MB / 0.8837 GB, free: 15.31 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.224e-06/1.082, allocations: 2.969 kB / 0.8838 GB, free: 15.31 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=81) (initialization_lambda0): time 0.002453/1.085, allocations: 1.014 MB / 0.8847 GB, free: 15.19 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 1.905e-05/1.085, allocations: 11.59 kB / 0.8848 GB, free: 15.19 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.515e-05/1.085, allocations: 10.12 kB / 0.8848 GB, free: 15.19 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.788e-05/1.085, allocations: 14.19 kB / 0.8848 GB, free: 15.19 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0008618/1.085, allocations: 195.4 kB / 0.885 GB, free: 15.18 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.443e-05/1.086, allocations: 25.23 kB / 0.885 GB, free: 15.18 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001491/1.087, allocations: 162.2 kB / 0.8851 GB, free: 15.15 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.522e-05/1.087, allocations: 40.2 kB / 0.8852 GB, free: 15.15 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * 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 (81):
 * Single equations (assignments): 81
 * 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 prepare postOptimizeDAE: time 0.0002945/1.087, allocations: 226.5 kB / 0.8854 GB, free: 14.96 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002326/1.088, allocations: 137.1 kB / 0.8855 GB, free: 14.89 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001982/1.09, allocations: 0.6589 MB / 0.8862 GB, free: 14.8 MB / 0.6856 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.125e-06/1.09, allocations: 5.812 kB / 0.8862 GB, free: 14.8 MB / 0.6856 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.903e-06/1.09, allocations: 0.9688 kB / 0.8862 GB, free: 14.8 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 5.901e-06/1.09, allocations: 0 / 0.8862 GB, free: 14.8 MB / 0.6856 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.004239/1.094, allocations: 1.934 MB / 0.8881 GB, free: 14.31 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.834e-06/1.094, allocations: 4.906 kB / 0.8881 GB, free: 14.31 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0005181/1.094, allocations: 101.1 kB / 0.8882 GB, free: 14.31 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (simulation): time 1.085e-05/1.094, allocations: 9.016 kB / 0.8882 GB, free: 14.31 MB / 0.6856 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.001e-05/1.095, allocations: 17.38 kB / 0.8882 GB, free: 14.3 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 8.276e-06/1.095, allocations: 6.359 kB / 0.8882 GB, free: 14.3 MB / 0.6856 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 7.364e-06/1.095, allocations: 9.25 kB / 0.8882 GB, free: 14.3 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001618/1.096, allocations: 0.8198 MB / 0.889 GB, free: 13.95 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0004382/1.097, allocations: 296.5 kB / 0.8893 GB, free: 13.77 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002253/1.097, allocations: 12.36 kB / 0.8893 GB, free: 13.77 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001084/1.098, allocations: 95.94 kB / 0.8894 GB, free: 13.76 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 8.833e-05/1.098, allocations: 36.45 kB / 0.8894 GB, free: 13.76 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 5.035e-05/1.098, allocations: 22.94 kB / 0.8894 GB, free: 13.76 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.001574/1.1, allocations: 0.991 MB / 0.8904 GB, free: 13.64 MB / 0.6856 GB
Notification: Performance of sort global known variables: time 1.7e-07/1.1, allocations: 1.859 kB / 0.8904 GB, free: 13.64 MB / 0.6856 GB
Notification: Performance of remove unused functions: time 0.00198/1.102, allocations: 0.5685 MB / 0.891 GB, free: 13.59 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * 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 simulation (32):
 * Single equations (assignments): 32
 * 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.0006214/1.102, allocations: 262.2 kB / 0.8912 GB, free: 13.48 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.00299/1.105, allocations: 1.1 MB / 0.8923 GB, free: 13.45 MB / 0.6856 GB
Notification: Performance of simCode: created event and clocks part: time 4.138e-06/1.105, allocations: 2.234 kB / 0.8923 GB, free: 13.45 MB / 0.6856 GB
Notification: Performance of simCode: created simulation system equations: time 0.0007376/1.106, allocations: 268.6 kB / 0.8926 GB, free: 13.44 MB / 0.6856 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003729/1.11, allocations: 0.7484 MB / 0.8933 GB, free: 13.39 MB / 0.6856 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.005162/1.115, allocations: 2.682 MB / 0.8959 GB, free: 12.01 MB / 0.6856 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0009239/1.116, allocations: 1.183 MB / 0.8971 GB, free: 11.29 MB / 0.6856 GB
Notification: Performance of simCode: alias equations: time 0.001799/1.118, allocations: 365.9 kB / 0.8974 GB, free: 11.21 MB / 0.6856 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0003062/1.118, allocations: 94 kB / 0.8975 GB, free: 11.2 MB / 0.6856 GB
Notification: Performance of SimCode: time 3.41e-07/1.118, allocations: 4.781 kB / 0.8975 GB, free: 11.2 MB / 0.6856 GB
Notification: Performance of Templates: time 0.0392/1.157, allocations: 24.09 MB / 0.921 GB, free: 6.516 MB / 0.6856 GB
make -j1 -f BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.makefile
(rm -f BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.sim & ./BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildSysPro_BuildSysPro.IBPSA.Fluid.Examples.Performance.Example4.pipe 2>&1)