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.000976/0.0009761, allocations: 91.84 kB / 15.36 MB, free: 0.7383 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.007632/0.007632, allocations: 170 kB / 16.26 MB, free: 6.613 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.389/1.389, allocations: 223.3 MB / 240.3 MB, free: 5.363 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.064/1.064, allocations: 143.2 MB / 434.6 MB, free: 13.68 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004883/0.0004883, allocations: 8 kB / 0.5164 GB, free: 11.14 MB / 430.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2449/0.2454, allocations: 83.41 MB / 0.5978 GB, free: 7.695 MB / 0.4981 GB
Notification: Performance of NFInst.instantiate(BuildSysPro.IBPSA.Fluid.MixingVolumes.Validation.CoupledVolumesWater): time 0.4487/0.6941, allocations: 25.46 MB / 0.6227 GB, free: 29.26 MB / 0.4981 GB
Notification: Performance of NFInst.instExpressions: time 0.01015/0.7043, allocations: 7.088 MB / 0.6296 GB, free: 25.23 MB / 0.4981 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001757/0.7061, allocations: 28.5 kB / 0.6296 GB, free: 25.22 MB / 0.4981 GB
Notification: Performance of NFTyping.typeComponents: time 0.001161/0.7073, allocations: 323.7 kB / 0.63 GB, free: 25.04 MB / 0.4981 GB
Notification: Performance of NFTyping.typeBindings: time 0.004526/0.7119, allocations: 1.036 MB / 0.631 GB, free: 24.44 MB / 0.4981 GB
Notification: Performance of NFTyping.typeClassSections: time 0.002529/0.7144, allocations: 0.8581 MB / 0.6318 GB, free: 23.98 MB / 0.4981 GB
Notification: Performance of NFFlatten.flatten: time 0.003582/0.718, allocations: 2.81 MB / 0.6345 GB, free: 22.96 MB / 0.4981 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.000913/0.7189, allocations: 0.5093 MB / 0.635 GB, free: 22.75 MB / 0.4981 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001362/0.7203, allocations: 0.5884 MB / 0.6356 GB, free: 22.5 MB / 0.4981 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.0008239/0.7211, allocations: 0.5385 MB / 0.6361 GB, free: 22.27 MB / 0.4981 GB
Notification: Performance of NFPackage.collectConstants: time 8.387e-05/0.7212, allocations: 27.81 kB / 0.6362 GB, free: 22.27 MB / 0.4981 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.001826/0.7231, allocations: 0.7689 MB / 0.6369 GB, free: 22 MB / 0.4981 GB
Notification: Performance of NFScalarize.scalarize: time 0.0001547/0.7232, allocations: 127.3 kB / 0.637 GB, free: 21.95 MB / 0.4981 GB
Notification: Performance of NFVerifyModel.verify: time 0.0003693/0.7236, allocations: 368.2 kB / 0.6374 GB, free: 21.73 MB / 0.4981 GB
Notification: Performance of NFConvertDAE.convert: time 0.001277/0.7249, allocations: 1.219 MB / 0.6386 GB, free: 21.36 MB / 0.4981 GB
Notification: Performance of FrontEnd - DAE generated: time 5.31e-06/0.7249, allocations: 0 / 0.6386 GB, free: 21.36 MB / 0.4981 GB
Notification: Performance of FrontEnd: time 1.713e-06/0.7249, allocations: 4 kB / 0.6386 GB, free: 21.35 MB / 0.4981 GB
Notification: Performance of Transformations before backend: time 8.796e-06/0.7249, allocations: 0 / 0.6386 GB, free: 21.35 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.001306/0.7262, allocations: 0.9183 MB / 0.6395 GB, free: 20.66 MB / 0.4981 GB
Notification: Performance of prepare preOptimizeDAE: time 4.787e-05/0.7263, allocations: 12.88 kB / 0.6395 GB, free: 20.65 MB / 0.4981 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0002927/0.7266, allocations: 83.28 kB / 0.6396 GB, free: 20.61 MB / 0.4981 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001044/0.7276, allocations: 0.7341 MB / 0.6403 GB, free: 20.14 MB / 0.4981 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.891e-05/0.7277, allocations: 37.03 kB / 0.6403 GB, free: 20.12 MB / 0.4981 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 7.613e-05/0.7278, allocations: 45.38 kB / 0.6404 GB, free: 20.11 MB / 0.4981 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.0007235/0.7285, allocations: 0.5034 MB / 0.6409 GB, free: 19.84 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 1.993e-05/0.7285, allocations: 0 / 0.6409 GB, free: 19.84 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 4.579e-05/0.7286, allocations: 18.59 kB / 0.6409 GB, free: 19.83 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 2.818e-05/0.7286, allocations: 15.84 kB / 0.6409 GB, free: 19.82 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.0004956/0.7291, allocations: 321.6 kB / 0.6412 GB, free: 19.71 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.002334/0.7315, allocations: 2.742 MB / 0.6439 GB, free: 17.45 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0002827/0.7318, allocations: 193.8 kB / 0.6441 GB, free: 17.36 MB / 0.4981 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0001272/0.7319, allocations: 101 kB / 0.6442 GB, free: 17.32 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002769/0.7322, allocations: 340.3 kB / 0.6445 GB, free: 16.95 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.511e-05/0.7322, allocations: 33.56 kB / 0.6445 GB, free: 16.91 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=22): time 2.254e-06/0.7322, allocations: 3.984 kB / 0.6445 GB, free: 16.9 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=22): time 0.0005269/0.7328, allocations: 319.2 kB / 0.6448 GB, free: 16.75 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.85e-05/0.7328, allocations: 54.03 kB / 0.6449 GB, free: 16.69 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0005401/0.7334, allocations: 0.5072 MB / 0.6454 GB, free: 16.46 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 2.756e-05/0.7334, allocations: 32.55 kB / 0.6454 GB, free: 16.43 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0001996/0.7336, allocations: 341.8 kB / 0.6457 GB, free: 16.15 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 7.13e-05/0.7337, allocations: 88.98 kB / 0.6458 GB, free: 16.09 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.615e-05/0.7337, allocations: 35.72 kB / 0.6459 GB, free: 16.06 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 8.987e-05/0.7338, allocations: 364.9 kB / 0.6462 GB, free: 15.72 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0001258/0.734, allocations: 89.3 kB / 0.6463 GB, free: 15.69 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0001946/0.7342, allocations: 235.3 kB / 0.6465 GB, free: 15.48 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0003139/0.7345, allocations: 338 kB / 0.6468 GB, free: 15.22 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 4.468e-06/0.7345, allocations: 2.906 kB / 0.6468 GB, free: 15.22 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=44) (initialization): time 0.000485/0.735, allocations: 389.8 kB / 0.6472 GB, free: 15.05 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 9.411e-05/0.7351, allocations: 218.2 kB / 0.6474 GB, free: 14.79 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 9.377e-06/0.7351, allocations: 5.375 kB / 0.6474 GB, free: 14.79 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 2.229e-05/0.7351, allocations: 5.344 kB / 0.6474 GB, free: 14.79 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002477/0.7354, allocations: 75.88 kB / 0.6475 GB, free: 14.79 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.383e-05/0.7354, allocations: 13.78 kB / 0.6475 GB, free: 14.78 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0004306/0.7358, allocations: 61.77 kB / 0.6476 GB, free: 14.75 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 3.029e-05/0.7359, allocations: 16.16 kB / 0.6476 GB, free: 14.75 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 7.684e-06/0.7359, allocations: 5.156 kB / 0.6476 GB, free: 14.75 MB / 0.4981 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.0004011/0.7363, allocations: 313.7 kB / 0.6479 GB, free: 14.62 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 5.26e-06/0.7363, allocations: 5.688 kB / 0.6479 GB, free: 14.62 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.831e-06/0.7363, allocations: 5.75 kB / 0.6479 GB, free: 14.62 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0002102/0.7365, allocations: 85.44 kB / 0.648 GB, free: 14.61 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 7.874e-06/0.7366, allocations: 9.188 kB / 0.648 GB, free: 14.61 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0003151/0.7369, allocations: 43.41 kB / 0.648 GB, free: 14.59 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 2.511e-05/0.7369, allocations: 17.12 kB / 0.648 GB, free: 14.59 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.0002267/0.7371, allocations: 287 kB / 0.6483 GB, free: 14.34 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 9.239e-05/0.7372, allocations: 57.5 kB / 0.6484 GB, free: 14.31 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0004128/0.7377, allocations: 284 kB / 0.6486 GB, free: 14.18 MB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 9.277e-06/0.7377, allocations: 2.625 kB / 0.6486 GB, free: 14.18 MB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 5.43e-06/0.7377, allocations: 0 / 0.6486 GB, free: 14.18 MB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0002787/0.738, allocations: 383.6 kB / 0.649 GB, free: 13.88 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.001239/0.7392, allocations: 1.054 MB / 0.65 GB, free: 13.16 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.418e-06/0.7392, allocations: 1.5 kB / 0.65 GB, free: 13.16 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.000194/0.7394, allocations: 55.73 kB / 0.6501 GB, free: 13.15 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 6.192e-06/0.7395, allocations: 5.781 kB / 0.6501 GB, free: 13.15 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 2.148e-05/0.7395, allocations: 7.188 kB / 0.6501 GB, free: 13.14 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 4.348e-06/0.7395, allocations: 6.875 kB / 0.6501 GB, free: 13.14 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 5.49e-06/0.7395, allocations: 8.75 kB / 0.6501 GB, free: 13.14 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0005553/0.7401, allocations: 0.5168 MB / 0.6506 GB, free: 12.79 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0001767/0.7402, allocations: 176.5 kB / 0.6508 GB, free: 12.64 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 6.597e-05/0.7403, allocations: 15.3 kB / 0.6508 GB, free: 12.64 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0003752/0.7407, allocations: 32.7 kB / 0.6508 GB, free: 12.63 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 3.152e-05/0.7407, allocations: 19.88 kB / 0.6509 GB, free: 12.62 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 2.738e-05/0.7408, allocations: 15.08 kB / 0.6509 GB, free: 12.62 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.0004502/0.7412, allocations: 408.6 kB / 0.6513 GB, free: 12.43 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 9.32e-07/0.7412, allocations: 0.7188 kB / 0.6513 GB, free: 12.43 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.00135/0.7426, allocations: 344.7 kB / 0.6516 GB, free: 12.36 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.001012/0.7436, allocations: 307.7 kB / 0.6519 GB, free: 12.2 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.0009652/0.7446, allocations: 398.2 kB / 0.6523 GB, free: 12.08 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 5.871e-06/0.7446, allocations: 1.312 kB / 0.6523 GB, free: 12.08 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.0001874/0.7448, allocations: 115.9 kB / 0.6524 GB, free: 12.04 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002221/0.747, allocations: 380.5 kB / 0.6527 GB, free: 11.96 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.001923/0.749, allocations: 1.355 MB / 0.6541 GB, free: 11.04 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0004453/0.7494, allocations: 0.8966 MB / 0.6549 GB, free: 10.33 MB / 0.4981 GB
Notification: Performance of simCode: alias equations: time 0.000862/0.7503, allocations: 156.1 kB / 0.6551 GB, free: 10.3 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.000155/0.7505, allocations: 59.69 kB / 0.6551 GB, free: 10.29 MB / 0.4981 GB
Notification: Performance of SimCode: time 1.182e-06/0.7505, allocations: 0 / 0.6551 GB, free: 10.29 MB / 0.4981 GB
Notification: Performance of Templates: time 0.03003/0.7805, allocations: 12.36 MB / 0.6672 GB, free: 9.215 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)