Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001234/0.001234, allocations: 103.6 kB / 18.05 MB, free: 4.289 MB / 14.72 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001195/0.001195, allocations: 193.8 kB / 21.17 MB, free: 1.18 MB / 14.72 MB
[Timeout remaining time 90]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.362/1.362, allocations: 225.6 MB / 249.7 MB, free: 3.242 MB / 190.1 MB
[Timeout remaining time 88]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo", uses=false) [Timeout 90]
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.8288/0.8288, allocations: 137.4 MB / 442.2 MB, free: 13.98 MB / 350.1 MB
[Timeout remaining time 89]
Using package IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.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(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization")
translateModel(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization,tolerance=1e-06,outputFormat="empty",numberOfIntervals=500,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization") [Timeout 660]
Notification: Performance of FrontEnd - loaded program: time 0.001865/0.001865, allocations: 67.89 kB / 0.6134 GB, free: 1.543 MB / 0.4981 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3349/0.3368, allocations: 85.53 MB / 0.6969 GB, free: 14.74 MB / 0.5137 GB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization): time 0.5507/0.8875, allocations: 188.5 MB / 0.881 GB, free: 1.793 MB / 0.67 GB
Notification: Performance of NFInst.instExpressions: time 0.01275/0.9002, allocations: 10.79 MB / 0.8915 GB, free: 1.68 MB / 0.67 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.001079/0.9013, allocations: 40.69 kB / 0.8915 GB, free: 1.68 MB / 0.67 GB
Notification: Performance of NFTyping.typeComponents: time 0.001216/0.9025, allocations: 0.5139 MB / 0.892 GB, free: 1.672 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.005537/0.9081, allocations: 2.653 MB / 0.8946 GB, free: 1.562 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.005767/0.9138, allocations: 2.479 MB / 0.8971 GB, free: 1.488 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.002819/0.9167, allocations: 2.784 MB / 0.8998 GB, free: 1.484 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.0009981/0.9177, allocations: 0.6714 MB / 0.9004 GB, free: 1.438 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.001709/0.9194, allocations: 1.034 MB / 0.9014 GB, free: 1.434 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.002106/0.9215, allocations: 1.147 MB / 0.9026 GB, free: 1.43 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0002332/0.9217, allocations: 97.81 kB / 0.9027 GB, free: 1.43 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.004734/0.9264, allocations: 3.118 MB / 0.9057 GB, free: 1.41 MB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.0004227/0.9269, allocations: 447.1 kB / 0.9061 GB, free: 1.406 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.0006499/0.9275, allocations: 0.5874 MB / 0.9067 GB, free: 1.406 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.003744/0.9313, allocations: 2.971 MB / 0.9096 GB, free: 1.184 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 3.396e-06/0.9313, allocations: 4.688 kB / 0.9096 GB, free: 1.18 MB / 0.67 GB
Notification: Performance of FrontEnd: time 1.442e-06/0.9313, allocations: 3.219 kB / 0.9096 GB, free: 1.18 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 1.302e-05/0.9313, allocations: 0.7812 kB / 0.9096 GB, free: 1.18 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: 173
 * Number of variables: 173
Notification: Performance of Generate backend data structure: time 0.002982/0.9343, allocations: 1.727 MB / 0.9113 GB, free: 16.23 MB / 0.6856 GB
Notification: Performance of prepare preOptimizeDAE: time 3.736e-05/0.9343, allocations: 12.45 kB / 0.9113 GB, free: 16.23 MB / 0.6856 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.001689/0.936, allocations: 290.2 kB / 0.9116 GB, free: 16.18 MB / 0.6856 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.00111/0.9371, allocations: 0.8437 MB / 0.9124 GB, free: 15.84 MB / 0.6856 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 3.51e-05/0.9371, allocations: 54.38 kB / 0.9125 GB, free: 15.84 MB / 0.6856 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0001136/0.9372, allocations: 71.38 kB / 0.9125 GB, free: 15.84 MB / 0.6856 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.00128/0.9385, allocations: 0.9893 MB / 0.9135 GB, free: 15.75 MB / 0.6856 GB
Notification: Performance of preOpt findStateOrder (simulation): time 2.091e-05/0.9385, allocations: 4.906 kB / 0.9135 GB, free: 15.75 MB / 0.6856 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 7.233e-05/0.9386, allocations: 29.8 kB / 0.9135 GB, free: 15.75 MB / 0.6856 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 7.493e-05/0.9387, allocations: 75.56 kB / 0.9136 GB, free: 15.75 MB / 0.6856 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.001315/0.94, allocations: 1.01 MB / 0.9146 GB, free: 15.74 MB / 0.6856 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.004834/0.9448, allocations: 3.577 MB / 0.9181 GB, free: 14.41 MB / 0.6856 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.0008251/0.9457, allocations: 0.6208 MB / 0.9187 GB, free: 14.38 MB / 0.6856 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.0004258/0.9461, allocations: 349.3 kB / 0.919 GB, free: 14.38 MB / 0.6856 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002611/0.9464, allocations: 204.6 kB / 0.9192 GB, free: 14.18 MB / 0.6856 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.987e-05/0.9464, allocations: 38.38 kB / 0.9192 GB, free: 14.14 MB / 0.6856 GB
Notification: Performance of pre-optimization done (n=62): time 1.864e-06/0.9464, allocations: 0 / 0.9192 GB, free: 14.14 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=62): time 0.001595/0.948, allocations: 1.033 MB / 0.9203 GB, free: 14.05 MB / 0.6856 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.63e-05/0.948, allocations: 110.2 kB / 0.9204 GB, free: 13.92 MB / 0.6856 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007945/0.9488, allocations: 0.8015 MB / 0.9211 GB, free: 13.79 MB / 0.6856 GB
Notification: Performance of collectPreVariables (initialization): time 6.084e-05/0.9489, allocations: 35.67 kB / 0.9212 GB, free: 13.75 MB / 0.6856 GB
Notification: Performance of collectInitialEqns (initialization): time 0.000288/0.9492, allocations: 435.8 kB / 0.9216 GB, free: 13.57 MB / 0.6856 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0001855/0.9494, allocations: 235.6 kB / 0.9218 GB, free: 13.42 MB / 0.6856 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0002127/0.9496, allocations: 143.3 kB / 0.922 GB, free: 13.4 MB / 0.6856 GB
Notification: Performance of setup shared object (initialization): time 0.0001475/0.9497, allocations: 337 kB / 0.9223 GB, free: 13.1 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.0004579/0.9502, allocations: 335.5 kB / 0.9226 GB, free: 13.09 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.0005943/0.9508, allocations: 468.7 kB / 0.923 GB, free: 12.98 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.0011/0.9519, allocations: 0.7706 MB / 0.9238 GB, free: 12.89 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.846e-06/0.9519, allocations: 0 / 0.9238 GB, free: 12.89 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=88) (initialization): time 0.001255/0.9531, allocations: 0.9408 MB / 0.9247 GB, free: 12.78 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 1.371e-05/0.9532, allocations: 5.5 kB / 0.9247 GB, free: 12.78 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 6.362e-06/0.9532, allocations: 6.812 kB / 0.9247 GB, free: 12.78 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.674e-05/0.9532, allocations: 5.344 kB / 0.9247 GB, free: 12.78 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0006365/0.9538, allocations: 150.6 kB / 0.9249 GB, free: 12.78 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.002e-05/0.9538, allocations: 8.5 kB / 0.9249 GB, free: 12.78 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.000684/0.9545, allocations: 79.58 kB / 0.925 GB, free: 12.74 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.733e-05/0.9546, allocations: 30.56 kB / 0.925 GB, free: 12.74 MB / 0.6856 GB
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.0006723/0.9552, allocations: 0.5367 MB / 0.9255 GB, free: 12.66 MB / 0.6856 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.0005412/0.9558, allocations: 462.7 kB / 0.926 GB, free: 12.52 MB / 0.6856 GB
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.000896/0.9567, allocations: 0.734 MB / 0.9267 GB, free: 12.42 MB / 0.6856 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 5.431e-06/0.9567, allocations: 3.984 kB / 0.9267 GB, free: 12.41 MB / 0.6856 GB
Notification: Performance of matching and sorting (n=88) (initialization_lambda0): time 0.001158/0.9578, allocations: 0.8877 MB / 0.9275 GB, free: 12.32 MB / 0.6856 GB
Notification: Performance of prepare postOptimizeDAE: time 1.007e-05/0.9579, allocations: 10.09 kB / 0.9276 GB, free: 12.32 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 4.238e-06/0.9579, allocations: 6.406 kB / 0.9276 GB, free: 12.31 MB / 0.6856 GB
Notification: Performance of postOpt tearingSystem (initialization): time 5.079e-06/0.9579, allocations: 6.906 kB / 0.9276 GB, free: 12.31 MB / 0.6856 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0005907/0.9585, allocations: 149.2 kB / 0.9277 GB, free: 12.31 MB / 0.6856 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 6.743e-06/0.9585, allocations: 7.875 kB / 0.9277 GB, free: 12.31 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0006597/0.9591, allocations: 64.11 kB / 0.9278 GB, free: 12.29 MB / 0.6856 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 5.012e-05/0.9592, allocations: 30.72 kB / 0.9278 GB, free: 12.29 MB / 0.6856 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 10
 * 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 (88):
 * Single equations (assignments): 88
 * 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.0001984/0.9594, allocations: 220.7 kB / 0.928 GB, free: 12.11 MB / 0.6856 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001432/0.9595, allocations: 102.2 kB / 0.9281 GB, free: 12.05 MB / 0.6856 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.001203/0.9607, allocations: 0.8066 MB / 0.9289 GB, free: 11.99 MB / 0.6856 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.0008784/0.9616, allocations: 0.7415 MB / 0.9296 GB, free: 11.82 MB / 0.6856 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 2.094e-06/0.9616, allocations: 0.9688 kB / 0.9296 GB, free: 11.82 MB / 0.6856 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0003305/0.9619, allocations: 255.5 kB / 0.9299 GB, free: 11.66 MB / 0.6856 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001205/0.962, allocations: 14.12 kB / 0.9299 GB, free: 11.66 MB / 0.6856 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0004974/0.9625, allocations: 20.25 kB / 0.9299 GB, free: 11.66 MB / 0.6856 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 7.473e-05/0.9626, allocations: 35.7 kB / 0.9299 GB, free: 11.66 MB / 0.6856 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.00127/0.9639, allocations: 0.8275 MB / 0.9307 GB, free: 11.54 MB / 0.6856 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.001532/0.9654, allocations: 1.388 MB / 0.9321 GB, free: 11.26 MB / 0.6856 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.0003755/0.9658, allocations: 320.4 kB / 0.9324 GB, free: 11.26 MB / 0.6856 GB
Notification: Performance of sorting global known variables: time 0.0005714/0.9664, allocations: 0.5578 MB / 0.9329 GB, free: 11.16 MB / 0.6856 GB
Notification: Performance of Backend: time 2.31e-07/0.9664, allocations: 0 / 0.9329 GB, free: 11.16 MB / 0.6856 GB
Notification: Performance of simCode: created initialization part: time 0.003388/0.9698, allocations: 1.63 MB / 0.9345 GB, free: 10.9 MB / 0.6856 GB
Notification: Performance of SimCode: time 0.003543/0.9733, allocations: 2.417 MB / 0.9369 GB, free: 9.547 MB / 0.6856 GB
Notification: Performance of Templates: time 0.04488/1.018, allocations: 30 MB / 0.9662 GB, free: 0.7969 MB / 0.7012 GB
[Timeout remaining time 659]
make -j1 -f IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.makefile [Timeout 660]
(rm -f IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.sim & ./IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeInitialization.pipe 2>&1) [Timeout 480]