Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.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_dpSystem,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem")
translateModel(IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.001197/0.001197, allocations: 110.7 kB / 16.42 MB, free: 6.516 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.001165/0.001165, allocations: 192.3 kB / 17.35 MB, free: 5.738 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.209/1.209, allocations: 222.9 MB / 241 MB, free: 15.15 MB / 206.1 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IDEAS 3.0.0-master/package.mo): time 0.7239/0.7239, allocations: 135.1 MB / 426.3 MB, free: 4.109 MB / 334.1 MB
Notification: Performance of FrontEnd - loaded program: time 0.001108/0.001108, allocations: 61.72 kB / 0.5863 GB, free: 21.45 MB / 446.1 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.1183/0.1194, allocations: 80.92 MB / 0.6653 GB, free: 5.785 MB / 0.4981 GB
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/Blocks/Math.mo:543:5-544:48:writable] Warning: Parameter floConDp.gaiSpe.k has no value, and is fixed during initialization (fixed=true), using available start value (start=1) as default value.
[/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/Blocks/Math.mo:543:5-544:48:writable] Warning: Parameter floConDpSystem.gaiSpe.k has no value, and is fixed during initialization (fixed=true), using available start value (start=1) as default value.
Notification: Performance of NFInst.instantiate(IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem): time 0.587/0.7064, allocations: 357.7 MB / 1.015 GB, free: 9.086 MB / 0.7794 GB
Notification: Performance of NFInst.instExpressions: time 0.04449/0.7509, allocations: 34.71 MB / 1.049 GB, free: 6.297 MB / 0.8106 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.005505/0.7565, allocations: 182.9 kB / 1.049 GB, free: 6.117 MB / 0.8106 GB
Notification: Performance of NFTyping.typeComponents: time 0.007013/0.7635, allocations: 2.933 MB / 1.052 GB, free: 3.168 MB / 0.8106 GB
Notification: Performance of NFTyping.typeBindings: time 0.4091/1.173, allocations: 8.712 MB / 1.06 GB, free: 3.914 MB / 0.8106 GB
Notification: Performance of NFTyping.typeClassSections: time 0.0109/1.184, allocations: 4.803 MB / 1.065 GB, free: 3.895 MB / 0.8106 GB
Notification: Performance of NFFlatten.flatten: time 0.01626/1.2, allocations: 12.14 MB / 1.077 GB, free: 3.875 MB / 0.8106 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.007471/1.207, allocations: 4.475 MB / 1.081 GB, free: 19.42 MB / 0.8262 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01174/1.219, allocations: 5.958 MB / 1.087 GB, free: 19.41 MB / 0.8262 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.00744/1.227, allocations: 3.867 MB / 1.091 GB, free: 19.33 MB / 0.8262 GB
Notification: Performance of NFPackage.collectConstants: time 0.001906/1.228, allocations: 475.3 kB / 1.091 GB, free: 19.33 MB / 0.8262 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01325/1.242, allocations: 6.726 MB / 1.098 GB, free: 19.26 MB / 0.8262 GB
Notification: Performance of NFScalarize.scalarize: time 0.002957/1.245, allocations: 1.836 MB / 1.099 GB, free: 19.07 MB / 0.8262 GB
Notification: Performance of NFVerifyModel.verify: time 0.00573/1.25, allocations: 2.82 MB / 1.102 GB, free: 18.3 MB / 0.8262 GB
Notification: Performance of NFConvertDAE.convert: time 0.01551/1.266, allocations: 9.181 MB / 1.111 GB, free: 17.16 MB / 0.8262 GB
Notification: Performance of FrontEnd - DAE generated: time 5.631e-06/1.266, allocations: 4.25 kB / 1.111 GB, free: 17.16 MB / 0.8262 GB
Notification: Performance of FrontEnd: time 1.362e-06/1.266, allocations: 3.75 kB / 1.111 GB, free: 17.16 MB / 0.8262 GB
Notification: Performance of Transformations before backend: time 0.0002301/1.266, allocations: 0 / 1.111 GB, free: 17.16 MB / 0.8262 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 711
 * Number of variables: 711
Notification: Performance of Generate backend data structure: time 0.01526/1.282, allocations: 5.993 MB / 1.117 GB, free: 14.61 MB / 0.8262 GB
Notification: Performance of prepare preOptimizeDAE: time 4.029e-05/1.282, allocations: 15.33 kB / 1.117 GB, free: 14.61 MB / 0.8262 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.009369/1.291, allocations: 1.435 MB / 1.118 GB, free: 14.34 MB / 0.8262 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.008509/1.3, allocations: 3.787 MB / 1.122 GB, free: 13.46 MB / 0.8262 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002161/1.3, allocations: 235.8 kB / 1.122 GB, free: 13.45 MB / 0.8262 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0008325/1.301, allocations: 312.9 kB / 1.123 GB, free: 13.45 MB / 0.8262 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007256/1.308, allocations: 3.451 MB / 1.126 GB, free: 13.03 MB / 0.8262 GB
Notification: Performance of preOpt findStateOrder (simulation): time 7.563e-05/1.308, allocations: 10.53 kB / 1.126 GB, free: 13.03 MB / 0.8262 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004189/1.308, allocations: 123.1 kB / 1.126 GB, free: 13.03 MB / 0.8262 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 8.796e-05/1.308, allocations: 100.9 kB / 1.126 GB, free: 13.02 MB / 0.8262 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.005445/1.314, allocations: 2.801 MB / 1.129 GB, free: 12.98 MB / 0.8262 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.0206/1.335, allocations: 15.52 MB / 1.144 GB, free: 5.477 MB / 0.8262 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.003254/1.338, allocations: 1.918 MB / 1.146 GB, free: 5.172 MB / 0.8262 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.001812/1.34, allocations: 1.154 MB / 1.147 GB, free: 4.992 MB / 0.8262 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000317/1.34, allocations: 228.6 kB / 1.147 GB, free: 4.793 MB / 0.8262 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.054e-05/1.34, allocations: 60.08 kB / 1.147 GB, free: 4.746 MB / 0.8262 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.001723/1.342, allocations: 248.4 kB / 1.148 GB, free: 4.633 MB / 0.8262 GB
Notification: Performance of pre-optimization done (n=183): time 8.335e-06/1.342, allocations: 0 / 1.148 GB, free: 4.633 MB / 0.8262 GB
Notification: Performance of matching and sorting (n=191): time 0.01591/1.358, allocations: 6.999 MB / 1.154 GB, free: 2.324 MB / 0.8262 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.528e-05/1.358, allocations: 72.06 kB / 1.155 GB, free: 2.262 MB / 0.8262 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.006498/1.364, allocations: 3.955 MB / 1.158 GB, free: 15.75 MB / 0.8419 GB
Notification: Performance of collectPreVariables (initialization): time 0.0002897/1.365, allocations: 78.5 kB / 1.158 GB, free: 15.7 MB / 0.8419 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001464/1.366, allocations: 1.983 MB / 1.16 GB, free: 14.72 MB / 0.8419 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006118/1.367, allocations: 0.655 MB / 1.161 GB, free: 14.16 MB / 0.8419 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0007767/1.367, allocations: 499.8 kB / 1.161 GB, free: 13.84 MB / 0.8419 GB
Notification: Performance of setup shared object (initialization): time 8.211e-05/1.368, allocations: 311.3 kB / 1.162 GB, free: 13.53 MB / 0.8419 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001934/1.369, allocations: 0.9852 MB / 1.163 GB, free: 12.85 MB / 0.8419 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002591/1.372, allocations: 1.937 MB / 1.165 GB, free: 10.89 MB / 0.8419 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: zone2.dynBal.medium.p = zone2.dynBal.p_start (sin.p = zone2.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: zone1.dynBal.medium.p = zone1.dynBal.p_start (sin.p = zone1.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: zone3.dynBal.medium.p = zone3.dynBal.p_start (sin.p = zone3.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: zone4.dynBal.medium.p = zone4.dynBal.p_start (sin.p = zone4.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization): time 0.03414/1.406, allocations: 10.96 MB / 1.175 GB, free: 2.145 MB / 0.8419 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.3e-05/1.406, allocations: 8 kB / 1.175 GB, free: 2.137 MB / 0.8419 GB
Notification: Performance of matching and sorting (n=284) (initialization): time 0.007565/1.414, allocations: 3.352 MB / 1.179 GB, free: 15.69 MB / 0.8575 GB
Notification: Performance of prepare postOptimizeDAE: time 3.038e-05/1.414, allocations: 12 kB / 1.179 GB, free: 15.68 MB / 0.8575 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.874e-05/1.414, allocations: 16 kB / 1.179 GB, free: 15.66 MB / 0.8575 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001378/1.415, allocations: 310.8 kB / 1.179 GB, free: 15.39 MB / 0.8575 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002095/1.417, allocations: 0.5217 MB / 1.179 GB, free: 14.91 MB / 0.8575 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001228/1.419, allocations: 1.818 MB / 1.181 GB, free: 13.13 MB / 0.8575 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004552/1.423, allocations: 232.3 kB / 1.181 GB, free: 12.95 MB / 0.8575 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002185/1.423, allocations: 116.9 kB / 1.182 GB, free: 12.85 MB / 0.8575 GB
Warning: The initial conditions are over specified. The following 4 initial equations are redundant, so they are removed from the initialization system:
         zone2.dynBal.medium.p = zone2.dynBal.p_start
         zone1.dynBal.medium.p = zone1.dynBal.p_start
         zone3.dynBal.medium.p = zone3.dynBal.p_start
         zone4.dynBal.medium.p = zone4.dynBal.p_start.
Notification: Performance of preBalanceInitialSystem (initialization_lambda0): time 0.00359/1.427, allocations: 1.674 MB / 1.183 GB, free: 11.78 MB / 0.8575 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.002348/1.429, allocations: 1.882 MB / 1.185 GB, free: 9.676 MB / 0.8575 GB
Warning: It was not possible to determine if the initialization problem is consistent, because of not evaluable parameters/start values during compile time: zone2.dynBal.medium.p = zone2.dynBal.p_start (sin.p = zone2.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: zone1.dynBal.medium.p = zone1.dynBal.p_start (sin.p = zone1.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: zone3.dynBal.medium.p = zone3.dynBal.p_start (sin.p = zone3.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: zone4.dynBal.medium.p = zone4.dynBal.p_start (sin.p = zone4.dynBal.p_start)
Notification: Performance of analyzeInitialSystem (initialization_lambda0): time 0.02901/1.458, allocations: 9.892 MB / 1.195 GB, free: 15.55 MB / 0.8731 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 1.712e-05/1.458, allocations: 8 kB / 1.195 GB, free: 15.55 MB / 0.8731 GB
Notification: Performance of matching and sorting (n=284) (initialization_lambda0): time 0.006437/1.465, allocations: 3.135 MB / 1.198 GB, free: 12.51 MB / 0.8731 GB
Notification: Performance of prepare postOptimizeDAE: time 1.957e-05/1.465, allocations: 12 kB / 1.198 GB, free: 12.5 MB / 0.8731 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 1.915e-05/1.465, allocations: 19.94 kB / 1.198 GB, free: 12.48 MB / 0.8731 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0006601/1.466, allocations: 209.8 kB / 1.198 GB, free: 12.27 MB / 0.8731 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001922/1.468, allocations: 0.51 MB / 1.198 GB, free: 11.79 MB / 0.8731 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.004595/1.472, allocations: 5.722 MB / 1.204 GB, free: 5.66 MB / 0.8731 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004568/1.477, allocations: 274.4 kB / 1.204 GB, free: 5.395 MB / 0.8731 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002032/1.477, allocations: 112 kB / 1.204 GB, free: 5.285 MB / 0.8731 GB
Warning: The initial conditions are over specified. The following 4 initial equations are redundant, so they are removed from the initialization_lambda0 system:
         zone2.dynBal.medium.p = zone2.dynBal.p_start
         zone1.dynBal.medium.p = zone1.dynBal.p_start
         zone3.dynBal.medium.p = zone3.dynBal.p_start
         zone4.dynBal.medium.p = zone4.dynBal.p_start.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 80
 * 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 (266):
 * Single equations (assignments): 261
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 3
 * Mixed (continuous/discrete) equation systems: 0
Notification: Torn system details for strict tearing set:
 * Linear torn systems (#iteration vars, #inner vars, density): 0 systems
 * Non-linear torn systems (#iteration vars, #inner vars): 3 systems
   {(2,9), (1,3), (1,3)}
Notification: Performance of prepare postOptimizeDAE: time 0.0006415/1.478, allocations: 347.7 kB / 1.205 GB, free: 4.973 MB / 0.8731 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0007349/1.478, allocations: 426.7 kB / 1.205 GB, free: 4.551 MB / 0.8731 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.005345/1.484, allocations: 2.763 MB / 1.208 GB, free: 1.777 MB / 0.8731 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.003976/1.488, allocations: 2.298 MB / 1.21 GB, free: 15.45 MB / 0.8887 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 4.88e-06/1.488, allocations: 0 / 1.21 GB, free: 15.45 MB / 0.8887 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001661/1.489, allocations: 0.7261 MB / 1.211 GB, free: 14.7 MB / 0.8887 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004921/1.49, allocations: 32 kB / 1.211 GB, free: 14.66 MB / 0.8887 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.002682/1.493, allocations: 155.8 kB / 1.211 GB, free: 14.51 MB / 0.8887 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002456/1.493, allocations: 137.8 kB / 1.211 GB, free: 14.38 MB / 0.8887 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.005678/1.499, allocations: 2.576 MB / 1.214 GB, free: 11.82 MB / 0.8887 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.006356/1.505, allocations: 3.932 MB / 1.218 GB, free: 7.867 MB / 0.8887 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.001747/1.507, allocations: 1.045 MB / 1.219 GB, free: 6.828 MB / 0.8887 GB
Notification: Performance of sorting global known variables: time 0.003714/1.51, allocations: 2.71 MB / 1.221 GB, free: 4.121 MB / 0.8887 GB
Notification: Performance of Backend: time 3.91e-07/1.51, allocations: 0 / 1.221 GB, free: 4.121 MB / 0.8887 GB
Notification: Performance of simCode: created initialization part: time 0.009969/1.52, allocations: 4.737 MB / 1.226 GB, free: 15.59 MB / 0.9044 GB
Notification: Performance of SimCode: time 0.01446/1.535, allocations: 9.22 MB / 1.235 GB, free: 6.395 MB / 0.9044 GB
Notification: Performance of Templates: time 0.142/1.677, allocations: 103.5 MB / 1.336 GB, free: 140 kB / 0.9981 GB
make -j1 -f IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.makefile
(rm -f IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe ; mkfifo IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe ; head -c 1048576 < IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe >> ../files/IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.sim & ./IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IDEAS_dev_IDEAS.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe 2>&1)