Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.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/IDEAS 3.0.0-master/package.mo", uses=false)
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.Movers.Validation.FlowControlled_m_flow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow")
translateModel(IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001043/0.001043, allocations: 86.25 kB / 15.06 MB, free: 1.09 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.001341/0.001341, allocations: 168.7 kB / 15.95 MB, free: 204 kB / 14.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.426/1.426, allocations: 223.3 MB / 240 MB, free: 5.414 MB / 190.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 1.002/1.002, allocations: 135.3 MB / 426.4 MB, free: 14.14 MB / 350.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.0003982/0.0003985, allocations: 19.98 kB / 0.507 GB, free: 5.121 MB / 414.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.2142/0.2147, allocations: 82.29 MB / 0.5874 GB, free: 3.004 MB / 494.1 MB
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow): time 1.092/1.307, allocations: 295.7 MB / 0.8761 GB, free: 35.25 MB / 0.67 GB
Notification: Performance of NFInst.instExpressions: time 0.02631/1.333, allocations: 18.87 MB / 0.8946 GB, free: 35.07 MB / 0.67 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.004879/1.338, allocations: 73.38 kB / 0.8946 GB, free: 35.07 MB / 0.67 GB
Notification: Performance of NFTyping.typeComponents: time 0.00439/1.342, allocations: 0.9673 MB / 0.8956 GB, free: 35.05 MB / 0.67 GB
Notification: Performance of NFTyping.typeBindings: time 0.0105/1.353, allocations: 3.11 MB / 0.8986 GB, free: 34.91 MB / 0.67 GB
Notification: Performance of NFTyping.typeClassSections: time 0.006355/1.359, allocations: 1.784 MB / 0.9004 GB, free: 34.87 MB / 0.67 GB
Notification: Performance of NFFlatten.flatten: time 0.0121/1.371, allocations: 6.779 MB / 0.907 GB, free: 34.85 MB / 0.67 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00407/1.376, allocations: 1.581 MB / 0.9085 GB, free: 34.67 MB / 0.67 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.007858/1.383, allocations: 2.564 MB / 0.911 GB, free: 34.64 MB / 0.67 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.004473/1.388, allocations: 1.698 MB / 0.9127 GB, free: 34.63 MB / 0.67 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008614/1.389, allocations: 92.97 kB / 0.9128 GB, free: 34.63 MB / 0.67 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.008168/1.397, allocations: 2.202 MB / 0.9149 GB, free: 34.59 MB / 0.67 GB
Notification: Performance of NFScalarize.scalarize: time 0.002129/1.399, allocations: 0.7436 MB / 0.9157 GB, free: 34.59 MB / 0.67 GB
Notification: Performance of NFVerifyModel.verify: time 0.001951/1.401, allocations: 0.5099 MB / 0.9162 GB, free: 34.56 MB / 0.67 GB
Notification: Performance of NFConvertDAE.convert: time 0.007782/1.409, allocations: 3.341 MB / 0.9194 GB, free: 34.3 MB / 0.67 GB
Notification: Performance of FrontEnd - DAE generated: time 6.072e-06/1.409, allocations: 4 kB / 0.9194 GB, free: 34.29 MB / 0.67 GB
Notification: Performance of FrontEnd: time 3.827e-06/1.409, allocations: 1 kB / 0.9194 GB, free: 34.29 MB / 0.67 GB
Notification: Performance of Transformations before backend: time 0.0001129/1.409, allocations: 0 / 0.9194 GB, free: 34.29 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: 341
 * Number of variables: 341
Notification: Performance of Generate backend data structure: time 0.006941/1.416, allocations: 2.577 MB / 0.9219 GB, free: 32.92 MB / 0.67 GB
Notification: Performance of prepare preOptimizeDAE: time 4.67e-05/1.416, allocations: 9 kB / 0.9219 GB, free: 32.92 MB / 0.67 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.006021/1.422, allocations: 0.8788 MB / 0.9228 GB, free: 32.75 MB / 0.67 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.004559/1.427, allocations: 1.747 MB / 0.9245 GB, free: 32.29 MB / 0.67 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0004087/1.427, allocations: 183.5 kB / 0.9247 GB, free: 32.28 MB / 0.67 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004273/1.428, allocations: 150.8 kB / 0.9248 GB, free: 32.28 MB / 0.67 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.003697/1.431, allocations: 1.711 MB / 0.9265 GB, free: 32.09 MB / 0.67 GB
Notification: Performance of preOpt findStateOrder (simulation): time 3.913e-05/1.431, allocations: 9.219 kB / 0.9265 GB, free: 32.09 MB / 0.67 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0001354/1.432, allocations: 56.44 kB / 0.9266 GB, free: 32.09 MB / 0.67 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 5.983e-05/1.432, allocations: 46.33 kB / 0.9266 GB, free: 32.09 MB / 0.67 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.003525/1.435, allocations: 1.501 MB / 0.9281 GB, free: 32.05 MB / 0.67 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.01329/1.448, allocations: 7.85 MB / 0.9357 GB, free: 28.15 MB / 0.67 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.002201/1.451, allocations: 1.151 MB / 0.9369 GB, free: 27.92 MB / 0.67 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001179/1.452, allocations: 0.5517 MB / 0.9374 GB, free: 27.9 MB / 0.67 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0002408/1.452, allocations: 115 kB / 0.9375 GB, free: 27.8 MB / 0.67 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 3.971e-05/1.452, allocations: 48.39 kB / 0.9376 GB, free: 27.76 MB / 0.67 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001594/1.454, allocations: 205.3 kB / 0.9378 GB, free: 27.69 MB / 0.67 GB
Notification: Performance of pre-optimization done (n=77): time 3.626e-06/1.454, allocations: 2.812 kB / 0.9378 GB, free: 27.69 MB / 0.67 GB
Notification: Performance of matching and sorting (n=77): time 0.004007/1.458, allocations: 1.54 MB / 0.9393 GB, free: 27.33 MB / 0.67 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.823e-05/1.458, allocations: 62.69 kB / 0.9393 GB, free: 27.27 MB / 0.67 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.002487/1.46, allocations: 1.809 MB / 0.9411 GB, free: 26.75 MB / 0.67 GB
Notification: Performance of collectPreVariables (initialization): time 0.0001578/1.461, allocations: 45.77 kB / 0.9411 GB, free: 26.72 MB / 0.67 GB
Notification: Performance of collectInitialEqns (initialization): time 0.0006502/1.461, allocations: 0.9789 MB / 0.9421 GB, free: 26.32 MB / 0.67 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0002261/1.461, allocations: 273.1 kB / 0.9424 GB, free: 26.12 MB / 0.67 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 5.687e-05/1.462, allocations: 23.22 kB / 0.9424 GB, free: 26.12 MB / 0.67 GB
Notification: Performance of setup shared object (initialization): time 7.881e-05/1.462, allocations: 342.8 kB / 0.9427 GB, free: 25.8 MB / 0.67 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001059/1.463, allocations: 480.6 kB / 0.9432 GB, free: 25.72 MB / 0.67 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00121/1.464, allocations: 0.8937 MB / 0.944 GB, free: 25.12 MB / 0.67 GB
Notification: Performance of analyzeInitialSystem (initialization): time 0.001166/1.465, allocations: 0.5736 MB / 0.9446 GB, free: 25.03 MB / 0.67 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 9.708e-06/1.465, allocations: 5.281 kB / 0.9446 GB, free: 25.03 MB / 0.67 GB
Notification: Performance of matching and sorting (n=127) (initialization): time 0.003075/1.468, allocations: 1.492 MB / 0.9461 GB, free: 24.7 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0001715/1.468, allocations: 380.8 kB / 0.9464 GB, free: 24.25 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.45e-05/1.468, allocations: 11.83 kB / 0.9464 GB, free: 24.25 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 3.066e-05/1.468, allocations: 18 kB / 0.9464 GB, free: 24.25 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001335/1.47, allocations: 308.3 kB / 0.9467 GB, free: 24.19 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.000284/1.47, allocations: 0.6124 MB / 0.9473 GB, free: 23.59 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002181/1.472, allocations: 189.7 kB / 0.9475 GB, free: 23.53 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0001005/1.472, allocations: 52.89 kB / 0.9476 GB, free: 23.52 MB / 0.67 GB
Notification: Performance of prepare postOptimizeDAE: time 8.096e-06/1.472, allocations: 3.312 kB / 0.9476 GB, free: 23.52 MB / 0.67 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.002028/1.474, allocations: 1.176 MB / 0.9487 GB, free: 23.36 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.405e-05/1.474, allocations: 8.656 kB / 0.9487 GB, free: 23.36 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (initialization): time 1.79e-05/1.474, allocations: 15.12 kB / 0.9487 GB, free: 23.36 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.0009392/1.475, allocations: 264.3 kB / 0.949 GB, free: 23.32 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 2.345e-05/1.475, allocations: 25.11 kB / 0.949 GB, free: 23.32 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.002385/1.478, allocations: 157 kB / 0.9492 GB, free: 23.27 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 8.894e-05/1.478, allocations: 49.27 kB / 0.9492 GB, free: 23.27 MB / 0.67 GB
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 43
 * 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 (125):
 * Single equations (assignments): 122
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 1
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 1 {1}
 * Without analytic Jacobian: 0
Notification: Performance of prepare postOptimizeDAE: time 0.001435/1.479, allocations: 0.5899 MB / 0.9498 GB, free: 23.03 MB / 0.67 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0003282/1.48, allocations: 198.2 kB / 0.95 GB, free: 22.92 MB / 0.67 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.002575/1.482, allocations: 1.198 MB / 0.9511 GB, free: 22.63 MB / 0.67 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 8.466e-06/1.482, allocations: 9.891 kB / 0.9512 GB, free: 22.63 MB / 0.67 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.552e-06/1.482, allocations: 0 / 0.9512 GB, free: 22.63 MB / 0.67 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.001951/1.484, allocations: 1.025 MB / 0.9522 GB, free: 22.31 MB / 0.67 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 postOpt removeSimpleEquations (simulation): time 0.005405/1.49, allocations: 3.058 MB / 0.9551 GB, free: 21.13 MB / 0.67 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 6.102e-06/1.49, allocations: 7.062 kB / 0.9552 GB, free: 21.12 MB / 0.67 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001041/1.491, allocations: 230.2 kB / 0.9554 GB, free: 21.08 MB / 0.67 GB
Notification: Performance of postOpt tearingSystem (simulation): time 8.415e-06/1.491, allocations: 5.094 kB / 0.9554 GB, free: 21.08 MB / 0.67 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 5.761e-05/1.491, allocations: 25.08 kB / 0.9554 GB, free: 21.07 MB / 0.67 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.0002018/1.491, allocations: 0.5911 MB / 0.956 GB, free: 20.48 MB / 0.67 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 3.757e-06/1.491, allocations: 9 kB / 0.956 GB, free: 20.48 MB / 0.67 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.003416/1.495, allocations: 1.824 MB / 0.9578 GB, free: 19.94 MB / 0.67 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.0008524/1.495, allocations: 362.8 kB / 0.9581 GB, free: 19.7 MB / 0.67 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0002337/1.496, allocations: 26.53 kB / 0.9581 GB, free: 19.69 MB / 0.67 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.001303/1.497, allocations: 166.7 kB / 0.9583 GB, free: 19.63 MB / 0.67 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 9.725e-05/1.497, allocations: 54.66 kB / 0.9584 GB, free: 19.61 MB / 0.67 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 6.076e-05/1.497, allocations: 34.73 kB / 0.9584 GB, free: 19.59 MB / 0.67 GB
Notification: Performance of sorting global known variables: time 0.001626/1.499, allocations: 1.234 MB / 0.9596 GB, free: 19.25 MB / 0.67 GB
Notification: Performance of sort global known variables: time 3.6e-07/1.499, allocations: 0 / 0.9596 GB, free: 19.25 MB / 0.67 GB
Notification: Performance of remove unused functions: time 0.002672/1.501, allocations: 0.9869 MB / 0.9606 GB, free: 19.11 MB / 0.67 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 6
 * Number of states: 3 (floMacDyn.vol.dynBal.U,floMacDyn.vol.dynBal.m,floMacDyn.vol.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 (79):
 * Single equations (assignments): 78
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 1
 * Torn equation systems: 0
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details:
 * Constant Jacobian: 0
 * Linear Jacobian (size,density): 0
 * Non-linear Jacobian: 1 {1}
 * Without analytic Jacobian: 0
Notification: Performance of Backend phase and start with SimCode phase: time 0.0008006/1.502, allocations: 364.9 kB / 0.9609 GB, free: 18.95 MB / 0.67 GB
Notification: Performance of simCode: created initialization part: time 0.003714/1.506, allocations: 1.745 MB / 0.9626 GB, free: 18.66 MB / 0.67 GB
Notification: Performance of simCode: created event and clocks part: time 4.268e-06/1.506, allocations: 0 / 0.9626 GB, free: 18.66 MB / 0.67 GB
Notification: Performance of simCode: created simulation system equations: time 0.001103/1.507, allocations: 0.522 MB / 0.9631 GB, free: 18.58 MB / 0.67 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.004773/1.512, allocations: 1.037 MB / 0.9641 GB, free: 18.26 MB / 0.67 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.006686/1.519, allocations: 4.284 MB / 0.9683 GB, free: 15.85 MB / 0.67 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001185/1.52, allocations: 1.578 MB / 0.9699 GB, free: 14.8 MB / 0.67 GB
Notification: Performance of simCode: alias equations: time 0.002533/1.522, allocations: 486.1 kB / 0.9703 GB, free: 14.57 MB / 0.67 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.0004169/1.523, allocations: 155.1 kB / 0.9705 GB, free: 14.55 MB / 0.67 GB
Notification: Performance of SimCode: time 1.263e-06/1.523, allocations: 0 / 0.9705 GB, free: 14.55 MB / 0.67 GB
Notification: Performance of Templates: time 0.06171/1.584, allocations: 37.37 MB / 1.007 GB, free: 10.49 MB / 0.7012 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.sim & ./IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_m_flow.pipe 2>&1)