Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.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 3.2.3+maint.om/package.mo", uses=false)
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo", uses=false)
Using package Annex60 with version 1.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo)
Using package Modelica with version 3.2.3 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+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(Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization")
translateModel(Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001084/0.001084, allocations: 99.47 kB / 15.05 MB, free: 1.105 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.001038/0.001038, allocations: 177.3 kB / 15.94 MB, free: 228 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 3.2.3+maint.om/package.mo): time 1.419/1.419, allocations: 205.4 MB / 222.1 MB, free: 5.527 MB / 174.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Annex60 1.0.0/package.mo): time 0.4256/0.4256, allocations: 54.98 MB / 325.3 MB, free: 0.7461 MB / 254.1 MB
Notification: Modelica requested package Complex of version 3.2.3. Complex 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Modelica requested package ModelicaServices of version 3.2.3. ModelicaServices 4.0.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Annex60 requested package Modelica of version 3.2.2. Modelica 3.2.3 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.0006029/0.000603, allocations: 16 kB / 396.6 MB, free: 11.72 MB / 334.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3325/0.3331, allocations: 60.71 MB / 457.3 MB, free: 38.49 MB / 382.1 MB
Notification: Performance of NFInst.instantiate(Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization): time 0.1821/0.5152, allocations: 156.6 MB / 0.5995 GB, free: 10.66 MB / 478.1 MB
Notification: Performance of NFInst.instExpressions: time 0.01467/0.53, allocations: 9.894 MB / 0.6092 GB, free: 0.7578 MB / 478.1 MB
Notification: Performance of NFInst.updateImplicitVariability: time 0.002012/0.532, allocations: 39.69 kB / 0.6092 GB, free: 0.7188 MB / 478.1 MB
Notification: Performance of NFTyping.typeComponents: time 0.001675/0.5337, allocations: 0.4937 MB / 0.6097 GB, free: 228 kB / 478.1 MB
Notification: Performance of NFTyping.typeBindings: time 0.006482/0.5402, allocations: 1.858 MB / 0.6115 GB, free: 14.36 MB / 494.1 MB
Notification: Performance of NFTyping.typeClassSections: time 0.006826/0.5471, allocations: 2.056 MB / 0.6135 GB, free: 12.32 MB / 494.1 MB
Notification: Performance of NFFlatten.flatten: time 0.004001/0.5511, allocations: 1.732 MB / 0.6152 GB, free: 10.59 MB / 494.1 MB
Notification: Performance of NFFlatten.resolveConnections: time 0.001658/0.5527, allocations: 0.66 MB / 0.6159 GB, free: 9.914 MB / 494.1 MB
Notification: Performance of NFEvalConstants.evaluate: time 0.002618/0.5554, allocations: 0.9229 MB / 0.6168 GB, free: 8.988 MB / 494.1 MB
Notification: Performance of NFSimplifyModel.simplify: time 0.00194/0.5573, allocations: 0.7476 MB / 0.6175 GB, free: 8.238 MB / 494.1 MB
Notification: Performance of NFPackage.collectConstants: time 0.0005195/0.5579, allocations: 52 kB / 0.6176 GB, free: 8.188 MB / 494.1 MB
Notification: Performance of NFFlatten.collectFunctions: time 0.004931/0.5628, allocations: 1.771 MB / 0.6193 GB, free: 6.414 MB / 494.1 MB
Notification: Performance of NFScalarize.scalarize: time 0.000801/0.5636, allocations: 403 kB / 0.6197 GB, free: 6.02 MB / 494.1 MB
Notification: Performance of NFVerifyModel.verify: time 0.0006892/0.5643, allocations: 261.1 kB / 0.6199 GB, free: 5.758 MB / 494.1 MB
Notification: Performance of NFConvertDAE.convert: time 0.004402/0.5688, allocations: 2.03 MB / 0.6219 GB, free: 3.73 MB / 494.1 MB
Notification: Performance of FrontEnd - DAE generated: time 2.835e-06/0.5688, allocations: 0 / 0.6219 GB, free: 3.73 MB / 494.1 MB
Notification: Performance of FrontEnd: time 2.735e-06/0.5688, allocations: 0 / 0.6219 GB, free: 3.73 MB / 494.1 MB
Notification: Performance of Transformations before backend: time 6.586e-05/0.5689, allocations: 0 / 0.6219 GB, free: 3.73 MB / 494.1 MB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 205
 * Number of variables: 205
Notification: Performance of Generate backend data structure: time 0.004713/0.5736, allocations: 1.519 MB / 0.6234 GB, free: 2.16 MB / 494.1 MB
Notification: Performance of prepare preOptimizeDAE: time 5.065e-05/0.5736, allocations: 8.031 kB / 0.6234 GB, free: 2.152 MB / 494.1 MB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001953/0.5756, allocations: 282.3 kB / 0.6237 GB, free: 1.875 MB / 494.1 MB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.001685/0.5773, allocations: 0.7701 MB / 0.6244 GB, free: 1.062 MB / 494.1 MB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 6.815e-05/0.5774, allocations: 61.98 kB / 0.6245 GB, free: 1 MB / 494.1 MB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001709/0.5776, allocations: 87.84 kB / 0.6246 GB, free: 0.9141 MB / 494.1 MB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.001937/0.5795, allocations: 1.122 MB / 0.6256 GB, free: 15.78 MB / 0.4981 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.452e-05/0.5796, allocations: 4 kB / 0.6257 GB, free: 15.77 MB / 0.4981 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001022/0.5797, allocations: 35.98 kB / 0.6257 GB, free: 15.74 MB / 0.4981 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001039/0.5798, allocations: 77.94 kB / 0.6258 GB, free: 15.66 MB / 0.4981 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001915/0.5817, allocations: 1.125 MB / 0.6269 GB, free: 14.54 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.007295/0.589, allocations: 3.981 MB / 0.6307 GB, free: 10.3 MB / 0.4981 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0009005/0.5899, allocations: 0.5914 MB / 0.6313 GB, free: 9.695 MB / 0.4981 GB
Notification: Performance of preOpt evalFunc (simulation): time 4.487e-05/0.59, allocations: 16.55 kB / 0.6313 GB, free: 9.68 MB / 0.4981 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.689e-05/0.59, allocations: 51.83 kB / 0.6314 GB, free: 9.621 MB / 0.4981 GB
Notification: Performance of pre-optimization done (n=56): time 2.214e-06/0.59, allocations: 0 / 0.6314 GB, free: 9.621 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=56): time 0.001732/0.5918, allocations: 0.9429 MB / 0.6323 GB, free: 8.672 MB / 0.4981 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.396e-05/0.5919, allocations: 111.4 kB / 0.6324 GB, free: 8.539 MB / 0.4981 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.001242/0.5931, allocations: 0.937 MB / 0.6333 GB, free: 7.578 MB / 0.4981 GB
Notification: Performance of collectPreVariables (initialization): time 8.324e-05/0.5932, allocations: 41.7 kB / 0.6334 GB, free: 7.531 MB / 0.4981 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0003844/0.5936, allocations: 487.3 kB / 0.6338 GB, free: 7.051 MB / 0.4981 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001956/0.5938, allocations: 205 kB / 0.634 GB, free: 6.848 MB / 0.4981 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 4.464e-05/0.5938, allocations: 12 kB / 0.634 GB, free: 6.836 MB / 0.4981 GB
Notification: Performance of setup shared object (initialization): time 0.0001377/0.594, allocations: 341.9 kB / 0.6344 GB, free: 6.496 MB / 0.4981 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0005845/0.5946, allocations: 333.8 kB / 0.6347 GB, free: 6.172 MB / 0.4981 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0006526/0.5952, allocations: 484.4 kB / 0.6351 GB, free: 5.648 MB / 0.4981 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0006715/0.5959, allocations: 422.8 kB / 0.6356 GB, free: 5.238 MB / 0.4981 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.11e-06/0.5959, allocations: 0 / 0.6356 GB, free: 5.238 MB / 0.4981 GB
Notification: Performance of matching and sorting (n=95) (initialization): time 0.0016/0.5975, allocations: 0.969 MB / 0.6365 GB, free: 4.262 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0004665/0.598, allocations: 1.142 MB / 0.6376 GB, free: 3.031 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.122e-05/0.598, allocations: 4 kB / 0.6376 GB, free: 3.027 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.991e-05/0.5981, allocations: 4 kB / 0.6376 GB, free: 3.023 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007737/0.5988, allocations: 163.8 kB / 0.6378 GB, free: 2.863 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.979e-05/0.5989, allocations: 11.98 kB / 0.6378 GB, free: 2.852 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0009398/0.5998, allocations: 117.4 kB / 0.6379 GB, free: 2.738 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.346e-05/0.5999, allocations: 31.98 kB / 0.6379 GB, free: 2.707 MB / 0.4981 GB
Notification: Performance of prepare postOptimizeDAE: time 1.165e-05/0.5999, allocations: 4 kB / 0.6379 GB, free: 2.703 MB / 0.4981 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.00135/0.6013, allocations: 0.8197 MB / 0.6387 GB, free: 1.887 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 7.153e-06/0.6013, allocations: 4.375 kB / 0.6387 GB, free: 1.883 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (initialization): time 9.979e-06/0.6013, allocations: 12 kB / 0.6388 GB, free: 1.871 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007331/0.6021, allocations: 163.7 kB / 0.6389 GB, free: 1.711 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.962e-05/0.6021, allocations: 12.73 kB / 0.6389 GB, free: 1.699 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0008668/0.603, allocations: 107 kB / 0.639 GB, free: 1.594 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 6.089e-05/0.603, allocations: 31.92 kB / 0.6391 GB, free: 1.562 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 13
 * 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 (95):
 * Single equations (assignments): 95
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of prepare postOptimizeDAE: time 0.0006097/0.6036, allocations: 384.8 kB / 0.6394 GB, free: 1.176 MB / 0.4981 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0002108/0.6039, allocations: 102.8 kB / 0.6395 GB, free: 1.074 MB / 0.4981 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001351/0.6052, allocations: 0.7635 MB / 0.6403 GB, free: 312 kB / 0.4981 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.536e-06/0.6052, allocations: 8 kB / 0.6403 GB, free: 304 kB / 0.4981 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.015e-06/0.6053, allocations: 7.953 kB / 0.6403 GB, free: 296 kB / 0.4981 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.2905/0.8958, allocations: 0.6956 MB / 0.641 GB, free: 25.32 MB / 0.4981 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.00286/0.8987, allocations: 1.817 MB / 0.6427 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.502e-06/0.8987, allocations: 3.562 kB / 0.6427 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006033/0.8993, allocations: 119.9 kB / 0.6428 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt tearingSystem (simulation): time 9.638e-06/0.8993, allocations: 4.656 kB / 0.6429 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 4.223e-05/0.8994, allocations: 18.38 kB / 0.6429 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 9.408e-06/0.8994, allocations: 9.547 kB / 0.6429 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.196e-06/0.8994, allocations: 2.719 kB / 0.6429 GB, free: 24.96 MB / 0.4981 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001559/0.901, allocations: 1.386 MB / 0.6442 GB, free: 24.62 MB / 0.4981 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0005117/0.9015, allocations: 281 kB / 0.6445 GB, free: 24.5 MB / 0.4981 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001388/0.9016, allocations: 19.52 kB / 0.6445 GB, free: 24.5 MB / 0.4981 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0007311/0.9024, allocations: 51.3 kB / 0.6446 GB, free: 24.5 MB / 0.4981 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0001041/0.9025, allocations: 46.17 kB / 0.6446 GB, free: 24.5 MB / 0.4981 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.983e-05/0.9026, allocations: 28.62 kB / 0.6446 GB, free: 24.5 MB / 0.4981 GB
Notification: Performance of sorting global known variables: time 0.0008118/0.9034, allocations: 0.6483 MB / 0.6453 GB, free: 24.39 MB / 0.4981 GB
Notification: Performance of sort global known variables: time 1.7e-07/0.9034, allocations: 0 / 0.6453 GB, free: 24.39 MB / 0.4981 GB
Notification: Performance of remove unused functions: time 0.001842/0.9052, allocations: 0.8109 MB / 0.6461 GB, free: 24.36 MB / 0.4981 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 9
 * Number of states: 3 (vol1.dynBal.U,vol1.dynBal.m,vol1.dynBal.mXi[1])
 * 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 (58):
 * Single equations (assignments): 58
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.001103/0.9063, allocations: 0.524 MB / 0.6466 GB, free: 24.25 MB / 0.4981 GB
Notification: Performance of simCode: created initialization part: time 0.001811/0.9082, allocations: 1.187 MB / 0.6477 GB, free: 24.25 MB / 0.4981 GB
Notification: Performance of simCode: created event and clocks part: time 5.1e-06/0.9082, allocations: 2.125 kB / 0.6477 GB, free: 24.25 MB / 0.4981 GB
Notification: Performance of simCode: created simulation system equations: time 0.0004305/0.9086, allocations: 344.2 kB / 0.6481 GB, free: 24.25 MB / 0.4981 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.002414/0.911, allocations: 0.5512 MB / 0.6486 GB, free: 24.24 MB / 0.4981 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.00289/0.9139, allocations: 2.311 MB / 0.6509 GB, free: 23.17 MB / 0.4981 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0005648/0.9145, allocations: 1.125 MB / 0.652 GB, free: 22.45 MB / 0.4981 GB
Notification: Performance of simCode: alias equations: time 0.001499/0.916, allocations: 427.1 kB / 0.6524 GB, free: 22.38 MB / 0.4981 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002386/0.9163, allocations: 82.38 kB / 0.6524 GB, free: 22.38 MB / 0.4981 GB
Notification: Performance of SimCode: time 7.32e-07/0.9163, allocations: 3.812 kB / 0.6525 GB, free: 22.38 MB / 0.4981 GB
Notification: Performance of Templates: time 0.04354/0.9598, allocations: 23.47 MB / 0.6754 GB, free: 16.97 MB / 0.4981 GB
make -j1 -f Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.makefile
Environment - environmentSimulation:
(rm -f Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe ; mkfifo Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe ; head -c 1048576 < Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe >> ../files/Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.sim & ./Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization  -abortSlowSimulation -alarm=480 -lv LOG_STATS > Annex60_1.0.0_Annex60.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe 2>&1)
Environment - environmentSimulation: