Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.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.MixingVolumes.Validation.CoupledVolumesWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater")
translateModel(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0009304/0.0009304, allocations: 99.58 kB / 16.02 MB, free: 76 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001215/0.001215, allocations: 188.6 kB / 16.92 MB, free: 6.746 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: 223.3 MB / 241 MB, free: 5.355 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 1.01/1.01, allocations: 143.2 MB / 435.3 MB, free: 13.73 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003408/0.0003408, allocations: 8 kB / 0.517 GB, free: 11.21 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1607/0.161, allocations: 83.41 MB / 0.5985 GB, free: 7.758 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater): time 0.3012/0.4622, allocations: 25.45 MB / 0.6234 GB, free: 29.45 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.008335/0.4706, allocations: 7.093 MB / 0.6303 GB, free: 25.4 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001142/0.4718, allocations: 34.38 kB / 0.6303 GB, free: 25.38 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.0009694/0.4727, allocations: 322.1 kB / 0.6306 GB, free: 25.2 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.002781/0.4755, allocations: 1.031 MB / 0.6316 GB, free: 24.61 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002165/0.4777, allocations: 0.8601 MB / 0.6325 GB, free: 24.14 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.002745/0.4805, allocations: 2.815 MB / 0.6352 GB, free: 23.12 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0007306/0.4812, allocations: 0.5067 MB / 0.6357 GB, free: 22.9 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.0009727/0.4822, allocations: 0.5886 MB / 0.6363 GB, free: 22.66 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0006274/0.4828, allocations: 0.528 MB / 0.6368 GB, free: 22.44 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 6.25e-05/0.4829, allocations: 23.34 kB / 0.6368 GB, free: 22.44 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001401/0.4843, allocations: 0.7789 MB / 0.6376 GB, free: 22.16 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.0001371/0.4845, allocations: 135 kB / 0.6377 GB, free: 22.1 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.0002955/0.4848, allocations: 370.3 kB / 0.6381 GB, free: 21.88 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.0009243/0.4857, allocations: 0.9295 MB / 0.639 GB, free: 21.66 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 4.809e-06/0.4857, allocations: 0 / 0.639 GB, free: 21.66 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.764e-06/0.4857, allocations: 3.938 kB / 0.639 GB, free: 21.66 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 8.816e-06/0.4857, allocations: 0 / 0.639 GB, free: 21.66 MB / 0.4981 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 110
 * Number of variables: 110
Notification: Performance of Generate backend data structure: time 0.001129/0.4869, allocations: 0.9187 MB / 0.6399 GB, free: 20.96 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 3.518e-05/0.4869, allocations: 12.59 kB / 0.6399 GB, free: 20.95 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0002498/0.4872, allocations: 89.72 kB / 0.64 GB, free: 20.91 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.0009281/0.4881, allocations: 0.7234 MB / 0.6407 GB, free: 20.45 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 2.799e-05/0.4881, allocations: 38.84 kB / 0.6407 GB, free: 20.43 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 6.635e-05/0.4882, allocations: 46.97 kB / 0.6408 GB, free: 20.41 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0006684/0.4889, allocations: 0.5007 MB / 0.6412 GB, free: 20.15 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.993e-05/0.4889, allocations: 4.5 kB / 0.6413 GB, free: 20.14 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.19e-05/0.489, allocations: 17.09 kB / 0.6413 GB, free: 20.13 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.278e-05/0.489, allocations: 12.06 kB / 0.6413 GB, free: 20.13 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004485/0.4895, allocations: 329 kB / 0.6416 GB, free: 20.02 MB / 0.4981 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.002152/0.4916, allocations: 2.743 MB / 0.6443 GB, free: 17.75 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0002656/0.4919, allocations: 194.5 kB / 0.6445 GB, free: 17.66 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001207/0.492, allocations: 103.3 kB / 0.6446 GB, free: 17.62 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000244/0.4923, allocations: 338.6 kB / 0.6449 GB, free: 17.24 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.25e-05/0.4923, allocations: 41.72 kB / 0.6449 GB, free: 17.2 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=22): time 2.144e-06/0.4923, allocations: 0 / 0.6449 GB, free: 17.2 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=22): time 0.0005023/0.4928, allocations: 315.1 kB / 0.6452 GB, free: 17.05 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 2.465e-05/0.4929, allocations: 53.88 kB / 0.6453 GB, free: 16.99 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0004716/0.4933, allocations: 0.51 MB / 0.6458 GB, free: 16.76 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 2.706e-05/0.4934, allocations: 32.45 kB / 0.6458 GB, free: 16.72 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0001679/0.4935, allocations: 346.5 kB / 0.6461 GB, free: 16.45 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 5.254e-05/0.4936, allocations: 85.41 kB / 0.6462 GB, free: 16.38 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.389e-05/0.4937, allocations: 42.83 kB / 0.6463 GB, free: 16.35 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 7.007e-05/0.4937, allocations: 360.2 kB / 0.6466 GB, free: 16.02 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001203/0.4939, allocations: 84.56 kB / 0.6467 GB, free: 15.99 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0001707/0.494, allocations: 233.2 kB / 0.6469 GB, free: 15.78 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.000303/0.4944, allocations: 337.5 kB / 0.6472 GB, free: 15.52 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.949e-06/0.4944, allocations: 2.625 kB / 0.6472 GB, free: 15.52 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=44) (initialization): time 0.0004589/0.4948, allocations: 388.9 kB / 0.6476 GB, free: 15.35 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 7.939e-05/0.4949, allocations: 214.9 kB / 0.6478 GB, free: 15.09 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 8.516e-06/0.4949, allocations: 6.625 kB / 0.6478 GB, free: 15.09 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.815e-05/0.495, allocations: 10.5 kB / 0.6478 GB, free: 15.09 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002462/0.4952, allocations: 80.12 kB / 0.6479 GB, free: 15.08 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.043e-05/0.4952, allocations: 10.31 kB / 0.6479 GB, free: 15.08 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003945/0.4956, allocations: 58.95 kB / 0.648 GB, free: 15.05 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.794e-05/0.4957, allocations: 25.34 kB / 0.648 GB, free: 15.04 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 6.071e-06/0.4957, allocations: 3.406 kB / 0.648 GB, free: 15.04 MB / 0.4981 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.0003641/0.496, allocations: 310.2 kB / 0.6483 GB, free: 14.92 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.398e-06/0.4961, allocations: 5.25 kB / 0.6483 GB, free: 14.92 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.22e-06/0.4961, allocations: 5.25 kB / 0.6483 GB, free: 14.92 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002085/0.4963, allocations: 81.91 kB / 0.6484 GB, free: 14.91 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 5.891e-06/0.4963, allocations: 5.156 kB / 0.6484 GB, free: 14.91 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00032/0.4966, allocations: 48.23 kB / 0.6484 GB, free: 14.89 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.318e-05/0.4967, allocations: 18.72 kB / 0.6484 GB, free: 14.89 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 19
 * 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 (44):
 * Single equations (assignments): 44
 * 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.0002155/0.4969, allocations: 285.3 kB / 0.6487 GB, free: 14.64 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 7.515e-05/0.497, allocations: 58.03 kB / 0.6488 GB, free: 14.61 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0004016/0.4974, allocations: 279.6 kB / 0.649 GB, free: 14.49 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.946e-06/0.4974, allocations: 6.344 kB / 0.649 GB, free: 14.49 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 4.829e-06/0.4974, allocations: 11.88 kB / 0.6491 GB, free: 14.48 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0002678/0.4977, allocations: 376.1 kB / 0.6494 GB, free: 14.17 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001158/0.4988, allocations: 1.054 MB / 0.6504 GB, free: 13.45 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.067e-06/0.4988, allocations: 2.344 kB / 0.6504 GB, free: 13.45 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000196/0.499, allocations: 52.78 kB / 0.6505 GB, free: 13.45 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 5.21e-06/0.4991, allocations: 2.641 kB / 0.6505 GB, free: 13.45 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.094e-05/0.4991, allocations: 13.92 kB / 0.6505 GB, free: 13.44 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 4.829e-06/0.4991, allocations: 10.84 kB / 0.6505 GB, free: 13.43 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.126e-06/0.4991, allocations: 2.812 kB / 0.6505 GB, free: 13.43 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005824/0.4997, allocations: 0.5251 MB / 0.651 GB, free: 13.08 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0002326/0.4999, allocations: 182.5 kB / 0.6512 GB, free: 12.93 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 7.681e-05/0.5, allocations: 12.09 kB / 0.6512 GB, free: 12.92 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003877/0.5004, allocations: 27.36 kB / 0.6512 GB, free: 12.92 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 3.957e-05/0.5005, allocations: 22.94 kB / 0.6513 GB, free: 12.91 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 3.576e-05/0.5005, allocations: 22.45 kB / 0.6513 GB, free: 12.91 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.0004297/0.5009, allocations: 399 kB / 0.6517 GB, free: 12.72 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1.41e-07/0.5009, allocations: 0 / 0.6517 GB, free: 12.72 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.00102/0.502, allocations: 351.1 kB / 0.652 GB, free: 12.65 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 10
 * Number of states: 2 (vol.dynBal.U,vol1.dynBal.U)
 * 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 (25):
 * Single equations (assignments): 24
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 1
 * 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.0006329/0.5026, allocations: 294 kB / 0.6523 GB, free: 12.5 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.0006237/0.5032, allocations: 407.8 kB / 0.6527 GB, free: 12.37 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 3.397e-06/0.5033, allocations: 0 / 0.6527 GB, free: 12.37 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.0001473/0.5034, allocations: 108 kB / 0.6528 GB, free: 12.33 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002081/0.5055, allocations: 388.1 kB / 0.6531 GB, free: 12.25 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.001799/0.5073, allocations: 1.38 MB / 0.6545 GB, free: 11.3 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0003522/0.5077, allocations: 0.8938 MB / 0.6554 GB, free: 10.59 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0001637/0.5079, allocations: 62.62 kB / 0.6554 GB, free: 10.57 MB / 0.4981 GB
Notification: Performance of SimCode: time 5.51e-07/0.5079, allocations: 0 / 0.6554 GB, free: 10.57 MB / 0.4981 GB
Notification: Performance of Templates: time 0.02122/0.5291, allocations: 10.75 MB / 0.6659 GB, free: 9.371 MB / 0.4981 GB
make -j1 -f BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.makefile
(rm -f BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.pipe ; mkfifo BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.pipe ; head -c 1048576 < BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.pipe >> ../files/BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.sim & ./BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater  -abortSlowSimulation -alarm=480 -lv LOG_STATS > BuildSysPro_BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater.pipe 2>&1)