Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.conf.json 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/IDEAS 3.0.0-master/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/ModelicaServices 4.0.0+maint.om/package.mo", uses=false) Using package ModelicaServices with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/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 IDEAS with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/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) Running command: translateModel(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor") translateModel(IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor") Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001589/0.001589, allocations: 173.2 kB / 7.647 MB, free: 1.422 MB / 5.871 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.8178/0.8178, allocations: 133.6 MB / 141.6 MB, free: 2.867 MB / 106.7 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.476/1.476, allocations: 221.5 MB / 395.5 MB, free: 10.78 MB / 330.7 MB Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.000849/0.0008491, allocations: 101 kB / 478.2 MB, free: 1.324 MB / 346.7 MB Notification: Performance of FrontEnd - loaded program: time 2.219e-05/2.222e-05, allocations: 4 kB / 0.5535 GB, free: 9.062 MB / 442.7 MB Notification: Performance of FrontEnd - Absyn->SCode: time 0.4461/0.4461, allocations: 82.3 MB / 0.6338 GB, free: 40.79 MB / 458.7 MB Notification: Performance of FrontEnd - scodeFlatten: time 5.361/5.807, allocations: 1.885 GB / 2.518 GB, free: 113 MB / 0.6823 GB Notification: Performance of FrontEnd - mkProgramGraph: time 0.0003283/5.807, allocations: 91.27 kB / 2.519 GB, free: 113 MB / 0.6823 GB Notification: Performance of FrontEnd - DAE generated: time 3.486/9.293, allocations: 0.749 GB / 3.268 GB, free: 118.4 MB / 0.6823 GB Notification: Performance of FrontEnd: time 1.252e-06/9.293, allocations: 0 / 3.268 GB, free: 118.4 MB / 0.6823 GB Notification: Performance of Transformations before backend: time 0.0001588/9.293, allocations: 42.47 kB / 3.268 GB, free: 118.4 MB / 0.6823 GB Notification: Model statistics after passing the front-end and creating the data structures used by the back-end: * Number of equations: 272 * Number of variables: 272 Notification: Performance of Generate backend data structure: time 0.004115/9.297, allocations: 1.544 MB / 3.269 GB, free: 117.7 MB / 0.6823 GB Notification: Performance of prepare preOptimizeDAE: time 4.953e-05/9.297, allocations: 10.31 kB / 3.269 GB, free: 117.7 MB / 0.6823 GB Notification: Performance of preOpt normalInlineFunction (simulation): time 0.003761/9.301, allocations: 486.9 kB / 3.27 GB, free: 117.6 MB / 0.6823 GB Notification: Performance of preOpt evaluateParameters (simulation): time 0.002597/9.304, allocations: 1.101 MB / 3.271 GB, free: 117.2 MB / 0.6823 GB Notification: Performance of preOpt simplifyIfEquations (simulation): time 4.641e-05/9.304, allocations: 35.25 kB / 3.271 GB, free: 117.2 MB / 0.6823 GB Notification: Performance of preOpt expandDerOperator (simulation): time 0.0003071/9.304, allocations: 74.47 kB / 3.271 GB, free: 117.2 MB / 0.6823 GB Notification: Performance of preOpt removeEqualFunctionCalls (simulation): time 0.001771/9.306, allocations: 0.9345 MB / 3.272 GB, free: 117.2 MB / 0.6823 GB Notification: Performance of preOpt clockPartitioning (simulation): time 0.001975/9.308, allocations: 1.041 MB / 3.273 GB, free: 117.1 MB / 0.6823 GB Notification: Performance of preOpt findStateOrder (simulation): time 3.326e-05/9.308, allocations: 0 / 3.273 GB, free: 117.1 MB / 0.6823 GB Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001291/9.308, allocations: 6.688 kB / 3.273 GB, free: 117.1 MB / 0.6823 GB Notification: Performance of preOpt inlineArrayEqn (simulation): time 3.788e-05/9.308, allocations: 35.33 kB / 3.273 GB, free: 117.1 MB / 0.6823 GB Notification: Performance of preOpt removeSimpleEquations (simulation): time 0.005093/9.313, allocations: 3.944 MB / 3.277 GB, free: 115.6 MB / 0.6823 GB Notification: Performance of preOpt comSubExp (simulation): time 0.0007951/9.314, allocations: 0.713 MB / 3.277 GB, free: 115.6 MB / 0.6823 GB Notification: Performance of preOpt resolveLoops (simulation): time 0.0004526/9.314, allocations: 433.1 kB / 3.278 GB, free: 115.6 MB / 0.6823 GB Notification: Performance of preOpt evalFunc (simulation): time 0.0003522/9.315, allocations: 290.2 kB / 3.278 GB, free: 115.3 MB / 0.6823 GB Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 2.387e-05/9.315, allocations: 47.62 kB / 3.278 GB, free: 115.2 MB / 0.6823 GB Notification: Performance of pre-optimization done (n=83): time 2.585e-06/9.315, allocations: 0 / 3.278 GB, free: 115.2 MB / 0.6823 GB Notification: Performance of matching and sorting (n=89): time 0.00401/9.319, allocations: 2.086 MB / 3.28 GB, free: 114.7 MB / 0.6823 GB Notification: Performance of inlineWhenForInitialization (initialization): time 4.109e-05/9.319, allocations: 62.72 kB / 3.28 GB, free: 114.6 MB / 0.6823 GB Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.0007957/9.32, allocations: 0.5925 MB / 3.281 GB, free: 114.5 MB / 0.6823 GB Notification: Performance of collectPreVariables (initialization): time 6.656e-05/9.32, allocations: 29.64 kB / 3.281 GB, free: 114.5 MB / 0.6823 GB Notification: Performance of collectInitialEqns (initialization): time 0.0003132/9.32, allocations: 0.5512 MB / 3.281 GB, free: 114.2 MB / 0.6823 GB Notification: Performance of collectInitialBindings (initialization): time 0.0002179/9.32, allocations: 305 kB / 3.282 GB, free: 114 MB / 0.6823 GB Notification: Performance of simplifyInitialFunctions (initialization): time 3.492e-05/9.32, allocations: 0 / 3.282 GB, free: 114 MB / 0.6823 GB Notification: Performance of setup shared object (initialization): time 8.268e-05/9.32, allocations: 340.4 kB / 3.282 GB, free: 113.7 MB / 0.6823 GB Notification: Performance of preBalanceInitialSystem (initialization): time 0.0003981/9.321, allocations: 311 kB / 3.282 GB, free: 113.7 MB / 0.6823 GB Notification: Performance of partitionIndependentBlocks (initialization): time 0.0004948/9.321, allocations: 475.3 kB / 3.283 GB, free: 113.5 MB / 0.6823 GB Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: vol1.dynBal.medium.p = vol1.dynBal.p_start (bou.p = vol1.dynBal.p_start) Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: volMFactor.dynBal.medium.p = volMFactor.dynBal.p_start (bou.p = volMFactor.dynBal.p_start) Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: vol.dynBal.medium.p = vol.dynBal.p_start (bou.p = vol.dynBal.p_start) Notification: Performance of analyzeInitialSystem (initialization): time 0.00425/9.326, allocations: 2.151 MB / 3.285 GB, free: 113.2 MB / 0.6823 GB Notification: Performance of solveInitialSystemEqSystem (initialization): time 5.911e-06/9.326, allocations: 0 / 3.285 GB, free: 113.2 MB / 0.6823 GB Notification: Performance of matching and sorting (n=108) (initialization): time 0.001364/9.327, allocations: 0.8861 MB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of prepare postOptimizeDAE: time 2.114e-05/9.327, allocations: 6.875 kB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.001e-05/9.327, allocations: 6.969 kB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of postOpt tearingSystem (initialization): time 2.954e-05/9.327, allocations: 7.594 kB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0007037/9.328, allocations: 181.4 kB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 1.226e-05/9.328, allocations: 10.16 kB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.001166/9.329, allocations: 43.09 kB / 3.286 GB, free: 113 MB / 0.6823 GB Notification: Performance of postOpt collapseArrayExpressions (initialization): time 7.36e-05/9.329, allocations: 39.05 kB / 3.286 GB, free: 113 MB / 0.6823 GB Warning: The initial conditions are over specified. The following 3 initial equations are redundant, so they are removed from the initialization sytem: vol1.dynBal.medium.p = vol1.dynBal.p_start volMFactor.dynBal.medium.p = volMFactor.dynBal.p_start vol.dynBal.medium.p = vol.dynBal.p_start. 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 () * Top-level inputs: 0 Notification: Strong component statistics for initialization (108): * Single equations (assignments): 108 * 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.0001927/9.329, allocations: 135.9 kB / 3.286 GB, free: 112.9 MB / 0.6823 GB Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0001514/9.329, allocations: 108.6 kB / 3.286 GB, free: 112.8 MB / 0.6823 GB Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.0003311/9.33, allocations: 119.8 kB / 3.286 GB, free: 112.8 MB / 0.6823 GB Notification: Performance of postOpt inlineArrayEqn (simulation): time 7.464e-06/9.33, allocations: 6.875 kB / 3.286 GB, free: 112.8 MB / 0.6823 GB Notification: Performance of postOpt constantLinearSystem (simulation): time 2.08e-05/9.33, allocations: 6.859 kB / 3.286 GB, free: 112.8 MB / 0.6823 GB Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001166/9.331, allocations: 1.013 MB / 3.287 GB, free: 112.2 MB / 0.6823 GB Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.0038/9.335, allocations: 2.793 MB / 3.29 GB, free: 110.8 MB / 0.6823 GB Notification: Performance of postOpt simplifyComplexFunction (simulation): time 9.067e-06/9.335, allocations: 8.5 kB / 3.29 GB, free: 110.8 MB / 0.6823 GB Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.0006762/9.335, allocations: 174 kB / 3.29 GB, free: 110.7 MB / 0.6823 GB Notification: Performance of postOpt tearingSystem (simulation): time 0.0002951/9.336, allocations: 102.2 kB / 3.29 GB, free: 110.7 MB / 0.6823 GB Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.788e-05/9.336, allocations: 21.92 kB / 3.29 GB, free: 110.7 MB / 0.6823 GB Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.001714/9.338, allocations: 2.807 MB / 3.293 GB, free: 108.1 MB / 0.6823 GB Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.897e-06/9.338, allocations: 11.12 kB / 3.293 GB, free: 108.1 MB / 0.6823 GB Notification: Performance of postOpt symbolicJacobian (simulation): time 0.001984/9.34, allocations: 2.099 MB / 3.295 GB, free: 106.4 MB / 0.6823 GB Notification: Performance of postOpt removeConstants (simulation): time 0.0004896/9.34, allocations: 282.2 kB / 3.295 GB, free: 106.2 MB / 0.6823 GB Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0001231/9.34, allocations: 23.11 kB / 3.295 GB, free: 106.2 MB / 0.6823 GB Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.0008977/9.341, allocations: 60.48 kB / 3.295 GB, free: 106.2 MB / 0.6823 GB Notification: Performance of postOpt findZeroCrossings (simulation): time 9.835e-05/9.341, allocations: 85.2 kB / 3.295 GB, free: 106.2 MB / 0.6823 GB Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.472e-05/9.341, allocations: 48.41 kB / 3.296 GB, free: 106.2 MB / 0.6823 GB Notification: Performance of sorting global known variables: time 0.0005104/9.342, allocations: 465.3 kB / 3.296 GB, free: 106 MB / 0.6823 GB Notification: Performance of sort global known variables: time 1.5e-07/9.342, allocations: 2.656 kB / 3.296 GB, free: 106 MB / 0.6823 GB Notification: Performance of remove unused functions: time 0.001635/9.343, allocations: 470.4 kB / 3.296 GB, free: 105.9 MB / 0.6823 GB Notification: Model statistics after passing the back-end for simulation: * Number of independent subsystems: 39 * Number of states: 6 (vol.dynBal.U,vol.dynBal.mXi[1],volMFactor.dynBal.U,volMFactor.dynBal.mXi[1],vol1.dynBal.U,vol1.dynBal.mXi[1]) * Number of discrete variables: 0 () * Number of discrete states: 0 () * Top-level inputs: 0 Notification: Strong component statistics for simulation (85): * Single equations (assignments): 83 * 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: 2 * Mixed (continuous/discrete) equation systems: 0 Notification: Torn system details for strict tearing set: * Linear torn systems: 2 {(1,100.0%) 2,(1,100.0%) 2} * Non-linear torn systems: 0 Notification: Performance of Backend phase and start with SimCode phase: time 0.002277/9.346, allocations: 1.856 MB / 3.298 GB, free: 105.3 MB / 0.6823 GB Notification: Performance of simCode: created initialization part: time 0.001124/9.347, allocations: 0.7461 MB / 3.299 GB, free: 105.1 MB / 0.6823 GB Notification: Performance of simCode: created event and clocks part: time 8.376e-06/9.347, allocations: 1.781 kB / 3.299 GB, free: 105.1 MB / 0.6823 GB Notification: Performance of simCode: created simulation system equations: time 0.0007118/9.348, allocations: 0.6185 MB / 3.3 GB, free: 104.7 MB / 0.6823 GB Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003213/9.351, allocations: 0.6687 MB / 3.3 GB, free: 104.6 MB / 0.6823 GB Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.003164/9.354, allocations: 2.19 MB / 3.302 GB, free: 103.6 MB / 0.6823 GB Notification: Performance of simCode: some other stuff during SimCode phase: time 0.0006207/9.355, allocations: 1.28 MB / 3.304 GB, free: 102.8 MB / 0.6823 GB Notification: Performance of simCode: alias equations: time 0.00121/9.356, allocations: 300.9 kB / 3.304 GB, free: 102.8 MB / 0.6823 GB Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0002268/9.356, allocations: 85.56 kB / 3.304 GB, free: 102.8 MB / 0.6823 GB Notification: Performance of SimCode: time 7.41e-07/9.356, allocations: 0 / 3.304 GB, free: 102.8 MB / 0.6823 GB Notification: Performance of Templates: time 0.04048/9.397, allocations: 29.95 MB / 3.333 GB, free: 75.89 MB / 0.6823 GB make -j1 -f IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.makefile (rm -f IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.sim & ./IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.MixingVolumes.Validation.MixingVolumeMFactor.pipe 2>&1)