Running: ./testmodel.py --libraries=/home/hudson/saved_omc/libraries/.openmodelica/libraries --ompython_omhome=/usr IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.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.0009982/0.0009982, allocations: 84.44 kB / 20.69 MB, free: 3.871 MB / 18.57 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.0008232/0.0008232, allocations: 166.3 kB / 23.97 MB, free: 0.5977 MB / 18.57 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 0.8824/0.8824, allocations: 177.1 MB / 204.3 MB, free: 5.734 MB / 186.7 MB
"
[Timeout remaining time 179]
loadFile("/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo", uses=false) [Timeout 180]
"Notification: Performance of loadFile(/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/package.mo): time 0.5437/0.5437, allocations: 114 MB / 374.7 MB, free: 8.727 MB / 346.7 MB
"
[Timeout remaining time 179]
Using package IBPSA with version 4.0.0 (/home/hudson/saved_omc/libraries/.openmodelica/libraries/IBPSA master/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(IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU")
translateModel(IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU,tolerance=1e-06,outputFormat="empty",numberOfIntervals=2500,variableFilter="",fileNamePrefix="IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU") [Timeout 660]
"Notification: Performance of FrontEnd - loaded program: time 1.783e-06/1.783e-06, allocations: 0 / 0.5554 GB, free: 2.297 MB / 0.5105 GB
Notification: Performance of FrontEnd - Absyn->SCode: time 2.565e-05/2.743e-05, allocations: 7.406 kB / 0.5554 GB, free: 2.289 MB / 0.5105 GB
Notification: Performance of NFInst.instantiate(IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU): time 0.5154/0.5154, allocations: 330 MB / 0.8777 GB, free: 0.5 MB / 0.6823 GB
Notification: Performance of NFInst.instExpressions: time 0.04051/0.5559, allocations: 31.2 MB / 0.9081 GB, free: 1.23 MB / 0.7136 GB
Notification: Performance of NFInst.updateImplicitVariability: time 0.008142/0.5641, allocations: 202.3 kB / 0.9083 GB, free: 1.031 MB / 0.7136 GB
Notification: Performance of NFTyping.typeComponents: time 0.006327/0.5704, allocations: 2.348 MB / 0.9106 GB, free: 14.67 MB / 0.7292 GB
Notification: Performance of NFTyping.typeBindings: time 0.01764/0.588, allocations: 8.097 MB / 0.9185 GB, free: 6.535 MB / 0.7292 GB
Notification: Performance of NFTyping.typeClassSections: time 0.01056/0.5986, allocations: 4.732 MB / 0.9232 GB, free: 1.852 MB / 0.7292 GB
Notification: Performance of NFFlatten.flatten: time 0.01421/0.6128, allocations: 11.34 MB / 0.9342 GB, free: 6.477 MB / 0.7448 GB
Notification: Performance of NFFlatten.resolveConnections: time 0.004897/0.6177, allocations: 3.062 MB / 0.9372 GB, free: 3.355 MB / 0.7448 GB
Notification: Performance of NFEvalConstants.evaluate: time 0.01459/0.6323, allocations: 7.661 MB / 0.9447 GB, free: 11.75 MB / 0.7605 GB
Notification: Performance of NFSimplifyModel.simplify: time 0.005261/0.6375, allocations: 2.991 MB / 0.9476 GB, free: 8.762 MB / 0.7605 GB
Notification: Performance of NFPackage.collectConstants: time 0.0008213/0.6384, allocations: 0.5547 MB / 0.9482 GB, free: 8.207 MB / 0.7605 GB
Notification: Performance of NFFlatten.collectFunctions: time 0.01033/0.6487, allocations: 5.822 MB / 0.9538 GB, free: 2.371 MB / 0.7605 GB
Notification: Performance of NFScalarize.scalarize: time 0.001297/0.65, allocations: 1.561 MB / 0.9554 GB, free: 0.8047 MB / 0.7605 GB
Notification: Performance of NFVerifyModel.verify: time 0.00298/0.653, allocations: 1.964 MB / 0.9573 GB, free: 14.84 MB / 0.7761 GB
Notification: Performance of NFConvertDAE.convert: time 0.01737/0.6703, allocations: 10.16 MB / 0.9672 GB, free: 4.648 MB / 0.7761 GB
Notification: Performance of FrontEnd - DAE generated: time 7.044e-06/0.6704, allocations: 4 kB / 0.9672 GB, free: 4.645 MB / 0.7761 GB
Notification: Performance of FrontEnd: time 1.774e-06/0.6704, allocations: 0 / 0.9672 GB, free: 4.645 MB / 0.7761 GB
Notification: Performance of Transformations before backend: time 9.585e-05/0.6705, allocations: 0 / 0.9672 GB, free: 4.645 MB / 0.7761 GB
Notification: Model statistics after passing the front-end and creating the data structures used by the back-end:
 * Number of equations: 859
 * Number of variables: 859
Notification: Performance of Generate backend data structure: time 0.008741/0.6792, allocations: 6.046 MB / 0.9731 GB, free: 14.5 MB / 0.7917 GB
Notification: Performance of prepare preOptimizeDAE: time 4.893e-05/0.6792, allocations: 8.625 kB / 0.9731 GB, free: 14.49 MB / 0.7917 GB
Notification: Performance of preOpt normalInlineFunction (simulation): time 0.01448/0.6937, allocations: 4.398 MB / 0.9774 GB, free: 10.09 MB / 0.7917 GB
Notification: Performance of preOpt evaluateParameters (simulation): time 0.005727/0.6995, allocations: 3.671 MB / 0.981 GB, free: 6.352 MB / 0.7917 GB
Notification: Performance of preOpt simplifyIfEquations (simulation): time 0.0002145/0.6997, allocations: 343 kB / 0.9813 GB, free: 6.004 MB / 0.7917 GB
Notification: Performance of preOpt expandDerOperator (simulation): time 0.0004756/0.7001, allocations: 359.9 kB / 0.9817 GB, free: 5.652 MB / 0.7917 GB
Notification: Performance of preOpt clockPartitioning (simulation): time 0.2813/0.9815, allocations: 5.922 MB / 0.9875 GB, free: 310.3 MB / 0.7917 GB
Notification: Performance of preOpt findStateOrder (simulation): time 5.925e-05/0.9815, allocations: 1.312 kB / 0.9875 GB, free: 310.3 MB / 0.7917 GB
Notification: Performance of preOpt replaceEdgeChange (simulation): time 0.0004783/0.982, allocations: 136 kB / 0.9876 GB, free: 310.3 MB / 0.7917 GB
Notification: Performance of preOpt inlineArrayEqn (simulation): time 9.88e-05/0.9821, allocations: 135.1 kB / 0.9877 GB, free: 310.3 MB / 0.7917 GB
Notification: Performance of preOpt removeEqualRHS (simulation): time 0.01127/0.9934, allocations: 6.803 MB / 0.9944 GB, free: 308.5 MB / 0.7917 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.02256/1.016, allocations: 16.1 MB / 1.01 GB, free: 300.9 MB / 0.7917 GB
Notification: Performance of preOpt comSubExp (simulation): time 0.006097/1.022, allocations: 3.856 MB / 1.014 GB, free: 299.6 MB / 0.7917 GB
Notification: Performance of preOpt resolveLoops (simulation): time 0.003258/1.025, allocations: 2.137 MB / 1.016 GB, free: 298.9 MB / 0.7917 GB
Notification: Performance of preOpt evalFunc (simulation): time 0.009069/1.034, allocations: 5.69 MB / 1.021 GB, free: 295.9 MB / 0.7917 GB
Notification: Performance of preOpt encapsulateWhenConditions (simulation): time 0.004219/1.039, allocations: 2.698 MB / 1.024 GB, free: 294.9 MB / 0.7917 GB
Notification: Performance of pre-optimization done (n=228): time 5.55e-06/1.039, allocations: 0 / 1.024 GB, free: 294.9 MB / 0.7917 GB
Notification: Performance of matching and sorting (n=228): time 0.01933/1.058, allocations: 7.926 MB / 1.032 GB, free: 292.7 MB / 0.7917 GB
Notification: Performance of inlineWhenForInitialization (initialization): time 6.616e-05/1.058, allocations: 127.5 kB / 1.032 GB, free: 292.6 MB / 0.7917 GB
Notification: Performance of selectInitializationVariablesDAE (initialization): time 0.00566/1.064, allocations: 3.641 MB / 1.036 GB, free: 291.3 MB / 0.7917 GB
Notification: Performance of collectPreVariables (initialization): time 0.0004224/1.064, allocations: 84.02 kB / 1.036 GB, free: 291.2 MB / 0.7917 GB
Notification: Performance of collectInitialEqns (initialization): time 0.001259/1.065, allocations: 1.897 MB / 1.037 GB, free: 289.9 MB / 0.7917 GB
Notification: Performance of collectInitialBindings (initialization): time 0.0006985/1.066, allocations: 0.8246 MB / 1.038 GB, free: 289.2 MB / 0.7917 GB
Notification: Performance of simplifyInitialFunctions (initialization): time 0.001484/1.067, allocations: 0.8096 MB / 1.039 GB, free: 288.9 MB / 0.7917 GB
Notification: Performance of setup shared object (initialization): time 3.545e-05/1.068, allocations: 307.3 kB / 1.039 GB, free: 288.6 MB / 0.7917 GB
Notification: Performance of preBalanceInitialSystem (initialization): time 0.005273/1.073, allocations: 2.71 MB / 1.042 GB, free: 287.7 MB / 0.7917 GB
Notification: Performance of partitionIndependentBlocks (initialization): time 0.00548/1.078, allocations: 4.06 MB / 1.046 GB, free: 285.3 MB / 0.7917 GB
Notification: It was not possible to check the given initialization system for consistency symbolically, because the relevant equations are part of an algebraic loop. This is not supported yet.
Notification: Performance of analyzeInitialSystem (initialization): time 0.009242/1.088, allocations: 5.85 MB / 1.052 GB, free: 282.8 MB / 0.7917 GB
Notification: Performance of solveInitialSystemEqSystem (initialization): time 2.304e-05/1.088, allocations: 4.219 kB / 1.052 GB, free: 282.8 MB / 0.7917 GB
Notification: Performance of matching and sorting (n=446) (initialization): time 0.01341/1.101, allocations: 7.492 MB / 1.059 GB, free: 279.6 MB / 0.7917 GB
Notification: Performance of prepare postOptimizeDAE: time 3.048e-05/1.101, allocations: 11.66 kB / 1.059 GB, free: 279.6 MB / 0.7917 GB
Notification: Performance of postOpt simplifyComplexFunction (initialization): time 2.235e-05/1.101, allocations: 15.28 kB / 1.059 GB, free: 279.6 MB / 0.7917 GB
Notification: Performance of postOpt tearingSystem (initialization): time 0.001933/1.103, allocations: 0.9694 MB / 1.06 GB, free: 279.1 MB / 0.7917 GB
Notification: Performance of postOpt solveSimpleEquations (initialization): time 0.003883/1.107, allocations: 1.408 MB / 1.061 GB, free: 277.7 MB / 0.7917 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (initialization): time 0.007547/1.114, allocations: 13.53 MB / 1.075 GB, free: 263.3 MB / 0.7917 GB
Notification: Performance of postOpt simplifyAllExpressions (initialization): time 0.00478/1.119, allocations: 1.104 MB / 1.076 GB, free: 262.2 MB / 0.7917 GB
Notification: Performance of postOpt collapseArrayExpressions (initialization): time 0.0003243/1.119, allocations: 183.8 kB / 1.076 GB, free: 262.1 MB / 0.7917 GB
Warning: The initial conditions are over specified. The following 2 initial equations are redundant, so they are removed from the initialization system:
         hexWetNTU.UAFroRated.TAirIn = 293.15
         hexWetNTU.UAFroRated.TWatIn = 293.15.
Notification: Model statistics after passing the back-end for initialization:
 * Number of independent subsystems: 65
 * Number of states: 0 ()
 * Number of discrete variables: 11 (hexWetNTU.UAFroRated.isFulWet,hexWetNTU.UAFroRated.isFulDry,$PRE.w_a2.nextTimeEvent,$whenCondition1,hexWetNTU_TX.flowRegime_nominal,hexWetNTU_TX.UAFroRated.isFulWet,hexWetNTU_TX.UAFroRated.isFulDry,hexWetNTU.flowRegime_nominal,w_a2.nextTimeEventScaled,$PRE.w_a2.nextTimeEventScaled,w_a2.nextTimeEvent)
 * Number of discrete states: 0 ()
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for initialization (416):
 * Single equations (assignments): 401
 * Array equations: 4
 * Algorithm blocks: 0
 * Record equations: 0
 * When equations: 0
 * If-equations: 0
 * Equation systems (not torn): 2
 * Torn equation systems: 9
 * Mixed (continuous/discrete) equation systems: 0
Notification: Equation system details (not torn):
 * Constant Jacobian (size): 0 systems
 * Linear Jacobian (size,density): 0 systems
 * Non-linear Jacobian (size): 2 systems
   {1, 1}
 * Without analytic Jacobian (size): 0 systems
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): 9 systems
   {(1,1), (1,3), (1,2), (1,2), (1,4), (1,2), (1,4), (1,2), (5,2)}
Notification: Performance of prepare postOptimizeDAE: time 0.001879/1.121, allocations: 0.8624 MB / 1.077 GB, free: 261.2 MB / 0.7917 GB
Notification: Performance of postOpt lateInlineFunction (simulation): time 0.000729/1.122, allocations: 381.6 kB / 1.077 GB, free: 260.8 MB / 0.7917 GB
Notification: Performance of postOpt wrapFunctionCalls (simulation): time 0.01033/1.132, allocations: 5.741 MB / 1.083 GB, free: 255.1 MB / 0.7917 GB
Notification: Performance of postOpt inlineArrayEqn (simulation): time 2.02e-05/1.132, allocations: 24 kB / 1.083 GB, free: 255 MB / 0.7917 GB
Notification: Performance of postOpt constantLinearSystem (simulation): time 6.442e-06/1.132, allocations: 0 / 1.083 GB, free: 255 MB / 0.7917 GB
Notification: Performance of postOpt simplifysemiLinear (simulation): time 1.711e-05/1.132, allocations: 11.95 kB / 1.083 GB, free: 255 MB / 0.7917 GB
Notification: Performance of postOpt removeSimpleEquations (simulation): time 0.01406/1.147, allocations: 9.585 MB / 1.092 GB, free: 245.4 MB / 0.7917 GB
Notification: Performance of postOpt simplifyComplexFunction (simulation): time 8.706e-06/1.147, allocations: 7.938 kB / 1.092 GB, free: 245.4 MB / 0.7917 GB
Notification: Performance of postOpt solveSimpleEquations (simulation): time 0.00201/1.149, allocations: 0.6788 MB / 1.093 GB, free: 244.7 MB / 0.7917 GB
Notification: Performance of postOpt tearingSystem (simulation): time 0.001436/1.15, allocations: 0.7639 MB / 1.093 GB, free: 243.9 MB / 0.7917 GB
Notification: Performance of postOpt inputDerivativesUsed (simulation): time 0.0001665/1.15, allocations: 79.91 kB / 1.094 GB, free: 243.8 MB / 0.7917 GB
Notification: Performance of postOpt calculateStrongComponentJacobians (simulation): time 0.005809/1.156, allocations: 10.85 MB / 1.104 GB, free: 232.3 MB / 0.7917 GB
Notification: Performance of postOpt calculateStateSetsJacobians (simulation): time 2.946e-06/1.156, allocations: 7.938 kB / 1.104 GB, free: 232.3 MB / 0.7917 GB
Notification: Performance of postOpt symbolicJacobian (simulation): time 0.0129/1.169, allocations: 8.142 MB / 1.112 GB, free: 224.1 MB / 0.7917 GB
Notification: Performance of postOpt removeConstants (simulation): time 0.001635/1.17, allocations: 0.9363 MB / 1.113 GB, free: 223.1 MB / 0.7917 GB
Notification: Performance of postOpt simplifyTimeIndepFuncCalls (simulation): time 0.0004705/1.171, allocations: 47.98 kB / 1.113 GB, free: 223.1 MB / 0.7917 GB
Notification: Performance of postOpt simplifyAllExpressions (simulation): time 0.00266/1.174, allocations: 0.516 MB / 1.114 GB, free: 222.6 MB / 0.7917 GB
Notification: Performance of postOpt findZeroCrossings (simulation): time 0.0002982/1.174, allocations: 177.6 kB / 1.114 GB, free: 222.4 MB / 0.7917 GB
Notification: Performance of postOpt collapseArrayExpressions (simulation): time 0.0001784/1.174, allocations: 111.9 kB / 1.114 GB, free: 222.3 MB / 0.7917 GB
Notification: Performance of sorting global known variables: time 0.002832/1.177, allocations: 2.465 MB / 1.116 GB, free: 219.8 MB / 0.7917 GB
Notification: Performance of sort global known variables: time 2.3e-07/1.177, allocations: 0 / 1.116 GB, free: 219.8 MB / 0.7917 GB
Notification: Performance of remove unused functions: time 0.003375/1.18, allocations: 1.622 MB / 1.118 GB, free: 218.2 MB / 0.7917 GB
Notification: Model statistics after passing the back-end for simulation:
 * Number of independent subsystems: 16
 * Number of states: 8 (relHumIn.phi,TDryBulIn.T,senMasFraIn.X,senMasFraOut.X,TDryBulOut.T,hexWetNTU.dryWetCalcs.fullyWet.TWatOutEst,relHumOut_eps.phi,hexWetNTU_TX.dryWetCalcs.fullyWet.TWatOutEst)
 * Number of discrete variables: 3 ($whenCondition1,w_a2.nextTimeEvent,w_a2.nextTimeEventScaled)
 * Number of discrete states: 2 (w_a2.nextTimeEvent,w_a2.nextTimeEventScaled)
 * Number of clocked states: 0 ()
 * Top-level inputs: 0
Notification: Strong component statistics for simulation (226):
 * Single equations (assignments): 213
 * Array equations: 0
 * Algorithm blocks: 0
 * Record equations: 3
 * When equations: 2
 * If-equations: 0
 * Equation systems (not torn): 0
 * Torn equation systems: 8
 * 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): 8 systems
   {(1,4), (1,2), (1,4), (1,2), (1,1), (1,1), (1,3), (1,3)}
Notification: Performance of Backend phase and start with SimCode phase: time 0.001595/1.182, allocations: 1.094 MB / 1.119 GB, free: 217.4 MB / 0.7917 GB
Notification: Performance of simCode: created initialization part: time 0.009763/1.192, allocations: 6.224 MB / 1.125 GB, free: 211.1 MB / 0.7917 GB
Notification: Performance of simCode: created event and clocks part: time 4.649e-06/1.192, allocations: 4 kB / 1.125 GB, free: 211.1 MB / 0.7917 GB
Notification: Performance of simCode: created simulation system equations: time 0.003684/1.195, allocations: 2.929 MB / 1.128 GB, free: 208.1 MB / 0.7917 GB
Notification: Performance of simCode: created of all other equations (e.g. parameter, nominal, assert, etc): time 0.003542/1.199, allocations: 1.479 MB / 1.129 GB, free: 206.7 MB / 0.7917 GB
Notification: Performance of simCode: created linear, non-linear and system jacobian parts: time 0.01334/1.212, allocations: 9.181 MB / 1.138 GB, free: 197.5 MB / 0.7917 GB
Notification: Performance of simCode: some other stuff during SimCode phase: time 0.00107/1.213, allocations: 1.787 MB / 1.14 GB, free: 195.6 MB / 0.7917 GB
Notification: Performance of simCode: all other stuff during SimCode phase: time 0.001486/1.215, allocations: 396.2 kB / 1.14 GB, free: 195.3 MB / 0.7917 GB
Notification: Performance of SimCode: time 1.212e-06/1.215, allocations: 0 / 1.14 GB, free: 195.3 MB / 0.7917 GB
Notification: Performance of Templates: time 0.3828/1.598, allocations: 258.1 MB / 1.392 GB, free: 278.2 MB / 0.7917 GB
"
[Timeout remaining time 658]
make -j1 -f IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.makefile [Timeout 660]
(rm -f IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.pipe ; mkfifo IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.pipe ; head -c 1048576 < IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.pipe >> ../files/IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.sim & ./IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU  -abortSlowSimulation -alarm=480 -lv LOG_STATS > IBPSA_latest_IBPSA.Fluid.HeatExchangers.Validation.WetCoilEffectivenessNTU.pipe 2>&1) [Timeout 480]
[Calling sys.exit(0), Time elapsed: 15.188376518897712]
Failed to read output from testmodel.py, exit status != 0:
1.603794440627098 1.611120391 0.9389769099999999
Calling exit ...
<OMPython.OMCSessionZMQ object at 0x747f1d5c5400>