Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries/ --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.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/IBPSA 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 IBPSA with version 3.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 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(IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem")
translateModel(IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem,tolerance=1e-06,outputFormat="empty",numberOfIntervals=5000,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem")
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.0.0+maint.om/package.mo): time 0.001572/0.001572, allocations: 171.9 kB / 16.76 MB, free: 4.91 MB / 13.93 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA 3.0.0-master/package.mo): time 0.4533/0.4533, allocations: 86.27 MB / 103.8 MB, free: 14.57 MB / 90.72 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.0.0+maint.om/package.mo): time 1.302/1.302, allocations: 223.2 MB / 348.4 MB, free: 5.039 MB / 282.7 MB
Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.0.0+maint.om/package.mo): time 0.0007995/0.0007995, allocations: 96.98 kB / 420.2 MB, free: 9.645 MB / 314.7 MB
Notification: Performance of FrontEnd - loaded program: time 0.0004013/0.0004014, allocations: 12 kB / 0.4901 GB, free: 8.004 MB / 394.7 MB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.3078/0.3083, allocations: 71.02 MB / 0.5594 GB, free: 43.76 MB / 410.7 MB
Notification: Performance of FrontEnd - scodeFlatten: time 3.433/3.741, allocations: 1.902 GB / 2.461 GB, free: 153.1 MB / 0.6042 GB
Notification: Performance of FrontEnd - mkProgramGraph: time 0.0002725/3.741, allocations: 95.95 kB / 2.461 GB, free: 153 MB / 0.6042 GB
Notification: Performance of FrontEnd - DAE generated: time 10.43/14.18, allocations: 3.156 GB / 5.618 GB, free: 20.3 MB / 0.7292 GB
Notification: Performance of FrontEnd: time 2.424e-06/14.18, allocations: 4 kB / 5.618 GB, free: 20.3 MB / 0.7292 GB
Notification: Performance of Transformations before backend: time 0.0007426/14.18, allocations: 123.9 kB / 5.618 GB, free: 20.18 MB / 0.7292 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.02071/14.2, allocations: 5.906 MB / 5.624 GB, free: 14.19 MB / 0.7292 GB
Notification: Performance of prepare preOptimizeDAE: time 4.761e-05/14.2, allocations: 8.031 kB / 5.624 GB, free: 14.18 MB / 0.7292 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0149/14.21, allocations: 1.462 MB / 5.625 GB, free: 12.71 MB / 0.7292 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01715/14.23, allocations: 4.625 MB / 5.63 GB, free: 8 MB / 0.7292 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0003821/14.23, allocations: 238 kB / 5.63 GB, free: 7.766 MB / 0.7292 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.001315/14.23, allocations: 316.3 kB / 5.63 GB, free: 7.457 MB / 0.7292 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.01039/14.24, allocations: 3.367 MB / 5.633 GB, free: 3.957 MB / 0.7292 GB
Notification: Performance of preOpt findStateOrder (simulation): time 0.0001173/14.24, allocations: 7.875 kB / 5.633 GB, free: 3.949 MB / 0.7292 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0006294/14.24, allocations: 120 kB / 5.633 GB, free: 3.832 MB / 0.7292 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 0.0001057/14.24, allocations: 105.3 kB / 5.634 GB, free: 3.727 MB / 0.7292 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.008257/14.25, allocations: 2.73 MB / 5.636 GB, free: 0.9883 MB / 0.7292 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.03692/14.29, allocations: 15.1 MB / 5.651 GB, free: 1 MB / 0.7448 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.4678/14.76, allocations: 1.909 MB / 5.653 GB, free: 63 MB / 0.7448 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002038/14.76, allocations: 1.148 MB / 5.654 GB, free: 62.99 MB / 0.7448 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.0003649/14.76, allocations: 236.4 kB / 5.654 GB, free: 62.8 MB / 0.7448 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 4.723e-05/14.76, allocations: 59.31 kB / 5.654 GB, free: 62.76 MB / 0.7448 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.003264/14.76, allocations: 0.585 MB / 5.655 GB, free: 62.76 MB / 0.7448 GB
Notification: Performance of pre-optimization done (n=191): time 1.054e-05/14.76, allocations: 0 / 5.655 GB, free: 62.76 MB / 0.7448 GB
Notification: Performance of matching and sorting (n=199): time 0.0179/14.78, allocations: 7.268 MB / 5.662 GB, free: 61.62 MB / 0.7448 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 3.918e-05/14.78, allocations: 71.94 kB / 5.662 GB, free: 61.55 MB / 0.7448 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.005693/14.78, allocations: 3.798 MB / 5.666 GB, free: 61.25 MB / 0.7448 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004734/14.79, allocations: 82.3 kB / 5.666 GB, free: 61.21 MB / 0.7448 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001362/14.79, allocations: 1.994 MB / 5.668 GB, free: 60.82 MB / 0.7448 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006491/14.79, allocations: 0.6844 MB / 5.668 GB, free: 60.47 MB / 0.7448 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.0001253/14.79, allocations: 50.89 kB / 5.668 GB, free: 60.47 MB / 0.7448 GB
Notification: Performance of setup shared object (initialization): time 0.0001085/14.79, allocations: 398.7 kB / 5.669 GB, free: 60.16 MB / 0.7448 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.001709/14.79, allocations: 0.9005 MB / 5.67 GB, free: 60.15 MB / 0.7448 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.002134/14.79, allocations: 1.899 MB / 5.672 GB, free: 59.01 MB / 0.7448 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.02854/14.82, allocations: 10.04 MB / 5.681 GB, free: 57.91 MB / 0.7448 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.666e-05/14.82, allocations: 5.719 kB / 5.681 GB, free: 57.91 MB / 0.7448 GB
Notification: Performance of matching and sorting (n=290) (initialization): time 0.007323/14.83, allocations: 3.271 MB / 5.685 GB, free: 57.57 MB / 0.7448 GB
Notification: Performance of prepare postOptimizeDAE: time 0.0002947/14.83, allocations: 0.6195 MB / 5.685 GB, free: 56.86 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.276e-05/14.83, allocations: 22.88 kB / 5.685 GB, free: 56.86 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001489/14.83, allocations: 386.1 kB / 5.686 GB, free: 56.82 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002003/14.83, allocations: 0.5115 MB / 5.686 GB, free: 56.82 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.002722/14.83, allocations: 4.673 MB / 5.691 GB, free: 52.75 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004825/14.84, allocations: 356.1 kB / 5.691 GB, free: 52.73 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0002672/14.84, allocations: 128.7 kB / 5.691 GB, free: 52.73 MB / 0.7448 GB
Notification: Performance of prepare postOptimizeDAE: time 7.404e-06/14.84, allocations: 6.156 kB / 5.691 GB, free: 52.73 MB / 0.7448 GB
Notification: Performance of postOpt replaceHomotopyWithSimplified (initialization): time 0.004858/14.84, allocations: 2.716 MB / 5.694 GB, free: 52.64 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.572e-05/14.84, allocations: 24.56 kB / 5.694 GB, free: 52.64 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.0008789/14.84, allocations: 298.3 kB / 5.694 GB, free: 52.6 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.001819/14.85, allocations: 0.5021 MB / 5.695 GB, free: 52.6 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.005571/14.85, allocations: 8.091 MB / 5.702 GB, free: 45.8 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.004838/14.86, allocations: 348 kB / 5.703 GB, free: 45.79 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003523/14.86, allocations: 117 kB / 5.703 GB, free: 45.79 MB / 0.7448 GB
Warning: The initial conditions are over specified. The following 4 initial equations are redundant, so they are removed from the initialization sytem:
         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: 78
 * 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 (268):
 * Single equations (assignments): 261
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (linear and non-linear blocks): 0
 * Torn equation systems: 5
 * 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: 3 {2 9,1 3,1 3}
Notification: Performance of prepare postOptimizeDAE: time 0.002483/14.86, allocations: 0.971 MB / 5.704 GB, free: 45.59 MB / 0.7448 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.001112/14.86, allocations: 422.6 kB / 5.704 GB, free: 45.38 MB / 0.7448 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.00594/14.87, allocations: 2.828 MB / 5.707 GB, free: 45.19 MB / 0.7448 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 1.582e-05/14.87, allocations: 20.72 kB / 5.707 GB, free: 45.19 MB / 0.7448 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 8.967e-06/14.87, allocations: 3.5 kB / 5.707 GB, free: 45.19 MB / 0.7448 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.004166/14.87, allocations: 2.338 MB / 5.709 GB, free: 44.84 MB / 0.7448 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.014/14.89, allocations: 6.697 MB / 5.716 GB, free: 44 MB / 0.7448 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 1.324e-05/14.89, allocations: 10.09 kB / 5.716 GB, free: 44 MB / 0.7448 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.001928/14.89, allocations: 438.5 kB / 5.716 GB, free: 44 MB / 0.7448 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.0007231/14.89, allocations: 272.8 kB / 5.716 GB, free: 43.96 MB / 0.7448 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001509/14.89, allocations: 63.48 kB / 5.717 GB, free: 43.96 MB / 0.7448 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.002597/14.89, allocations: 4.595 MB / 5.721 GB, free: 39.89 MB / 0.7448 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 8.886e-06/14.89, allocations: 17.75 kB / 5.721 GB, free: 39.89 MB / 0.7448 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.00748/14.9, allocations: 4.254 MB / 5.725 GB, free: 39.03 MB / 0.7448 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002664/14.9, allocations: 0.7452 MB / 5.726 GB, free: 38.72 MB / 0.7448 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.001076/14.9, allocations: 56.97 kB / 5.726 GB, free: 38.71 MB / 0.7448 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003834/14.91, allocations: 280.4 kB / 5.726 GB, free: 38.7 MB / 0.7448 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003023/14.91, allocations: 161.7 kB / 5.726 GB, free: 38.7 MB / 0.7448 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001885/14.91, allocations: 96.44 kB / 5.727 GB, free: 38.7 MB / 0.7448 GB
Notification: Performance of sorting global known variables: time 0.004275/14.91, allocations: 2.596 MB / 5.729 GB, free: 38.52 MB / 0.7448 GB
Notification: Performance of sort global known variables: time 2.31e-07/14.91, allocations: 0 / 5.729 GB, free: 38.52 MB / 0.7448 GB
Notification: Performance of remove unused functions: time 0.009958/14.92, allocations: 2.572 MB / 5.732 GB, free: 38.52 MB / 0.7448 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 39
 * Number of states: 8 (zone2.dynBal.U,zone2.dynBal.mXi[1],zone1.dynBal.U,zone1.dynBal.mXi[1],zone3.dynBal.U,zone3.dynBal.mXi[1],zone4.dynBal.U,zone4.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 (189):
 * Single equations (assignments): 184
 * 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: 5
 * 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: 3 {2 9,1 3,1 3}
Notification: Performance of Backend phase and start with SimCode phase: time 0.01059/14.93, allocations: 8.039 MB / 5.739 GB, free: 34.54 MB / 0.7448 GB
Notification: Performance of simCode: created initialization part: time 0.008777/14.94, allocations: 4.572 MB / 5.744 GB, free: 33.7 MB / 0.7448 GB
Notification: Performance of simCode: created event and clocks part: time 1.388e-05/14.94, allocations: 5.156 kB / 5.744 GB, free: 33.7 MB / 0.7448 GB
Notification: Performance of simCode: created simulation system equations: time 0.002389/14.94, allocations: 1.41 MB / 5.745 GB, free: 33.46 MB / 0.7448 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.01651/14.96, allocations: 2.254 MB / 5.747 GB, free: 33.41 MB / 0.7448 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01155/14.97, allocations: 8.915 MB / 5.756 GB, free: 29.52 MB / 0.7448 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.001873/14.97, allocations: 2.529 MB / 5.759 GB, free: 28.72 MB / 0.7448 GB
Notification: Performance of simCode: alias equations: time 0.005659/14.98, allocations: 1.119 MB / 5.76 GB, free: 28.68 MB / 0.7448 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001157/14.98, allocations: 332.2 kB / 5.76 GB, free: 28.68 MB / 0.7448 GB
Notification: Performance of SimCode: time 8.41e-07/14.98, allocations: 0 / 5.76 GB, free: 28.68 MB / 0.7448 GB
Notification: Performance of Templates: time 0.1942/15.17, allocations: 146.8 MB / 5.903 GB, free: 10.52 MB / 0.7605 GB
make -j1 -f IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.makefile
(rm -f IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.sim & ./IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe 2>&1)