Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.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/IBPSA 4.0.0-master/package.mo", uses=false)
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.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(IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir")
translateModel(IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001254/0.001254, allocations: 111.8 kB / 17.09 MB, free: 5.977 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.001161/0.001161, allocations: 194.7 kB / 18.02 MB, free: 5.059 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.346/1.346, allocations: 222.9 MB / 241.7 MB, free: 15.09 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 4.0.0-master/package.mo): time 0.7886/0.7886, allocations: 126.1 MB / 417.9 MB, free: 12.8 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001242/0.001242, allocations: 71.64 kB / 0.5737 GB, free: 27.18 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1425/0.1437, allocations: 78.7 MB / 0.6506 GB, free: 4.086 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir): time 0.5215/0.6653, allocations: 184.9 MB / 0.8312 GB, free: 4.012 MB / 0.5919 GB
Notification: Performance of NFInst.instExpressions: time 0.009882/0.6752, allocations: 6.775 MB / 0.8378 GB, free: 14.52 MB / 0.6075 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.0009861/0.6762, allocations: 29.59 kB / 0.8378 GB, free: 14.5 MB / 0.6075 GB
Notification: Performance of NFTyping.typeComponents: time 0.00109/0.6773, allocations: 355.3 kB / 0.8381 GB, free: 14.21 MB / 0.6075 GB
Notification: Performance of NFTyping.typeBindings: time 0.004984/0.6824, allocations: 2.077 MB / 0.8402 GB, free: 12.68 MB / 0.6075 GB
Notification: Performance of NFTyping.typeClassSections: time 0.001697/0.6841, allocations: 0.6772 MB / 0.8408 GB, free: 12.18 MB / 0.6075 GB
Notification: Performance of NFFlatten.flatten: time 0.002053/0.6861, allocations: 1.682 MB / 0.8425 GB, free: 11.2 MB / 0.6075 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0008645/0.687, allocations: 0.5571 MB / 0.843 GB, free: 10.88 MB / 0.6075 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001944/0.689, allocations: 0.9095 MB / 0.8439 GB, free: 10.33 MB / 0.6075 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0007988/0.6898, allocations: 480.6 kB / 0.8444 GB, free: 10.05 MB / 0.6075 GB
Notification: Performance of NFPackage.collectConstants: time 0.0001434/0.6899, allocations: 54.84 kB / 0.8444 GB, free: 10.05 MB / 0.6075 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.00396/0.6939, allocations: 2.316 MB / 0.8467 GB, free: 8.766 MB / 0.6075 GB
Notification: Performance of NFScalarize.scalarize: time 0.0008054/0.6947, allocations: 309.4 kB / 0.847 GB, free: 8.578 MB / 0.6075 GB
Notification: Performance of NFVerifyModel.verify: time 0.000536/0.6953, allocations: 385.1 kB / 0.8473 GB, free: 8.34 MB / 0.6075 GB
Notification: Performance of NFConvertDAE.convert: time 0.003063/0.6983, allocations: 1.999 MB / 0.8493 GB, free: 7.141 MB / 0.6075 GB
Notification: Performance of FrontEnd - DAE generated: time 3.376e-06/0.6984, allocations: 0 / 0.8493 GB, free: 7.141 MB / 0.6075 GB
Notification: Performance of FrontEnd: time 1.232e-06/0.6984, allocations: 0 / 0.8493 GB, free: 7.141 MB / 0.6075 GB
Notification: Performance of Transformations before backend: time 2.835e-05/0.6984, allocations: 0 / 0.8493 GB, free: 7.141 MB / 0.6075 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 119
 * Number of variables: 119
Notification: Performance of Generate backend data structure: time 0.001891/0.7003, allocations: 1.144 MB / 0.8504 GB, free: 6.215 MB / 0.6075 GB
Notification: Performance of prepare preOptimizeDAE: time 3.481e-05/0.7003, allocations: 10.22 kB / 0.8504 GB, free: 6.215 MB / 0.6075 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001315/0.7017, allocations: 241 kB / 0.8506 GB, free: 6.102 MB / 0.6075 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001037/0.7027, allocations: 0.6465 MB / 0.8513 GB, free: 5.59 MB / 0.6075 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.224e-05/0.7028, allocations: 33.53 kB / 0.8513 GB, free: 5.574 MB / 0.6075 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 7.905e-05/0.7028, allocations: 48.78 kB / 0.8514 GB, free: 5.559 MB / 0.6075 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.000873/0.7037, allocations: 0.595 MB / 0.8519 GB, free: 5.207 MB / 0.6075 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.922e-05/0.7037, allocations: 3.938 kB / 0.8519 GB, free: 5.203 MB / 0.6075 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.938e-05/0.7038, allocations: 21.47 kB / 0.852 GB, free: 5.188 MB / 0.6075 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.413e-05/0.7038, allocations: 22.03 kB / 0.852 GB, free: 5.184 MB / 0.6075 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0007057/0.7045, allocations: 483.8 kB / 0.8524 GB, free: 4.957 MB / 0.6075 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.002563/0.7071, allocations: 2.148 MB / 0.8545 GB, free: 3.129 MB / 0.6075 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0006047/0.7077, allocations: 393 kB / 0.8549 GB, free: 2.918 MB / 0.6075 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0002773/0.708, allocations: 216.9 kB / 0.8551 GB, free: 2.812 MB / 0.6075 GB
Notification: Performance of preOpt evalFunc (simulation): time 5.66e-05/0.7081, allocations: 11.11 kB / 0.8551 GB, free: 2.809 MB / 0.6075 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.686e-05/0.7081, allocations: 41.62 kB / 0.8552 GB, free: 2.762 MB / 0.6075 GB
Notification: Performance of pre-optimization done (n=34): time 1.974e-06/0.7081, allocations: 0 / 0.8552 GB, free: 2.762 MB / 0.6075 GB
Notification: Performance of matching and sorting (n=34): time 0.001432/0.7096, allocations: 0.6963 MB / 0.8559 GB, free: 2.082 MB / 0.6075 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.106e-05/0.7096, allocations: 55.41 kB / 0.8559 GB, free: 2.016 MB / 0.6075 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005281/0.7101, allocations: 498.1 kB / 0.8564 GB, free: 1.52 MB / 0.6075 GB
Notification: Performance of collectPreVariables (initialization): time 3.64e-05/0.7102, allocations: 29.7 kB / 0.8564 GB, free: 1.484 MB / 0.6075 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000194/0.7104, allocations: 304.5 kB / 0.8567 GB, free: 1.18 MB / 0.6075 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001057/0.7105, allocations: 118.9 kB / 0.8568 GB, free: 1.062 MB / 0.6075 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001133/0.7106, allocations: 77.69 kB / 0.8569 GB, free: 0.9805 MB / 0.6075 GB
Notification: Performance of setup shared object (initialization): time 0.0001375/0.7108, allocations: 362.7 kB / 0.8572 GB, free: 0.6172 MB / 0.6075 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0002381/0.711, allocations: 164.7 kB / 0.8574 GB, free: 464 kB / 0.6075 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.000274/0.7113, allocations: 217.4 kB / 0.8576 GB, free: 228 kB / 0.6075 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0005154/0.7118, allocations: 373.2 kB / 0.858 GB, free: 15.85 MB / 0.6231 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.605e-06/0.7118, allocations: 0 / 0.858 GB, free: 15.85 MB / 0.6231 GB
Notification: Performance of matching and sorting (n=42) (initialization): time 0.000695/0.7125, allocations: 480.9 kB / 0.8584 GB, free: 15.37 MB / 0.6231 GB
Notification: Performance of prepare postOptimizeDAE: time 1.378e-05/0.7125, allocations: 8 kB / 0.8584 GB, free: 15.36 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.849e-06/0.7126, allocations: 0 / 0.8584 GB, free: 15.36 MB / 0.6231 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.297e-05/0.7126, allocations: 0 / 0.8584 GB, free: 15.36 MB / 0.6231 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.000479/0.7131, allocations: 98.62 kB / 0.8585 GB, free: 15.27 MB / 0.6231 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.0003296/0.7134, allocations: 0.5938 MB / 0.8591 GB, free: 14.65 MB / 0.6231 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003375/0.7137, allocations: 43.94 kB / 0.8591 GB, free: 14.61 MB / 0.6231 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 4.149e-05/0.7138, allocations: 16 kB / 0.8592 GB, free: 14.59 MB / 0.6231 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0004055/0.7142, allocations: 339.8 kB / 0.8595 GB, free: 14.26 MB / 0.6231 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0002729/0.7145, allocations: 217.5 kB / 0.8597 GB, free: 14.03 MB / 0.6231 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.0004826/0.715, allocations: 367.2 kB / 0.86 GB, free: 13.66 MB / 0.6231 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 7.684e-06/0.715, allocations: 0 / 0.86 GB, free: 13.66 MB / 0.6231 GB
Notification: Performance of matching and sorting (n=42) (initialization_lambda0): time 0.0006672/0.7157, allocations: 477.2 kB / 0.8605 GB, free: 13.18 MB / 0.6231 GB
Notification: Performance of prepare postOptimizeDAE: time 8.436e-06/0.7157, allocations: 4 kB / 0.8605 GB, free: 13.18 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.044e-06/0.7157, allocations: 0 / 0.8605 GB, free: 13.18 MB / 0.6231 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.571e-06/0.7157, allocations: 5.562 kB / 0.8605 GB, free: 13.18 MB / 0.6231 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0003233/0.716, allocations: 83.83 kB / 0.8606 GB, free: 13.09 MB / 0.6231 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 6.482e-06/0.716, allocations: 7.938 kB / 0.8606 GB, free: 13.09 MB / 0.6231 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003216/0.7164, allocations: 35.89 kB / 0.8606 GB, free: 13.05 MB / 0.6231 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 3.325e-05/0.7164, allocations: 16 kB / 0.8606 GB, free: 13.04 MB / 0.6231 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 4
 * 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 (42):
 * Single equations (assignments): 41
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 1
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 1 system
   {1}
 * Without analytic Jacobian (size): 0 systems
Notification: Performance of prepare postOptimizeDAE: time 0.0001294/0.7165, allocations: 204.1 kB / 0.8608 GB, free: 12.83 MB / 0.6231 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 9.305e-05/0.7166, allocations: 55.62 kB / 0.8609 GB, free: 12.78 MB / 0.6231 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0007682/0.7174, allocations: 476.6 kB / 0.8613 GB, free: 12.3 MB / 0.6231 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0006729/0.7181, allocations: 0.6964 MB / 0.862 GB, free: 11.52 MB / 0.6231 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.974e-06/0.7181, allocations: 0 / 0.862 GB, free: 11.52 MB / 0.6231 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002109/0.7183, allocations: 176.7 kB / 0.8622 GB, free: 11.32 MB / 0.6231 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 7.038e-05/0.7184, allocations: 7.984 kB / 0.8622 GB, free: 11.32 MB / 0.6231 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.000312/0.7187, allocations: 15.98 kB / 0.8622 GB, free: 11.3 MB / 0.6231 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 5.253e-05/0.7188, allocations: 23.97 kB / 0.8622 GB, free: 11.28 MB / 0.6231 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.0009662/0.7198, allocations: 0.5377 MB / 0.8628 GB, free: 10.73 MB / 0.6231 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.0008853/0.7207, allocations: 0.7724 MB / 0.8635 GB, free: 9.938 MB / 0.6231 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0002397/0.7209, allocations: 180.3 kB / 0.8637 GB, free: 9.762 MB / 0.6231 GB
Notification: Performance of sorting global known variables: time 0.0003652/0.7213, allocations: 354.8 kB / 0.864 GB, free: 9.418 MB / 0.6231 GB
Notification: Performance of Backend: time 2.364e-06/0.7213, allocations: 4 kB / 0.864 GB, free: 9.414 MB / 0.6231 GB
Notification: Performance of simCode: created initialization part: time 0.002759/0.7241, allocations: 1.129 MB / 0.8651 GB, free: 8.297 MB / 0.6231 GB
Notification: Performance of SimCode: time 0.002437/0.7265, allocations: 1.888 MB / 0.867 GB, free: 6.359 MB / 0.6231 GB
Notification: Performance of Templates: time 0.02835/0.7549, allocations: 19.04 MB / 0.8856 GB, free: 3.434 MB / 0.6387 GB
make -j1 -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.sim & ./IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.MixingVolumes.Validation.MSLCombustionAir.pipe 2>&1)