Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.conf.json
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo): time 0.001729/0.001729, allocations: 109.1 kB / 19.71 MB, free: 2.145 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo): time 0.002074/0.002074, allocations: 213.5 kB / 23 MB, free: 4.969 MB / 14.72 MB
"
[Timeout remaining time 180]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo): time 1.514/1.514, allocations: 230.6 MB / 256.8 MB, free: 7.82 MB / 206.1 MB
"
[Timeout remaining time 178]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo): time 3.165/3.165, allocations: 0.49 GB / 0.7959 GB, free: 35.46 MB / 0.6231 GB
"
[Timeout remaining time 177]
Using package Buildings with version 12.1.1 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Buildings 12.1.1-maint.12.x/package.mo)
Using package Modelica with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Modelica 4.1.0+maint.om/package.mo)
Using package Complex with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/Complex 4.1.0+maint.om/package.mo)
Using package ModelicaServices with version 4.1.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/ModelicaServices 4.1.0+maint.om/package.mo)
Running command: translateModel(Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|floConDp.dp_actual|floConDpSystem.dp_actual|floConDp.m_flow|floConDpSystem.m_flow",fileNamePrefix="Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem")
translateModel(Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem,tolerance=1e-06,outputFormat="mat",numberOfIntervals=2500,variableFilter="time|floConDp.dp_actual|floConDpSystem.dp_actual|floConDp.m_flow|floConDpSystem.m_flow",fileNamePrefix="Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem") [Timeout 300]
"Notification: Buildings requested package Modelica of version 4.0.0. Modelica 4.1.0 is used instead which states that it is fully compatible without conversion script needed.
Notification: Performance of FrontEnd - loaded program: time 0.002031/0.002031, allocations: 76.23 kB / 1.155 GB, free: 25.22 MB / 0.9044 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 0.312/0.314, allocations: 176.1 MB / 1.327 GB, free: 1.031 MB / 1.014 GB
Notification: Performance of NFInst.instantiate(Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem): time 1.078/1.392, allocations: 319.8 MB / 1.639 GB, free: 4.402 MB / 1.295 GB
Notification: Performance of NFInst.instExpressions: time 0.04568/1.437, allocations: 34.36 MB / 1.673 GB, free: 4.172 MB / 1.295 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.006866/1.444, allocations: 259 kB / 1.673 GB, free: 4.172 MB / 1.295 GB
Notification: Performance of NFTyping.typeComponents: time 0.009314/1.454, allocations: 3.629 MB / 1.677 GB, free: 4.094 MB / 1.295 GB
Notification: Performance of NFTyping.typeBindings: time 0.0218/1.475, allocations: 9.154 MB / 1.686 GB, free: 3.688 MB / 1.295 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01193/1.487, allocations: 5.197 MB / 1.691 GB, free: 3.551 MB / 1.295 GB
Notification: Performance of NFFlatten.flatten: time 0.02155/1.509, allocations: 18.36 MB / 1.709 GB, free: 3.414 MB / 1.295 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.00615/1.515, allocations: 3.57 MB / 1.712 GB, free: 19.13 MB / 1.311 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01481/1.53, allocations: 7.295 MB / 1.719 GB, free: 18.98 MB / 1.311 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.009376/1.539, allocations: 4.202 MB / 1.723 GB, free: 18.36 MB / 1.311 GB
Notification: Performance of NFPackage.collectConstants: time 0.002099/1.541, allocations: 477.7 kB / 1.724 GB, free: 18.36 MB / 1.311 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01358/1.555, allocations: 5.794 MB / 1.73 GB, free: 18.28 MB / 1.311 GB
Notification: Performance of NFScalarize.scalarize: time 0.002741/1.558, allocations: 2.149 MB / 1.732 GB, free: 17.55 MB / 1.311 GB
Notification: Performance of NFVerifyModel.verify: time 0.00398/1.562, allocations: 2.565 MB / 1.734 GB, free: 16.3 MB / 1.311 GB
Notification: Performance of NFConvertDAE.convert: time 0.01649/1.578, allocations: 10.03 MB / 1.744 GB, free: 12.18 MB / 1.311 GB
Notification: Performance of FrontEnd - DAE generated: time 4.969e-06/1.578, allocations: 0 / 1.744 GB, free: 12.18 MB / 1.311 GB
Notification: Performance of FrontEnd: time 3.687e-06/1.578, allocations: 6.688 kB / 1.744 GB, free: 12.18 MB / 1.311 GB
Notification: Performance of Transformations before backend: time 0.0002042/1.578, allocations: 0 / 1.744 GB, free: 12.18 MB / 1.311 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 719
 * Number of variables: 719
Notification: Performance of Generate backend data structure: time 0.01669/1.595, allocations: 6.845 MB / 1.751 GB, free: 7.434 MB / 1.311 GB
Notification: Performance of prepare preOptimizeDAE: time 4.246e-05/1.595, allocations: 12.16 kB / 1.751 GB, free: 7.43 MB / 1.311 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.0126/1.608, allocations: 1.876 MB / 1.752 GB, free: 6.578 MB / 1.311 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.01262/1.62, allocations: 5.691 MB / 1.758 GB, free: 3.086 MB / 1.311 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002457/1.621, allocations: 234.2 kB / 1.758 GB, free: 2.984 MB / 1.311 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0007064/1.621, allocations: 308.7 kB / 1.759 GB, free: 2.891 MB / 1.311 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.007762/1.629, allocations: 4.147 MB / 1.763 GB, free: 15.96 MB / 1.326 GB
Notification: Performance of preOpt findStateOrder (simulation): time 6.672e-05/1.629, allocations: 6.062 kB / 1.763 GB, free: 15.96 MB / 1.326 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0003445/1.629, allocations: 123.5 kB / 1.763 GB, free: 15.87 MB / 1.326 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.786e-05/1.629, allocations: 108.9 kB / 1.763 GB, free: 15.85 MB / 1.326 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.00663/1.636, allocations: 2.917 MB / 1.766 GB, free: 14.45 MB / 1.326 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.02478/1.661, allocations: 12.04 MB / 1.777 GB, free: 5.816 MB / 1.326 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.00421/1.665, allocations: 1.889 MB / 1.779 GB, free: 4.859 MB / 1.326 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.002289/1.667, allocations: 1.11 MB / 1.78 GB, free: 4.32 MB / 1.326 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.000314/1.668, allocations: 72.2 kB / 1.78 GB, free: 4.293 MB / 1.326 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 5.728e-05/1.668, allocations: 56.69 kB / 1.781 GB, free: 4.238 MB / 1.326 GB
Notification: Performance of preOpt simplifyInStream (simulation): time 0.002356/1.67, allocations: 301.7 kB / 1.781 GB, free: 4.07 MB / 1.326 GB
Notification: Performance of pre-optimization done (n=181): time 5.992e-06/1.67, allocations: 3.094 kB / 1.781 GB, free: 4.07 MB / 1.326 GB
Notification: Performance of matching and sorting (n=189): time 0.01948/1.69, allocations: 7.012 MB / 1.788 GB, free: 296 kB / 1.326 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 4.849e-05/1.69, allocations: 69.72 kB / 1.788 GB, free: 228 kB / 1.326 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.007524/1.697, allocations: 4.752 MB / 1.792 GB, free: 12.73 MB / 1.342 GB
Notification: Performance of collectPreVariables (initialization): time 0.0003916/1.698, allocations: 77.7 kB / 1.792 GB, free: 12.64 MB / 1.342 GB
Notification: Performance of collectInitialEqns (initialization): time 0.002387/1.7, allocations: 2.317 MB / 1.795 GB, free: 10.33 MB / 1.342 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0009877/1.701, allocations: 0.6918 MB / 1.795 GB, free: 9.645 MB / 1.342 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001173/1.702, allocations: 0.6884 MB / 1.796 GB, free: 8.949 MB / 1.342 GB
Notification: Performance of setup shared object (initialization): time 0.0001418/1.702, allocations: 305.1 kB / 1.796 GB, free: 8.648 MB / 1.342 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.003003/1.705, allocations: 1.363 MB / 1.798 GB, free: 7.277 MB / 1.342 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.005023/1.71, allocations: 3.693 MB / 1.801 GB, free: 2.859 MB / 1.342 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.04301/1.753, allocations: 11.32 MB / 1.812 GB, free: 7.379 MB / 1.357 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.076e-05/1.753, allocations: 4 kB / 1.812 GB, free: 7.375 MB / 1.357 GB
Notification: Performance of matching and sorting (n=416) (initialization): time 0.01056/1.764, allocations: 4.342 MB / 1.817 GB, free: 3.141 MB / 1.357 GB
Notification: Performance of prepare postOptimizeDAE: time 3.337e-05/1.764, allocations: 14.03 kB / 1.817 GB, free: 3.129 MB / 1.357 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.228e-05/1.764, allocations: 20 kB / 1.817 GB, free: 3.109 MB / 1.357 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001647/1.766, allocations: 307.1 kB / 1.817 GB, free: 2.805 MB / 1.357 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.002857/1.768, allocations: 0.6413 MB / 1.818 GB, free: 2.203 MB / 1.357 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.001728/1.77, allocations: 1.822 MB / 1.819 GB, free: 324 kB / 1.357 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.0052/1.775, allocations: 264.3 kB / 1.82 GB, free: 56 kB / 1.357 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003408/1.776, allocations: 138.9 kB / 1.82 GB, free: 15.92 MB / 1.373 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.004945/1.781, allocations: 2.298 MB / 1.822 GB, free: 13.62 MB / 1.373 GB
Notification: Performance of partitionIndependentBlocks (initialization_lambda0): time 0.004769/1.785, allocations: 3.658 MB / 1.825 GB, free: 9.234 MB / 1.373 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.03754/1.823, allocations: 10.47 MB / 1.836 GB, free: 14.59 MB / 1.389 GB
Notification: Performance of solveInitialSystemEqSystem (initialization_lambda0): time 2.101e-05/1.823, allocations: 4 kB / 1.836 GB, free: 14.59 MB / 1.389 GB
Notification: Performance of matching and sorting (n=416) (initialization_lambda0): time 0.009376/1.832, allocations: 4.167 MB / 1.84 GB, free: 10.53 MB / 1.389 GB
Notification: Performance of prepare postOptimizeDAE: time 2.86e-05/1.832, allocations: 12 kB / 1.84 GB, free: 10.52 MB / 1.389 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 3.044e-05/1.832, allocations: 23.94 kB / 1.84 GB, free: 10.49 MB / 1.389 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.000777/1.833, allocations: 209 kB / 1.84 GB, free: 10.28 MB / 1.389 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.00268/1.836, allocations: 0.6213 MB / 1.841 GB, free: 9.699 MB / 1.389 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.006002/1.842, allocations: 5.203 MB / 1.846 GB, free: 4.223 MB / 1.389 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00564/1.848, allocations: 316.8 kB / 1.846 GB, free: 3.914 MB / 1.389 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003782/1.848, allocations: 124 kB / 1.846 GB, free: 3.793 MB / 1.389 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: 88
 * 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 (334):
 * Single equations (assignments): 327
 * Array equations: 2
 * Algorithm blocks: 0
 * Record equations: 2
 * 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.0007693/1.849, allocations: 384.8 kB / 1.847 GB, free: 3.449 MB / 1.389 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.0013/1.85, allocations: 488.8 kB / 1.847 GB, free: 2.969 MB / 1.389 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.006764/1.857, allocations: 2.803 MB / 1.85 GB, free: 160 kB / 1.389 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 0.005121/1.862, allocations: 2.324 MB / 1.852 GB, free: 13.79 MB / 1.404 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 7.073e-06/1.862, allocations: 0 / 1.852 GB, free: 13.79 MB / 1.404 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.002934/1.865, allocations: 0.7561 MB / 1.853 GB, free: 13.03 MB / 1.404 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0009358/1.866, allocations: 36 kB / 1.853 GB, free: 13 MB / 1.404 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.003948/1.87, allocations: 177.8 kB / 1.853 GB, free: 12.82 MB / 1.404 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0003335/1.87, allocations: 137.7 kB / 1.853 GB, free: 12.69 MB / 1.404 GB
Notification: Performance of postOpt createDAEmodeBDAE (simulation): time 0.007369/1.877, allocations: 2.615 MB / 1.856 GB, free: 10.1 MB / 1.404 GB
Notification: Performance of postOpt symbolicJacobianDAE (simulation): time 0.009021/1.886, allocations: 3.966 MB / 1.859 GB, free: 6.117 MB / 1.404 GB
Notification: Performance of postOpt setEvaluationStage (simulation): time 0.002293/1.889, allocations: 0.9954 MB / 1.86 GB, free: 5.121 MB / 1.404 GB
Notification: Performance of sorting global known variables: time 0.006213/1.895, allocations: 3.191 MB / 1.864 GB, free: 1.949 MB / 1.404 GB
Notification: Performance of Backend: time 4.31e-07/1.895, allocations: 0 / 1.864 GB, free: 1.949 MB / 1.404 GB
Notification: Performance of simCode: created initialization part: time 0.01531/1.91, allocations: 5.62 MB / 1.869 GB, free: 12.54 MB / 1.42 GB
Notification: Performance of SimCode: time 0.02179/1.932, allocations: 10.66 MB / 1.879 GB, free: 1.918 MB / 1.42 GB
Notification: Performance of Templates: time 0.2091/2.141, allocations: 126.8 MB / 2.003 GB, free: 4.984 MB / 1.545 GB
"
[Timeout remaining time 298]
make -j1 -f Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.makefile [Timeout 300]
(rm -f Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe ; mkfifo Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe ; head -c 1048576 < Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe >> ../files/Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.sim & ./Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem  -abortSlowSimulation -alarm=400  -emit_protected -lv LOG_STATS > Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.pipe 2>&1) [Timeout 400]
diffSimulationResults("Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem_res.mat","/mnt/ReferenceFiles/Buildings/csv/maint_12.x/Buildings_Fluid_Movers_Validation_FlowControlled_dpSystem.csv","/var/lib/jenkins1/ws/OpenModelicaLibraryTestingWork/OpenModelicaLibraryTesting/files/Buildings_12_Buildings.Fluid.Movers.Validation.FlowControlled_dpSystem.diff",relTol=0.003,relTolDiffMinMax=0.003,rangeDelta=0.001) [Timeout 300]
""
[Timeout remaining time 300]
Reference file matches
[Calling sys.exit(0), Time elapsed: 14.132068940001773]